builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-045 starttime: 1503882982.65 results: success (0) revision: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.650044) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.650447) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.650724) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.687528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.687809) ========= 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=4300f881736b422476174874000001bc-1503875775.720873-793745818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-27 18:16: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-08-27 18:16:22 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090539 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.794055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.794366) ========= 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=4300f881736b422476174874000001bc-1503875775.720873-793745818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020863 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:22.847024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-27 18:16:22.847334) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7aec8d3eaa72838f1d83767f46dcfcae8de510ff --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 7aec8d3eaa72838f1d83767f46dcfcae8de510ff --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=4300f881736b422476174874000001bc-1503875775.720873-793745818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-27 18:16:22,866 truncating revision to first 12 chars 2017-08-27 18:16:22,866 Setting DEBUG logging. 2017-08-27 18:16:22,866 attempt 1/10 2017-08-27 18:16:22,866 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7aec8d3eaa72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-27 18:16:23,802 unpacking tar archive at: mozilla-central-7aec8d3eaa72/testing/mozharness/ program finished with exit code 0 elapsedTime=1.253776 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-27 18:16:24.117271) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:24.117598) ========= script_repo_revision: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:24.117969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:24.118240) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:16:24.128804) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-27 18:16:24.129083) ========= /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=4300f881736b422476174874000001bc-1503875775.720873-793745818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:16:24 INFO - MultiFileLogger online at 20170827 18:16:24 in /builds/slave/test 18:16: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 18:16:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:16:24 INFO - {'append_to_log': False, 18:16:24 INFO - 'base_work_dir': '/builds/slave/test', 18:16:24 INFO - 'blob_upload_branch': 'Firefox', 18:16:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:16:24 INFO - 'branch': 'Firefox', 18:16:24 INFO - 'buildbot_json_path': 'buildprops.json', 18:16:24 INFO - 'code_coverage': False, 18:16:24 INFO - 'config_files': ('talos/linux_config.py',), 18:16:24 INFO - 'default_actions': ('clobber', 18:16:24 INFO - 'read-buildbot-config', 18:16:24 INFO - 'download-and-extract', 18:16:24 INFO - 'populate-webroot', 18:16:24 INFO - 'create-virtualenv', 18:16:24 INFO - 'install', 18:16:24 INFO - 'setup-mitmproxy', 18:16:24 INFO - 'run-tests'), 18:16:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:16:24 INFO - 'disable_ccov_upload': False, 18:16:24 INFO - 'download_minidump_stackwalk': True, 18:16:24 INFO - 'download_symbols': 'ondemand', 18:16:24 INFO - 'enable_stylo': False, 18:16:24 INFO - 'enable_webrender': False, 18:16:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:16:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:16:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:16:24 INFO - 'gecko_profile': False, 18:16:24 INFO - 'gecko_profile_interval': 0, 18:16:24 INFO - 'installer_path': 'installer.exe', 18:16:24 INFO - 'log_level': 'info', 18:16:24 INFO - 'log_name': 'talos', 18:16:24 INFO - 'log_to_console': True, 18:16:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:16:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:16:24 INFO - 'opt_config_files': (), 18:16:24 INFO - 'pip_index': False, 18:16:24 INFO - 'suite': 'dromaeojs-e10s', 18:16:24 INFO - 'system_bits': '32', 18:16:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:16:24 INFO - 'title': 'talos-linux64-ix-045', 18:16:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:16:24 INFO - 'use_talos_json': True, 18:16:24 INFO - 'verify': 'False', 18:16:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:16:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:16:24 INFO - 'work_dir': 'build'} 18:16:24 INFO - [mozharness: 2017-08-28 01:16:24.298002Z] Running clobber step. 18:16:24 INFO - Running pre-action listener: _resource_record_pre_action 18:16:24 INFO - Running main action method: clobber 18:16:24 INFO - rmtree: /builds/slave/test/build 18:16:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:16:25 INFO - Running post-action listener: _resource_record_post_action 18:16:25 INFO - [mozharness: 2017-08-28 01:16:25.322247Z] Finished clobber step (success) 18:16:25 INFO - [mozharness: 2017-08-28 01:16:25.322346Z] Running read-buildbot-config step. 18:16:25 INFO - Running pre-action listener: _resource_record_pre_action 18:16:25 INFO - Running main action method: read_buildbot_config 18:16:25 INFO - Using buildbot properties: 18:16:25 INFO - { 18:16:25 INFO - "project": "", 18:16:25 INFO - "product": "firefox", 18:16:25 INFO - "who": "philringnalda@gmail.com", 18:16:25 INFO - "installer_path": "public/build/target.tar.bz2", 18:16:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:16:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:16:25 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:16:25 INFO - "stage_platform": "linux64-stylosequential", 18:16:25 INFO - "basedir": "/builds/slave/test", 18:16:25 INFO - "buildnumber": 213, 18:16:25 INFO - "platform": "ubuntu64_hw_styloseq", 18:16:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:16:25 INFO - "slavebuilddir": "test", 18:16:25 INFO - "taskId": "YV1Veg2QSs2138EITN-_vQ", 18:16:25 INFO - "branch": "mozilla-central", 18:16:25 INFO - "script_repo_revision": "production", 18:16:25 INFO - "revision": "7aec8d3eaa72838f1d83767f46dcfcae8de510ff", 18:16:25 INFO - "slavename": "talos-linux64-ix-045", 18:16:25 INFO - "repo_path": "mozilla-central" 18:16:25 INFO - } 18:16:25 INFO - Finding installer, test and symbols from parent task. 18:16:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YV1Veg2QSs2138EITN-_vQ'}, attempt #1 18:16:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YV1Veg2QSs2138EITN-_vQ'}, attempt #1 18:16:26 INFO - Task dependencies: fJ4-8OdcRqSrwqD2wKF_lQ 18:16:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ'}, attempt #1 18:16:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:16:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json 18:16:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip 18:16:26 INFO - Running post-action listener: _resource_record_post_action 18:16:26 INFO - [mozharness: 2017-08-28 01:16:26.671193Z] Finished read-buildbot-config step (success) 18:16:26 INFO - [mozharness: 2017-08-28 01:16:26.671411Z] Running download-and-extract step. 18:16:26 INFO - Running pre-action listener: _resource_record_pre_action 18:16:26 INFO - Running main action method: download_and_extract 18:16:26 INFO - mkdir: /builds/slave/test/build/tests 18:16: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')]} 18:16:26 INFO - trying https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json 18:16:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:16:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:16:27 INFO - Downloaded 989 bytes. 18:16:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:16:27 INFO - Using the following test package requirements: 18:16:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:16:27 INFO - u'common': [u'target.common.tests.zip'], 18:16:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:16:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:16:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:16:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:16:27 INFO - u'mozbase': [u'target.common.tests.zip'], 18:16:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:16:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:16:27 INFO - u'web-platform': [u'target.common.tests.zip', 18:16:27 INFO - u'target.web-platform.tests.tar.gz'], 18:16:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:16:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:16:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip 18:16:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:16:27 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip into memory 18:16:28 INFO - Content-Length response header: 54977876 18:16:28 INFO - Bytes received: 54977876 18:16:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:16:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip 18:16:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:16:39 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.common.tests.zip into memory 18:16:40 INFO - Content-Length response header: 54977876 18:16:40 INFO - Bytes received: 54977876 18:16:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.talos.tests.zip 18:16:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:16:52 INFO - Fetch https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.talos.tests.zip into memory 18:16:52 INFO - Content-Length response header: 14496611 18:16:52 INFO - Bytes received: 14496611 18:16:53 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')]} 18:16:53 INFO - trying https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:16:53 INFO - Downloading https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:16:54 INFO - Downloaded 61264127 bytes. 18:16:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:16:54 INFO - mkdir: /builds/slave/test/properties 18:16:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:16:54 INFO - Writing to file /builds/slave/test/properties/build_url 18:16:54 INFO - Contents: 18:16:54 INFO - build_url:https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 18:16:54 INFO - Running post-action listener: _resource_record_post_action 18:16:54 INFO - Running post-action listener: find_tests_for_verification 18:16:54 INFO - Running post-action listener: set_extra_try_arguments 18:16:54 INFO - [mozharness: 2017-08-28 01:16:54.469260Z] Finished download-and-extract step (success) 18:16:54 INFO - [mozharness: 2017-08-28 01:16:54.469357Z] Running populate-webroot step. 18:16:54 INFO - Running pre-action listener: _resource_record_pre_action 18:16:54 INFO - Running main action method: populate_webroot 18:16:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:16:54 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:16:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:16:54 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:16:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:54 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'tests': ['damp', 'tps']}, 18:16:54 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['damp', 'tps']}, 18:16:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:16:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:16:54 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:16:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:16:54 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:16:54 INFO - 'other-e10s': {'tests': ['a11yr', 18:16:54 INFO - 'ts_paint', 18:16:54 INFO - 'tpaint', 18:16:54 INFO - 'sessionrestore', 18:16:54 INFO - 'sessionrestore_many_windows', 18:16:54 INFO - 'sessionrestore_no_auto_restore', 18:16:54 INFO - 'tabpaint']}, 18:16:54 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['a11yr', 18:16:54 INFO - 'ts_paint', 18:16:54 INFO - 'tpaint', 18:16:54 INFO - 'sessionrestore', 18:16:54 INFO - 'sessionrestore_many_windows', 18:16:54 INFO - 'sessionrestore_no_auto_restore', 18:16:54 INFO - 'tabpaint']}, 18:16:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:16:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:16:54 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['bloom_basic_singleton']}, 18:16:54 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['bloom_basic']}, 18:16:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:16:54 INFO - 'tsvgr_opacity', 18:16:54 INFO - 'tart', 18:16:54 INFO - 'tscrollx', 18:16:54 INFO - 'tsvg_static']}, 18:16:54 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['tsvgx', 18:16:54 INFO - 'tsvgr_opacity', 18:16:54 INFO - 'tart', 18:16:54 INFO - 'tscrollx', 18:16:54 INFO - 'tsvg_static']}, 18:16:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:16:54 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'talos_options': ['--stylo'], 18:16:54 INFO - 'tests': ['tp5o']}, 18:16:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:54 INFO - 'talos_options': ['--mitmproxy', 18:16:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:54 INFO - '--firstNonBlankPaint'], 18:16:54 INFO - 'tests': ['tp6_google', 18:16:54 INFO - 'tp6_youtube', 18:16:54 INFO - 'tp6_amazon', 18:16:54 INFO - 'tp6_facebook']}, 18:16:54 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:54 INFO - 'talos_options': ['--stylo', 18:16:54 INFO - '--mitmproxy', 18:16:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:54 INFO - '--firstNonBlankPaint'], 18:16:54 INFO - 'tests': ['tp6_google', 18:16:54 INFO - 'tp6_youtube', 18:16:54 INFO - 'tp6_amazon', 18:16:54 INFO - 'tp6_facebook']}, 18:16:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:54 INFO - 'talos_options': ['--stylo', 18:16:54 INFO - '--stylo-threads=1', 18:16:54 INFO - '--mitmproxy', 18:16:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:54 INFO - '--firstNonBlankPaint'], 18:16:54 INFO - 'tests': ['tp6_google', 18:16:54 INFO - 'tp6_youtube', 18:16:54 INFO - 'tp6_amazon', 18:16:54 INFO - 'tp6_facebook']}, 18:16:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'talos_options': ['--xperf_path', 18:16:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:54 INFO - 'tests': ['tp5n']}, 18:16:54 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:54 INFO - 'talos_options': ['--stylo', 18:16:54 INFO - '--xperf_path', 18:16:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:54 INFO - 'tests': ['tp5n']}}} 18:16:54 INFO - Running post-action listener: _resource_record_post_action 18:16:54 INFO - [mozharness: 2017-08-28 01:16:54.483897Z] Finished populate-webroot step (success) 18:16:54 INFO - [mozharness: 2017-08-28 01:16:54.483988Z] Running create-virtualenv step. 18:16:54 INFO - Running pre-action listener: _resource_record_pre_action 18:16:54 INFO - Running main action method: create_virtualenv 18:16:54 INFO - Creating virtualenv /builds/slave/test/build/venv 18:16:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:16:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:16:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:16:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:16:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:16:55 INFO - Installing distribute.............................................................................................................................................................................................done. 18:16:55 INFO - Installing pip...............done. 18:16:55 INFO - Return code: 0 18:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:16:55 INFO - Reading from file tmpfile_stdout 18:16:55 INFO - Output received: 18:16:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:16:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:16:55 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')]} 18:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:55 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')]} 18:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:55 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:16:55 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 18:16:55 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 18:16:55 INFO - Using env: {'DISPLAY': ':0', 18:16:55 INFO - 'HOME': '/home/cltbld', 18:16:55 INFO - 'LANG': 'en_US.UTF-8', 18:16:55 INFO - 'LANGUAGE': 'en_US:en', 18:16:55 INFO - 'LOGNAME': 'cltbld', 18:16:55 INFO - 'MAIL': '/var/mail/cltbld', 18:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:55 INFO - 'MOZ_NO_REMOTE': '1', 18:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:55 INFO - 'NO_EM_RESTART': '1', 18:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:55 INFO - 'PWD': '/builds/slave/test', 18:16:55 INFO - 'SHELL': '/bin/bash', 18:16:55 INFO - 'SHLVL': '1', 18:16:55 INFO - 'TERM': 'linux', 18:16:55 INFO - 'TMOUT': '86400', 18:16:55 INFO - 'USER': 'cltbld', 18:16:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:55 INFO - '_': '/tools/buildbot/bin/python'} 18:16:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:16:56 INFO - Downloading/unpacking pip>=1.5 18:16:56 INFO - Running setup.py egg_info for package pip 18:16:56 INFO - warning: no files found matching 'pip/cacert.pem' 18:16:56 INFO - warning: no files found matching '*.html' under directory 'docs' 18:16:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:16:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:16:56 INFO - Installing collected packages: pip 18:16:56 INFO - Found existing installation: pip 1.1 18:16:56 INFO - Uninstalling pip: 18:16:56 INFO - Successfully uninstalled pip 18:16:56 INFO - Running setup.py install for pip 18:16:56 INFO - warning: no files found matching 'pip/cacert.pem' 18:16:56 INFO - warning: no files found matching '*.html' under directory 'docs' 18:16:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:16:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:16:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:16:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:16:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:16:56 INFO - Successfully installed pip 18:16:56 INFO - Cleaning up... 18:16:56 INFO - Return code: 0 18:16:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:16:56 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')]} 18:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:56 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')]} 18:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:56 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:16:56 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 18:16:56 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 18:16:56 INFO - Using env: {'DISPLAY': ':0', 18:16:56 INFO - 'HOME': '/home/cltbld', 18:16:56 INFO - 'LANG': 'en_US.UTF-8', 18:16:56 INFO - 'LANGUAGE': 'en_US:en', 18:16:56 INFO - 'LOGNAME': 'cltbld', 18:16:56 INFO - 'MAIL': '/var/mail/cltbld', 18:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:56 INFO - 'MOZ_NO_REMOTE': '1', 18:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:56 INFO - 'NO_EM_RESTART': '1', 18:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:56 INFO - 'PWD': '/builds/slave/test', 18:16:56 INFO - 'SHELL': '/bin/bash', 18:16:56 INFO - 'SHLVL': '1', 18:16:56 INFO - 'TERM': 'linux', 18:16:56 INFO - 'TMOUT': '86400', 18:16:56 INFO - 'USER': 'cltbld', 18:16:56 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:56 INFO - '_': '/tools/buildbot/bin/python'} 18:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:56 INFO - Downloading/unpacking psutil>=3.1.1 18:16:56 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 18:16:56 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 18:16:56 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 18:16:56 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 18:16:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:16:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:58 INFO - Installing collected packages: psutil 18:16:58 INFO - Running setup.py install for psutil 18:16:58 INFO - building 'psutil._psutil_linux' extension 18:16:58 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 18:16:58 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 18:16:58 INFO - building 'psutil._psutil_posix' extension 18:16:58 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 18:16:58 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 18:16:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:58 INFO - Successfully installed psutil 18:16:58 INFO - Cleaning up... 18:16:58 INFO - Return code: 0 18:16:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:16:58 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')]} 18:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:58 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')]} 18:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:58 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:16:58 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 18:16:58 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 18:16:58 INFO - Using env: {'DISPLAY': ':0', 18:16:58 INFO - 'HOME': '/home/cltbld', 18:16:58 INFO - 'LANG': 'en_US.UTF-8', 18:16:58 INFO - 'LANGUAGE': 'en_US:en', 18:16:58 INFO - 'LOGNAME': 'cltbld', 18:16:58 INFO - 'MAIL': '/var/mail/cltbld', 18:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:58 INFO - 'MOZ_NO_REMOTE': '1', 18:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:58 INFO - 'NO_EM_RESTART': '1', 18:16:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:58 INFO - 'PWD': '/builds/slave/test', 18:16:58 INFO - 'SHELL': '/bin/bash', 18:16:58 INFO - 'SHLVL': '1', 18:16:58 INFO - 'TERM': 'linux', 18:16:58 INFO - 'TMOUT': '86400', 18:16:58 INFO - 'USER': 'cltbld', 18:16:58 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:58 INFO - '_': '/tools/buildbot/bin/python'} 18:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:16:58 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 18:16:58 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 18:16:58 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 18:16:58 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 18:16:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:17:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:17:00 INFO - Installing collected packages: mozsystemmonitor 18:17:00 INFO - Running setup.py install for mozsystemmonitor 18:17:00 INFO - Successfully installed mozsystemmonitor 18:17:00 INFO - Cleaning up... 18:17:00 INFO - Return code: 0 18:17:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:17:00 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')]} 18:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:00 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')]} 18:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:00 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:17:00 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 18:17:00 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 18:17:00 INFO - Using env: {'DISPLAY': ':0', 18:17:00 INFO - 'HOME': '/home/cltbld', 18:17:00 INFO - 'LANG': 'en_US.UTF-8', 18:17:00 INFO - 'LANGUAGE': 'en_US:en', 18:17:00 INFO - 'LOGNAME': 'cltbld', 18:17:00 INFO - 'MAIL': '/var/mail/cltbld', 18:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:00 INFO - 'MOZ_NO_REMOTE': '1', 18:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:00 INFO - 'NO_EM_RESTART': '1', 18:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:00 INFO - 'PWD': '/builds/slave/test', 18:17:00 INFO - 'SHELL': '/bin/bash', 18:17:00 INFO - 'SHLVL': '1', 18:17:00 INFO - 'TERM': 'linux', 18:17:00 INFO - 'TMOUT': '86400', 18:17:00 INFO - 'USER': 'cltbld', 18:17:00 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:00 INFO - '_': '/tools/buildbot/bin/python'} 18:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:00 INFO - Downloading/unpacking jsonschema==2.5.1 18:17:00 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 18:17:00 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 18:17:00 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 18:17:00 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 18:17:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:17:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:17:01 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 18:17:01 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 18:17:01 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 18:17:01 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 18:17:01 INFO - Downloading functools32-3.2.3-2.tar.gz 18:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:17:01 INFO - warning: no files found matching '*.txt' 18:17:01 INFO - no previously-included directories found matching 'build' 18:17:01 INFO - no previously-included directories found matching 'dist' 18:17:01 INFO - no previously-included directories found matching '.git*' 18:17:01 INFO - Installing collected packages: jsonschema, functools32 18:17:01 INFO - Running setup.py install for functools32 18:17:01 INFO - warning: no files found matching '*.txt' 18:17:01 INFO - no previously-included directories found matching 'build' 18:17:01 INFO - no previously-included directories found matching 'dist' 18:17:01 INFO - no previously-included directories found matching '.git*' 18:17:01 INFO - Successfully installed jsonschema functools32 18:17:01 INFO - Cleaning up... 18:17:01 INFO - Return code: 0 18:17:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:17:01 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')]} 18:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:01 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')]} 18:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:01 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:17:01 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 18:17:01 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 18:17:01 INFO - Using env: {'DISPLAY': ':0', 18:17:01 INFO - 'HOME': '/home/cltbld', 18:17:01 INFO - 'LANG': 'en_US.UTF-8', 18:17:01 INFO - 'LANGUAGE': 'en_US:en', 18:17:01 INFO - 'LOGNAME': 'cltbld', 18:17:01 INFO - 'MAIL': '/var/mail/cltbld', 18:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:01 INFO - 'MOZ_NO_REMOTE': '1', 18:17:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:01 INFO - 'NO_EM_RESTART': '1', 18:17:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:01 INFO - 'PWD': '/builds/slave/test', 18:17:01 INFO - 'SHELL': '/bin/bash', 18:17:01 INFO - 'SHLVL': '1', 18:17:01 INFO - 'TERM': 'linux', 18:17:01 INFO - 'TMOUT': '86400', 18:17:01 INFO - 'USER': 'cltbld', 18:17:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:01 INFO - '_': '/tools/buildbot/bin/python'} 18:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:17:02 INFO - Cleaning up... 18:17:02 INFO - Return code: 0 18:17:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:17:02 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')]} 18:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:02 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')]} 18:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:02 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:17:02 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 18:17:02 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 18:17:02 INFO - Using env: {'DISPLAY': ':0', 18:17:02 INFO - 'HOME': '/home/cltbld', 18:17:02 INFO - 'LANG': 'en_US.UTF-8', 18:17:02 INFO - 'LANGUAGE': 'en_US:en', 18:17:02 INFO - 'LOGNAME': 'cltbld', 18:17:02 INFO - 'MAIL': '/var/mail/cltbld', 18:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:02 INFO - 'MOZ_NO_REMOTE': '1', 18:17:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:02 INFO - 'NO_EM_RESTART': '1', 18:17:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:02 INFO - 'PWD': '/builds/slave/test', 18:17:02 INFO - 'SHELL': '/bin/bash', 18:17:02 INFO - 'SHLVL': '1', 18:17:02 INFO - 'TERM': 'linux', 18:17:02 INFO - 'TMOUT': '86400', 18:17:02 INFO - 'USER': 'cltbld', 18:17:02 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:02 INFO - '_': '/tools/buildbot/bin/python'} 18:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:02 INFO - Downloading/unpacking blobuploader==1.2.4 18:17:02 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 18:17:02 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 18:17:02 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 18:17:02 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 18:17:03 INFO - Downloading blobuploader-1.2.4.tar.gz 18:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:17:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:17:03 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 18:17:03 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 18:17:03 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 18:17:03 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 18:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:17:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:17:03 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 18:17:03 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 18:17:03 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 18:17:03 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 18:17:03 INFO - Downloading docopt-0.6.1.tar.gz 18:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:17:03 INFO - Installing collected packages: blobuploader, requests, docopt 18:17:03 INFO - Running setup.py install for blobuploader 18:17:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:17:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:17:04 INFO - Running setup.py install for requests 18:17:04 INFO - Running setup.py install for docopt 18:17:04 INFO - Successfully installed blobuploader requests docopt 18:17:04 INFO - Cleaning up... 18:17:04 INFO - Return code: 0 18:17:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17: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')]} 18:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17: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')]} 18:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:04 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:17:04 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 18:17:04 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 18:17:04 INFO - Using env: {'DISPLAY': ':0', 18:17:04 INFO - 'HOME': '/home/cltbld', 18:17:04 INFO - 'LANG': 'en_US.UTF-8', 18:17:04 INFO - 'LANGUAGE': 'en_US:en', 18:17:04 INFO - 'LOGNAME': 'cltbld', 18:17:04 INFO - 'MAIL': '/var/mail/cltbld', 18:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:04 INFO - 'MOZ_NO_REMOTE': '1', 18:17:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:04 INFO - 'NO_EM_RESTART': '1', 18:17:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:04 INFO - 'PWD': '/builds/slave/test', 18:17:04 INFO - 'SHELL': '/bin/bash', 18:17:04 INFO - 'SHLVL': '1', 18:17:04 INFO - 'TERM': 'linux', 18:17:04 INFO - 'TMOUT': '86400', 18:17:04 INFO - 'USER': 'cltbld', 18:17:04 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:04 INFO - '_': '/tools/buildbot/bin/python'} 18:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:04 INFO - Running setup.py (path:/tmp/pip-cwHsBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:04 INFO - Running setup.py (path:/tmp/pip-hYJLTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:04 INFO - Running setup.py (path:/tmp/pip-oUF1Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:04 INFO - Running setup.py (path:/tmp/pip-Lp0nMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:05 INFO - Running setup.py (path:/tmp/pip-iBge6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:05 INFO - Running setup.py (path:/tmp/pip-N04q6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:05 INFO - Running setup.py (path:/tmp/pip-kwYNuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:05 INFO - Running setup.py (path:/tmp/pip-wjMz80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:05 INFO - Running setup.py (path:/tmp/pip-zMm1WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:05 INFO - Running setup.py (path:/tmp/pip-61auv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:05 INFO - Running setup.py (path:/tmp/pip-7SYzQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:05 INFO - Running setup.py (path:/tmp/pip-kzrKY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:05 INFO - Running setup.py (path:/tmp/pip-qRm5js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:05 INFO - Running setup.py (path:/tmp/pip-aRGbh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:05 INFO - Running setup.py (path:/tmp/pip-XxE9Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:05 INFO - Running setup.py (path:/tmp/pip-ZIl9XF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:05 INFO - Running setup.py (path:/tmp/pip-2FHtdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:17:05 INFO - Running setup.py install for manifestparser 18:17:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Running setup.py install for mozcrash 18:17:06 INFO - Running setup.py install for mozdebug 18:17:06 INFO - Running setup.py install for mozdevice 18:17:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Running setup.py install for mozfile 18:17:06 INFO - Running setup.py install for mozhttpd 18:17:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Running setup.py install for mozinfo 18:17:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Running setup.py install for mozInstall 18:17:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Running setup.py install for mozleak 18:17:06 INFO - Running setup.py install for mozlog 18:17:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:17:06 INFO - Running setup.py install for moznetwork 18:17:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:17:07 INFO - Running setup.py install for mozprocess 18:17:07 INFO - Running setup.py install for mozprofile 18:17:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:17:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:17:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:17:07 INFO - Running setup.py install for mozrunner 18:17:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:17:07 INFO - Running setup.py install for mozscreenshot 18:17:07 INFO - Running setup.py install for moztest 18:17:07 INFO - Running setup.py install for mozversion 18:17:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:17:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:17:07 INFO - Cleaning up... 18:17:07 INFO - Return code: 0 18:17:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:07 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:17:07 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 18:17:07 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 18:17:07 INFO - Using env: {'DISPLAY': ':0', 18:17:07 INFO - 'HOME': '/home/cltbld', 18:17:07 INFO - 'LANG': 'en_US.UTF-8', 18:17:07 INFO - 'LANGUAGE': 'en_US:en', 18:17:07 INFO - 'LOGNAME': 'cltbld', 18:17:07 INFO - 'MAIL': '/var/mail/cltbld', 18:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:07 INFO - 'MOZ_NO_REMOTE': '1', 18:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:07 INFO - 'NO_EM_RESTART': '1', 18:17:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:07 INFO - 'PWD': '/builds/slave/test', 18:17:07 INFO - 'SHELL': '/bin/bash', 18:17:07 INFO - 'SHLVL': '1', 18:17:07 INFO - 'TERM': 'linux', 18:17:07 INFO - 'TMOUT': '86400', 18:17:07 INFO - 'USER': 'cltbld', 18:17:07 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:07 INFO - '_': '/tools/buildbot/bin/python'} 18:17:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:07 INFO - Running setup.py (path:/tmp/pip-q2vngt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:07 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)) 18:17:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:07 INFO - Running setup.py (path:/tmp/pip-QZUdqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:08 INFO - Running setup.py (path:/tmp/pip-6LEx5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:08 INFO - Running setup.py (path:/tmp/pip-jeH63h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:08 INFO - Running setup.py (path:/tmp/pip-KQKj41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:08 INFO - Running setup.py (path:/tmp/pip-q8zNTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:08 INFO - Running setup.py (path:/tmp/pip-Bn0bcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:08 INFO - Running setup.py (path:/tmp/pip-F0MPfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:08 INFO - Running setup.py (path:/tmp/pip-Fp_y13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:08 INFO - Running setup.py (path:/tmp/pip-ws60yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:08 INFO - Running setup.py (path:/tmp/pip-buzN7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:08 INFO - Running setup.py (path:/tmp/pip-My8zpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:08 INFO - Running setup.py (path:/tmp/pip-XmW7ZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:08 INFO - Running setup.py (path:/tmp/pip-xRLAIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:08 INFO - Running setup.py (path:/tmp/pip-RuHPuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:08 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)) 18:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:08 INFO - Running setup.py (path:/tmp/pip-WrIqa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:09 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)) 18:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:09 INFO - Running setup.py (path:/tmp/pip-nluP6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:09 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)) 18:17:09 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)) 18:17:09 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)) 18:17:09 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)) 18:17:09 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)) 18:17:09 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)) 18:17:09 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)) 18:17:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:17: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 18:17: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 18:17: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 18:17: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 18:17:10 INFO - Downloading blessings-1.6.tar.gz 18:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:17:10 INFO - Installing collected packages: blessings 18:17:10 INFO - Running setup.py install for blessings 18:17:10 INFO - Successfully installed blessings 18:17:10 INFO - Cleaning up... 18:17:10 INFO - Return code: 0 18:17:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:10 INFO - Reading from file tmpfile_stdout 18:17:10 INFO - Current package versions: 18:17:10 INFO - argparse == 1.2.1 18:17:10 INFO - blessings == 1.6 18:17:10 INFO - blobuploader == 1.2.4 18:17:10 INFO - docopt == 0.6.1 18:17:10 INFO - functools32 == 3.2.3-2 18:17:10 INFO - jsonschema == 2.5.1 18:17:10 INFO - manifestparser == 1.1 18:17:10 INFO - mozInstall == 1.13 18:17:10 INFO - mozcrash == 1.0 18:17:10 INFO - mozdebug == 0.1 18:17:10 INFO - mozdevice == 0.51 18:17:10 INFO - mozfile == 1.2 18:17:10 INFO - mozhttpd == 0.7 18:17:10 INFO - mozinfo == 0.10 18:17:10 INFO - mozleak == 0.1 18:17:10 INFO - mozlog == 3.5 18:17:10 INFO - moznetwork == 0.27 18:17:10 INFO - mozprocess == 0.25 18:17:10 INFO - mozprofile == 0.28 18:17:10 INFO - mozrunner == 6.13 18:17:10 INFO - mozscreenshot == 0.1 18:17:10 INFO - mozsystemmonitor == 0.3 18:17:10 INFO - moztest == 0.8 18:17:10 INFO - mozversion == 1.4 18:17:10 INFO - psutil == 3.1.1 18:17:10 INFO - requests == 1.2.3 18:17:10 INFO - wsgiref == 0.1.2 18:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17: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')]} 18:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17: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')]} 18:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:10 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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:17:10 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 18:17:10 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 18:17:10 INFO - Using env: {'DISPLAY': ':0', 18:17:10 INFO - 'HOME': '/home/cltbld', 18:17:10 INFO - 'LANG': 'en_US.UTF-8', 18:17:10 INFO - 'LANGUAGE': 'en_US:en', 18:17:10 INFO - 'LOGNAME': 'cltbld', 18:17:10 INFO - 'MAIL': '/var/mail/cltbld', 18:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:10 INFO - 'MOZ_NO_REMOTE': '1', 18:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:10 INFO - 'NO_EM_RESTART': '1', 18:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:10 INFO - 'PWD': '/builds/slave/test', 18:17:10 INFO - 'SHELL': '/bin/bash', 18:17:10 INFO - 'SHLVL': '1', 18:17:10 INFO - 'TERM': 'linux', 18:17:10 INFO - 'TMOUT': '86400', 18:17:10 INFO - 'USER': 'cltbld', 18:17:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:10 INFO - '_': '/tools/buildbot/bin/python'} 18:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:10 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)) 18:17:10 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)) 18:17:10 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)) 18:17:10 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)) 18:17:10 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)) 18:17:10 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)) 18:17:10 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)) 18:17:10 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)) 18:17:10 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)) 18:17:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:17: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 18:17: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 18:17: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 18:17: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 18:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:17:12 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)) 18:17:12 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)) 18:17:12 INFO - Installing collected packages: simplejson 18:17:12 INFO - Running setup.py install for simplejson 18:17:12 INFO - building 'simplejson._speedups' extension 18:17:12 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 18:17:12 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 18:17:13 INFO - Successfully installed simplejson 18:17:13 INFO - Cleaning up... 18:17:13 INFO - Return code: 0 18:17:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:17:13 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')]} 18:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:13 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')]} 18:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x145ef90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, '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': '4300f881736b422476174874000001bc-1503875775.720873-793745818', '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 18:17:13 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 18:17:13 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 18:17:13 INFO - Using env: {'DISPLAY': ':0', 18:17:13 INFO - 'HOME': '/home/cltbld', 18:17:13 INFO - 'LANG': 'en_US.UTF-8', 18:17:13 INFO - 'LANGUAGE': 'en_US:en', 18:17:13 INFO - 'LOGNAME': 'cltbld', 18:17:13 INFO - 'MAIL': '/var/mail/cltbld', 18:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:13 INFO - 'MOZ_NO_REMOTE': '1', 18:17:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:13 INFO - 'NO_EM_RESTART': '1', 18:17:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:13 INFO - 'PWD': '/builds/slave/test', 18:17:13 INFO - 'SHELL': '/bin/bash', 18:17:13 INFO - 'SHLVL': '1', 18:17:13 INFO - 'TERM': 'linux', 18:17:13 INFO - 'TMOUT': '86400', 18:17:13 INFO - 'USER': 'cltbld', 18:17:13 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:13 INFO - '_': '/tools/buildbot/bin/python'} 18:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:17:13 INFO - Cleaning up... 18:17:13 INFO - Return code: 0 18:17:13 INFO - Running post-action listener: _resource_record_post_action 18:17:13 INFO - Running post-action listener: _start_resource_monitoring 18:17:13 INFO - Starting resource monitoring. 18:17:13 INFO - [mozharness: 2017-08-28 01:17:13.364670Z] Finished create-virtualenv step (success) 18:17:13 INFO - [mozharness: 2017-08-28 01:17:13.365145Z] Running install step. 18:17:13 INFO - Running pre-action listener: _resource_record_pre_action 18:17:13 INFO - Running main action method: install 18:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:17:13 INFO - Reading from file tmpfile_stdout 18:17:13 INFO - Detecting whether we're running mozinstall >=1.0... 18:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:17:13 INFO - Reading from file tmpfile_stdout 18:17:13 INFO - Output received: 18:17:13 INFO - Usage: mozinstall [options] installer 18:17:13 INFO - Options: 18:17:13 INFO - -h, --help show this help message and exit 18:17:13 INFO - -d DEST, --destination=DEST 18:17:13 INFO - Directory to install application into. [default: 18:17:13 INFO - "/builds/slave/test"] 18:17:13 INFO - --app=APP Application being installed. [default: firefox] 18:17:13 INFO - mkdir: /builds/slave/test/build/application 18:17:13 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'] 18:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:17:31 INFO - Reading from file tmpfile_stdout 18:17:31 INFO - Output received: 18:17:31 INFO - /builds/slave/test/build/application/firefox/firefox 18:17:31 INFO - Running post-action listener: _resource_record_post_action 18:17:31 INFO - [mozharness: 2017-08-28 01:17:31.423464Z] Finished install step (success) 18:17:31 INFO - [mozharness: 2017-08-28 01:17:31.423713Z] Running setup-mitmproxy step. 18:17:31 INFO - Running pre-action listener: _resource_record_pre_action 18:17:31 INFO - Running main action method: setup_mitmproxy 18:17:31 INFO - Skipping: mitmproxy is not required 18:17:31 INFO - Running post-action listener: _resource_record_post_action 18:17:31 INFO - [mozharness: 2017-08-28 01:17:31.424822Z] Finished setup-mitmproxy step (success) 18:17:31 INFO - [mozharness: 2017-08-28 01:17:31.425038Z] Running run-tests step. 18:17:31 INFO - Running pre-action listener: _resource_record_pre_action 18:17:31 INFO - Running pre-action listener: _set_gcov_prefix 18:17:31 INFO - Running main action method: run_tests 18:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:17:31 INFO - Python 2.7.3 18:17:31 INFO - Return code: 0 18:17:31 INFO - grabbing minidump binary from tooltool 18:17:31 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')]} 18:17:31 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 0x145c130>, '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 0x145d030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x145d4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:17:31 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 18:17:31 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 18:17:31 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 18:17:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:17:31 INFO - Return code: 0 18:17:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:17:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:17:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:17:31 INFO - ENV: RUST_BACKTRACE is now full 18:17:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:17:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:17:31 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/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 18:17:31 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/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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 18:17:31 INFO - Using env: {'DISPLAY': ':0', 18:17:31 INFO - 'HOME': '/home/cltbld', 18:17:31 INFO - 'LANG': 'en_US.UTF-8', 18:17:31 INFO - 'LANGUAGE': 'en_US:en', 18:17:31 INFO - 'LOGNAME': 'cltbld', 18:17:31 INFO - 'MAIL': '/var/mail/cltbld', 18:17:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:17:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:31 INFO - 'MOZ_NO_REMOTE': '1', 18:17:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:17:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:31 INFO - 'NO_EM_RESTART': '1', 18:17:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:31 INFO - 'PWD': '/builds/slave/test', 18:17:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:17:31 INFO - 'RUST_BACKTRACE': 'full', 18:17:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:17:31 INFO - 'SHELL': '/bin/bash', 18:17:31 INFO - 'SHLVL': '1', 18:17:31 INFO - 'STYLO_FORCE_ENABLED': '1', 18:17:31 INFO - 'TERM': 'linux', 18:17:31 INFO - 'TMOUT': '86400', 18:17:31 INFO - 'USER': 'cltbld', 18:17:31 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:31 INFO - '_': '/tools/buildbot/bin/python'} 18:17:31 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/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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 18:17:31 INFO - mozversion application_buildid: 20170828003112 18:17:31 INFO - mozversion application_changeset: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff 18:17:31 INFO - mozversion application_display_name: Nightly 18:17:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:17:31 INFO - mozversion application_name: Firefox 18:17:31 INFO - mozversion application_remotingname: firefox 18:17:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:17:31 INFO - mozversion application_vendor: Mozilla 18:17:31 INFO - mozversion application_version: 57.0a1 18:17:31 INFO - mozversion platform_buildid: 20170828003112 18:17:31 INFO - mozversion platform_changeset: 7aec8d3eaa72838f1d83767f46dcfcae8de510ff 18:17:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:17:31 INFO - mozversion platform_version: 57.0a1 18:17:31 INFO - using testdate: 1503883051 18:17:31 INFO - actual date: 1503883051 18:17:31 INFO - starting webserver on 'localhost:35685' 18:17:31 INFO - SUITE-START | Running 2 tests 18:17:31 INFO - TEST-START | dromaeo_css 18:17:31 INFO - Initialising browser for dromaeo_css test... 18:17:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35685/getInfo.html -profile /tmp/tmp9uq6co/profile 18:17:31 INFO - TEST-INFO | started process 13326 (/builds/slave/test/build/application/firefox/firefox http://localhost:35685/getInfo.html) 18:17:37 INFO - TEST-INFO | 13326: exit 0 18:17:38 INFO - Browser initialized. 18:17:38 INFO - Running cycle 1/1 for dromaeo_css test... 18:17:38 INFO - Using env: {'DISPLAY': ':0', 18:17:38 INFO - 'HOME': '/home/cltbld', 18:17:38 INFO - 'JSGC_DISABLE_POISONING': '1', 18:17:38 INFO - 'LANG': 'en_US.UTF-8', 18:17:38 INFO - 'LANGUAGE': 'en_US:en', 18:17:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:17:38 INFO - 'LOGNAME': 'cltbld', 18:17:38 INFO - 'MAIL': '/var/mail/cltbld', 18:17:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:17:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:17:38 INFO - 'MOZ_CRASHREPORTER': '1', 18:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:17:38 INFO - 'MOZ_NO_REMOTE': '1', 18:17:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:17:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:38 INFO - 'NO_EM_RESTART': '1', 18:17:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:38 INFO - 'PWD': '/builds/slave/test', 18:17:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:17:38 INFO - 'RUST_BACKTRACE': 'full', 18:17:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:17:38 INFO - 'SHELL': '/bin/bash', 18:17:38 INFO - 'SHLVL': '1', 18:17:38 INFO - 'STYLO_FORCE_ENABLED': '1', 18:17:38 INFO - 'TERM': 'linux', 18:17:38 INFO - 'TMOUT': '86400', 18:17:38 INFO - 'USER': 'cltbld', 18:17:38 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:38 INFO - '_': '/tools/buildbot/bin/python'} 18:17:38 INFO - TEST-INFO | started process 13560 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9uq6co/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:39 INFO - PID 13560 | 18:17:39 INFO - PID 13560 | (/builds/slave/test/build/application/firefox/firefox:13610): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:17:39 INFO - PID 13560 | 18:17:39 INFO - PID 13560 | 18:17:39 INFO - PID 13560 | (/builds/slave/test/build/application/firefox/firefox:13671): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:17:39 INFO - PID 13560 | 18:17:39 INFO - PID 13560 | __metrics Screen width/height:1600/1200 18:17:39 INFO - PID 13560 | colorDepth:24 18:17:39 INFO - PID 13560 | Browser inner width/height: 1024/768 18:17:39 INFO - PID 13560 | __metrics 18:17:49 INFO - PID 13560 | RSS: Main: 175271936 18:17:49 INFO - PID 13560 | 18:20:47 INFO - PID 13560 | Cycle 1(1): loaded http://localhost:35685/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35685/tests/dromaeo/cssquery-ext.html) 18:20:49 INFO - PID 13560 | RSS: Main: 178909184 18:20:49 INFO - PID 13560 | 18:23:51 INFO - PID 13560 | Cycle 1(1): loaded http://localhost:35685/tests/dromaeo/cssquery-ext.html (next: http://localhost:35685/tests/dromaeo/cssquery-jquery.html) 18:23:51 INFO - PID 13560 | RSS: Main: 175140864 18:23:51 INFO - PID 13560 | 18:26:53 INFO - PID 13560 | Cycle 1(1): loaded http://localhost:35685/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35685/tests/dromaeo/cssquery-mootools.html) 18:26:54 INFO - PID 13560 | RSS: Main: 177352704 18:26:54 INFO - PID 13560 | 18:29:55 INFO - PID 13560 | Cycle 1(1): loaded http://localhost:35685/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35685/tests/dromaeo/cssquery-prototype.html) 18:29:56 INFO - PID 13560 | RSS: Main: 174813184 18:29:56 INFO - PID 13560 | 18:33:00 INFO - PID 13560 | Cycle 1(1): loaded http://localhost:35685/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35685/tests/dromaeo/cssquery-yui.html) 18:33:00 INFO - PID 13560 | RSS: Main: 177311744 18:33:00 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | Cycle 1(1): loaded http://localhost:35685/tests/dromaeo/cssquery-yui.html (next: http://localhost:35685/tests/dromaeo/cssquery-dojo.html) 18:36:02 INFO - PID 13560 | RSS: Main: 179580928 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | __start_tp_report 18:36:02 INFO - PID 13560 | _x_x_mozilla_page_load 18:36:02 INFO - PID 13560 | _x_x_mozilla_page_load_details 18:36:02 INFO - PID 13560 | |i|pagename|runs| 18:36:02 INFO - PID 13560 | |0;dojo.html;3261.59230096238;3273.1653428375766;3284.5894263217097;3289.6545862684434;3298.2414044769703;6316.242050953881;6400.295967443578;6412.42288194063;6437.420632143107;6478.643674597895;6105.389461053893;6160.907586386208;6174.475169610577;6210.689465526728;6272.027835685464;5829.533637309016;5860.853478663025;5919.526437884959;5940;6005.6997150142315;5817.709114544277;5818.679972601505;5822.9417705822825;5898.557608179382;5909.084091965752;5591.74837132328;5706.229658996024;5780.1907732917425;5847.152162936393;5871.647701137946;5779.624324418899;5786.71066446676;5853.5902486825935;5867.823965281048;5868.530517558585;5640.464155905182;5690.573207009449;5708.315002199721;5739.483446489836;5841.591088623798;6231.813045608639;6273.435390814849;6284.27730810958;6372.522060845409;6407.583507072072;6758.898616520756;6798.388145066919;6838.794836154923;6841.281665425108;6873.175218973705;6803.625800580966;6806.047153398528;6814.2845001274645;6907.861842763167;6922.550034247808;6340.207474065742;6396.136521569575;6400.4559612432995;6406.93593064068;6426.260979987268;6886.724531018703;7042.225355210922;7064.258252883476;7088.220295767462;7102.467314951399;6626;6675.299093595199;6680.23177334603;6757.425618822455;6759.324067593201;6674.966625166843;6734.225564060098;6764.086848275519;6765.154355705537;6769.32306769329;6707.3628005339415;6719.966400167968;6725.05849181115;6725.226598941086;6728.730850766013;6615.669216539154;6753.797386078327;6757.898631520433;6824.010518474794;6825.9317406826285;5346.732663366816;5385.407605163429;5417.8103766367985;5419.458054194549;5420.349558053059;4650.837220697259;4708.623310135181;4731.077439899186;4742.549457801503;4743.596794272526;5821.214136091573;5921.348651648315;5954.19618351517;6033.064874944303;6042.395760423922;6777.457803375718;6783.830404239933;6784.59292442455;6784.728610855511;6793.048973143764;5977.731002104916;5980.55145864062;5994.730237139339;6021.458068773743;6026.54800889935;5838.9124163137585;5919.822405327847;5920.111983202468;5929.614575052694;5932.466078052909;6456.128422662974;6542.8364290893105;6550.377714117151;6561.376669216417;6670.9332906670315;6774.796756097325;6780.38976492118;6785.626790526569;6790.049393084873;6795.72817087311;6474.320196379501;6497.31778163305;6536.084948107174;6550.148480697479;6556.934430655823;6242.531810114168;6655.23464801554;6757.560758550679;6765.695543700447;6765.830854228487;6858.074159988537;6929.3070692930305;6943.166819981635;6944.201416836926;6950.513464057467;6570.737170513126;6576.736930522917;6586.110875032001;6676.499262555425;6680.931051031812;6486.448651864455;6565.310642382481;6574.76988305407;6634.4692424604955;6663.566868153554;6448.032795080775;6469.385408386196;6494.415502604599;6496.480281577368;6501.057346684799;6540.869182616416;6588.901166482602;6594.241662208908;6599.043138744951;6602.306757790363;6323.841903952438;6325.177726895474;6364.331745166691;6411.358864113738;6463.61218326883 18:36:02 INFO - PID 13560 | |1;ext.html;795.2683531151343;805.5046146619824;853.3130829682106;859.8409294280557;860.9913900860994;38127.23745525091;38850.222995540105;38862.61137388629;38894.22211555774;38895;35570;35767.46348804764;35968.28063438736;36130.277394452096;36151.81924090376;6902.447804175654;6999.685014174349;7006.684699188549;7011.474139439562;7028.894566581505;6925.515213935027;6984.685689144001;6996.230414654384;7015.89476157858;7020;7518.097828260617;7694.499857509272;7695.769126926201;7696.384289256874;7707.30634242918;7584.4690871639;7653.464257501977;7653.693852245903;7656.73201437947;7658.463907526475;7573.394128469709;7643.847123057563;7644.929709840627;7646.694132234759;7647.235276472364;34664.480032799525;34933.12667183315;34952.300953980775;34968.30063398718;34986;3444.431668774649;3463.3592785334795;3464.3937310970477;3466.4107101792756;3480.791152530831;29709.554356684668;29907.10278691621;29916.40167196644;29975.40049199004;30048.248793780112;4903.583195428393;5104.387473503166;5111.616628752821;5124.615653825941;5186.948130518685;175538;176071;176589;176641;177414;44058.77970610191;44796.3280550792;44807.55192448099;45025.77487112609;45089.77455112769;46354.536454635694;46527;46583.534164658595;46590;46652.300215496114;15550;15620.453284135228;15639;15639.296231669603;15770.211489425712;12802.42389092493;12849.807252891214;12860.807087893689;12866.356682165855;12887.420066097047;12998.805017924738;13036.217826930415;13044;13121.934390328179;13169;5391.191321301836;5475.06923822951;5475.178723191553;5481.260029895992;5495.230667706524;979.2802290316612;983.8622592837008;1001.324106228293;1001.5442973447064;1003.6537394598852;20397.286094986604;20461.89769051175;20583.794162058486;20684.276050338165;20986.2654807081;1072.6299426697776;1078.7087486378634;1102.3551222534907;1109.040679811973;1109.1514991031868;10571.995660412249;10572.312799667998;10674.62638807638;10903.29128606638;11075.058620017227;220446;221134;221459;221544;221811;41784;42718;46967;47499.525004750205;49568.50431495711;1376.2636989210723;1411.181514721459;1415.9150450972975;1424.551266351096;1437.8777803886787;25766.742332577185;26016.34959126037;26087.217383478524;26150;26583.601245981714;47898.76050619724;48176;48439;48440.27339589979;48450.27324590205;201977;204036;204376;204430;205346;33841.66158338385;34016;34122;34133.31733365269;34223.31553368968;40828.79585602053;40980.3852942212;44085.33871991987;45108.77445612882;45239.32141017953;32156.56868626331;32258;32292.35415291728;32338;32567.837160814044;31926.36147276995;32136.678633214306;32443.675563244065;32459.675403245667;33007.50488742719;36706.26587468289;36821.079473013386;36836.447453287685;37138.44292335563;37850.24299513939;32026.19934501656;32362.352752944338;32410.189745256557;32437.351252974335;32541;23830.16594419187;23905.880470597534;24131.034758609458;24383;24858.875705621354 18:36:02 INFO - PID 13560 | |2;jquery.html;553.4050895287567;554.4206304411889;570.7060863655215;574.1273264637749;574.6322353693636;20985.160593576256;21605.567888642236;21618.89190554045;21639.675404868918;22546.774532254673;24196;24883.253502394913;24950.500989980188;25014.74985250147;25017;6423.197100362455;6532.11816404784;6533.771318003871;6537.738490460376;6558.672066396685;6105.725242364082;6331.936680633181;6365.236171659408;6468.159139311882;6513.804585862432;6321.494280457576;6329.715162817662;6334.144890439778;6342.746290148389;6347;6331.936680633204;6347.619142851444;6348.619082855043;6349.0476428536085;6351.809445716636;5877.588568800185;5881.85295367615;5893;5898.203742494794;5901.498372638331;28245.435091298263;28304;28774.568381474295;28818.135455936357;28865;2041.8468614853796;2092.843036772233;2094.183268525276;2094.3821572635957;2095.6961240620026;18676.159572819255;19243;19297.614047719107;19299.90350048255;19311.420657380302;2383.6305372667266;2410.301012706313;2424.818138639609;2429.5383877063477;2492.6011838105815;130261;134574;135062;135077.32461337728;135495.32252338773;14216.21810800416;14748.115113093249;14840.332185051699;14877.330520126403;14969.326380312912;22192;22196.223132190295;22242.555148896932;22244.888775556017;22341;9727.902720972841;9902.55438505269;9922.553485093189;9944.204463642891;10010.199184065257;7990.12108668046;8999.685011024583;9026.503542305236;9107.726768196964;9310.581023853943;7961.761147165595;8856.778580535538;8858.11418858122;9022.097790220925;9032.36773425854;2596.896124155013;2605.4398304364654;2628.250948479674;2663.480621278871;2734.8495832728995;924.611663101499;943.4527973775193;958.3148049144895;961.077365728894;965.0205041882494;19074.33239836599;19200.71198932017;19557.41327760141;19640.312589059315;20056.498587535138;993.1111655068673;999.5502024089044;1007.7631756537207;1014.2342531388902;1019.454591793387;10499.842502362471;10579.465977433383;10612.575496980035;10676.62631807883;11007.669769906915;441360;441712;447546;450851;461473;105467.47266263772;109591;111373.44313278382;111515;111533.44233278943;1103.569607852938;1130.9547618095185;1148.4831825678477;1149.4712432281078;1152.0438036429912;16709.74935375946;16912.154392280583;17226.569335766708;17355.3925612608;17567.209475573887;54787;55021.44978550323;55030.72484637551;55871.441285586625;56724.432755671914;66732;66944;66990;68829.31170688229;70498;11871.703207419883;12317.014638829052;13946.302684865514;14320.212388318743;20985.370438886857;23587.528249435258;23637.52724945457;24024;24378.51242975166;24384.390390240387;17747.378841740476;18164.545886352946;18839.811601883805;18943.90528047351;18962;16991.235394407035;17419.041952692725;17458.47624571265;17618.207180677156;17650.205740741952;19540.413787586396;19659.508512287306;19766.047536831862;19881.105350259564;20034.59930801405;20628.381148565568;20682.896585516977;21682.566348673252;21684.34946951594;21713.891430542746;18406.723899141256;18866.05669716536;19002.809971900104;19027.619447610694;19029.524261893563 18:36:02 INFO - PID 13560 | |3;mootools.html;1299.8310219671441;1357.9185248885074;1361.1628848258317;1361.5166615851372;1367.1387026173513;14353.928230358846;14948.925255373708;15017;15030.398784048624;15194.620134496643;14980.325885335158;15289.694206115872;15323.387064517434;15336;15344.693106137898;2711.064682684471;2711.281510399736;2743.231895069384;2743.2867454461884;2743.327884668249;2763.5025695374825;2787.4425114976984;2787.456445993032;2789.623400840891;2789.9442011159763;2764.530029894923;2765.612814206003;2779.4857951278977;2782.916512504628;2787.1359878437747;2571.935701607456;2613.398918248804;2614.816962812604;2614.9346266343496;2625.5011547805857;2584.1988983415204;2593.364625666705;2597.6493173421536;2610;2613.2290974162665;14268.57194284173;14571.19858407787;14601.269936503133;14612.780808287884;14625.268736563236;7351.77944661661;7556.47104702671;7582.052243469567;7620.61896905158;7624.961875190588;5235.6073294502685;5359;5464.672119672832;5501.697406642633;5508.476694714036;1917.7890432052463;1940.2045161483866;1941.7184508246226;1943.7375954246138;1974.1116497576004;24770.38074048145;24813.875930620412;24818.37954051145;24857.627135592982;24861.254162375157;6524;6607.702653380609;6644.700988455531;6809.693563789641;6818.352256535622;16430.917845410695;16455.25951332193;16584.253708583143;16616.50150495487;16621.584460388585;7322.96338518304;7413.073365829271;7473.476856619985;7476.364509016686;7478.775636730907;5707.68607726579;5996.16053752475;6080.817575472743;6942.930570694229;6960.338767817049;5104.69371837691;5151.356080489939;5207.739613019334;5226.660267082628;5343.198520221998;1477.3647331647494;1540.3607502886177;1546.8917175797585;1550.58909389012;1554.945576904803;861.1603686405705;871.1680345270252;878.0210065776613;880.2517859819103;888.0276097673167;7072.8939065914055;7094.787156385213;7125.857482850314;7164.426845852319;7175.964120179366;698.1238545625208;698.4586945117474;708.7554793596202;710.8542748736496;715.3025799845109;4490.39379683745;4809.254565542346;4814.9518504815205;4866.075445665312;5047.823326183566;19782.90108549448;20920.058597363157;21222.681659775117;21378.14487420517;21688.457788555414;20312.898435507726;20663.69004464904;20669;20805.271815486387;20847.478813029797;10709.625163119254;11179.832302515631;11207.215494915441;11270.887291127148;11374.31754094757;7458.644816700869;7663.31030207284;7693;7730.265624765639;7749.496282741604;17453.127343633023;17536.47390578285;17700.026498542702;17858.285668573113;18139.365122220657;18315.402519304218;18540.727142115997;18605;18991.9554424508;19064.523386915436;9967.950160249442;10615.787684246117;10700.83948740754;10715.571377144826;10728.141748659933;12414.62756117318;12511.749765004466;12520.123591348518;12531.18547294423;12538.122331437076;10226.07965283128;10345.017223363768;10393.740156496147;10399.844002340122;10555.736106597396;9431.952840235756;9593.088656577615;9596.424214547149;9718.465484398426;10203.897961020592;8472.152784721726;8874.556272186235;8961;8963.103689630985;8967.955160224157;6425.935740642721;6480.611163330215;6542.640154791342;6544.051112588743;6560.245571759308;6380.712867921047;6470.22357317125;6480.546361754632;6501.1548498694865;6542.247641521286 18:36:02 INFO - PID 13560 | |4;prototype.html;499.5039173423655;503.52668491617874;518.4193703052582;520.2820108250613;520.7578476008655;6439.195100612424;6451.225852897653;6461.3538646135485;6486.156799616043;6521.413072823445;6149.385061493848;6255.562110652256;6286.685665716707;6323.20959880015;6338.714757835897;4998.300237966688;5074.847754567369;5114.258432527291;5120.718360490171;5154.974225128869;5071.746412679369;5089.821856235032;5096.388433387999;5136.794528218867;5138.768755405997;4874.878128046792;4983.775730092137;5016.949830501685;5025.522575355335;5037.546620804127;4965.428975667808;4981;5030.874228144289;5046.5710414614805;5071.7320669832725;4430.534793846664;4465.955340446587;4469.307257375112;4491.932621010687;4495.707778994388;6227.626342419469;6231.626102433868;6236.220472440945;6242.906356404657;6254.249490061176;5714.285714285715;5725.370209276976;5749.453801888814;5769.8557536061935;5776.393478684719;6590;6625.73497060117;6679.799606011828;6740.494462915301;6747.291534388916;4780.617550595944;4814.15752243356;4827.468978412372;4835.371401717789;4836.951630483721;6924.446044316443;6949.44404447643;6981.3367730065565;7008.4957521239385;7054.6825392857445;4990.3762029746285;5018.42288136869;5054.115529782273;5074.974625126924;5114.71869047206;5018.544622059635;5085.74571271435;5102.413222479462;5124.846254612367;5144.845654630367;5629.268195134607;5746.224259724967;5755.733738577506;5759.654420734685;5786.392428794916;6612.537122401482;6714.529982901247;6734.663266836638;6747;6771.187457505131;1645.4569991902642;1720.9913950430168;1721.3458885623384;1730.731736580832;1741.5907261793468;1177.3701069927547;1207.5954555224098;1222.584321330752;1232.9321887296285;1237.2576454127452;5813.505852002627;5872.7357268923;5875.941240587625;5933.9352543506975;5996.190514280604;6600.80197594073;6637.601743895381;6657.966710166515;6714.932850671431;6773.593584384953;5857.121431785266;5886.970565147147;5891.646501209942;5900.76396944126;5906.527477801766;3523.612402635708;3590.605033446319;3613.8915832525067;3640.4175331946763;3647.9270414592092;6458.61248325102;6517.152770139852;6537.411632953057;6626.734930602818;6692.397684208445;6887.965560172267;6992.825179370455;7050.0482434871665;7050.365467107882;7073.929260707327;6519.4132528072705;6524.902126468107;6609.173853268341;6627.105340779126;6632.204135503771;6735.158105236846;6740.39336459721;6763.93236067633;6770.966145169243;6836.213835408793;6577.408033277028;6628.171478565179;6642.701078451385;6671.2981242596825;6731.562448440835;6363.172787537591;6432.2281326241155;6433.710483028369;6559.392948727402;6567.573107747981;5014.272930425141;5026.145555255688;5034.899302014012;5056.292119103402;5056.570191533614;6646.335366463315;6785.355391238022;6787.457003439812;6930.48021398387;6936.306369363023;6498.935010649833;6518.543701940818;6530.510211734044;6580.374864387875;6624;6485.77299794505;6488.610683359014;6552.180977377828;6630.436412904764;6658.467322614276;6003.8198854034445;6472.190976127984;6481.222253329631;6500.252470965899;6615.37153970372;6495.350464953467;6629.701663425254;6648.235452922782;6689.09697190873;6696.19645642526;6323.028176756917;6357.682115894279;6430.2605200400685;6488.740450382121;6546.18172728409 18:36:02 INFO - PID 13560 | |5;yui.html;875.1161327059668;890.7594949363672;891.2825175733534;898.4519443139687;904.8371293167231;17035.829641703578;18075.638487230248;18084.457466276;18101.72847407287;18141.909290453546;17850.553736156602;18428.72356914644;19077.618447631037;19144.90427547864;19253.711194332096;910.1990248581255;942.5522876633579;948.8292107420662;949.5157469690473;951.2580187453763;906.9591868365907;936.9437833729964;937.6858752317981;947.8672985781961;953.1516949914547;963.9084286992725;964.0407794244736;964.1948972607881;964.3587014635294;986.9555869985832;963.8795150606175;964.6430820596354;964.8938616752151;965.3435663748648;989.223459584223;709.6345382128208;709.9503034787567;710.350029722803;716.6380977606327;728.6903066196899;16674.082925439096;16721.749173762404;16724.498265051945;16733.414330498374;18868.528286792804;20473;20629.4842628934;20863;21033;21062.684059739116;16849.747253791204;16928.238229279712;17052.744208836753;17081.402150924783;17113.22990465432;3694.3719567673293;3730.365837807552;3730.9626903730887;3732.234891847165;3732.496113024761;125926.37036814849;126768;128249.35875320564;129198.3540082303;130535;15039.924800375928;15325.31036103378;15475.84524154744;15518.37926482928;15617.68764624724;29513.557296640567;29535.409291814474;30226.395472090437;30257.09228723142;30269;8647.524386158695;8830.205281524693;8860.424072435271;8862.556872156367;8877.556122193864;3658.4695219193068;3962.465067215889;4130.938035929464;4133.297339452301;4133.42132101506;4358.978205108954;5774.133879917962;5816.854578635568;5819.88360232793;5826.388229235954;1079;1097.6871591596516;1109.9556017759198;1110.1451882050937;1122.848415463902;651.2900937977627;670.2794495916909;671.3454381977631;684.9589024658437;694.4097517110516;11003.284786488852;11054.15667466024;11326.773464530663;11583.942080289544;12091.730368311377;667.1427216027381;679.3037136934582;683.9794806155824;696.8257935516122;707.1266985273184;4517.141743068806;4781.019890922353;5639.379668236491;5857.589968702235;5956.873315363899;107292.46353768182;107905;109688;111376.4431177839;113468;92419;94101;95520;98256.50871745739;99709.50145249227;78869.60565197137;85754;86273.56863215644;87982.56008719915;90992.09007909968;11309.547618095185;11358;11438.42807859587;11457.656270311903;12081.154319197572;38560.80719596384;39648.80175599104;39825.2034959305;40582.1883562333;41481.79259103685;75396;75446.73659790216;75537.62231189029;75909.24090759242;77375.61312193403;17057.573560661083;17978;18016.00911949803;18113.909430452764;18173.818261817214;18667.813321866608;19053.42839714811;19115.904420477807;19128.617427651647;19155.616887662447;15051.623709407353;15176.24118794078;15215.239238038275;15264.313105910484;18135;16289.67420651604;16292.859499834898;16500.587485312964;16580.917095414447;16793;17452.73820892662;17622.559436014202;17694.469165925042;18003.72994405059;18007.909960450637;11425.485853136463;11436.256643318158;11625;12551;13615.591532254048;13456.394462249027;13659.38532766008;13888.861111388756;13904.165750055028;14256.786148207993 18:36:02 INFO - PID 13560 | __end_tp_report 18:36:02 INFO - PID 13560 | __start_cc_report 18:36:02 INFO - PID 13560 | _x_x_mozilla_cycle_collect,364 18:36:02 INFO - PID 13560 | __end_cc_report 18:36:02 INFO - PID 13560 | __startTimestamp1503884162770__endTimestamp 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | ------- Summary: start ------- 18:36:02 INFO - PID 13560 | Number of tests: 6 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | [#0] dojo.html Cycles:165 Average:6246.23 Median:6469.39 stddev:726.36 (11.2%) stddev-sans-first:689.82 18:36:02 INFO - PID 13560 | Values: 3261.6 3273.2 3284.6 3289.7 3298.2 6316.2 6400.3 6412.4 6437.4 6478.6 6105.4 6160.9 6174.5 6210.7 6272.0 5829.5 5860.9 5919.5 5940.0 6005.7 5817.7 5818.7 5822.9 5898.6 5909.1 5591.7 5706.2 5780.2 5847.2 5871.6 5779.6 5786.7 5853.6 5867.8 5868.5 5640.5 5690.6 5708.3 5739.5 5841.6 6231.8 6273.4 6284.3 6372.5 6407.6 6758.9 6798.4 6838.8 6841.3 6873.2 6803.6 6806.0 6814.3 6907.9 6922.6 6340.2 6396.1 6400.5 6406.9 6426.3 6886.7 7042.2 7064.3 7088.2 7102.5 6626.0 6675.3 6680.2 6757.4 6759.3 6675.0 6734.2 6764.1 6765.2 6769.3 6707.4 6720.0 6725.1 6725.2 6728.7 6615.7 6753.8 6757.9 6824.0 6825.9 5346.7 5385.4 5417.8 5419.5 5420.3 4650.8 4708.6 4731.1 4742.5 4743.6 5821.2 5921.3 5954.2 6033.1 6042.4 6777.5 6783.8 6784.6 6784.7 6793.0 5977.7 5980.6 5994.7 6021.5 6026.5 5838.9 5919.8 5920.1 5929.6 5932.5 6456.1 6542.8 6550.4 6561.4 6670.9 6774.8 6780.4 6785.6 6790.0 6795.7 6474.3 6497.3 6536.1 6550.1 6556.9 6242.5 6655.2 6757.6 6765.7 6765.8 6858.1 6929.3 6943.2 6944.2 6950.5 6570.7 6576.7 6586.1 6676.5 6680.9 6486.4 6565.3 6574.8 6634.5 6663.6 6448.0 6469.4 6494.4 6496.5 6501.1 6540.9 6588.9 6594.2 6599.0 6602.3 6323.8 6325.2 6364.3 6411.4 6463.6 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | [#1] ext.html Cycles:180 Average:36570.05 Median:3465.40 stddev:52152.98 (1505.0%) stddev-sans-first:52230.10 18:36:02 INFO - PID 13560 | Values: 795.3 805.5 853.3 859.8 861.0 38127.2 38850.2 38862.6 38894.2 38895.0 35570.0 35767.5 35968.3 36130.3 36151.8 6902.4 6999.7 7006.7 7011.5 7028.9 6925.5 6984.7 6996.2 7015.9 7020.0 7518.1 7694.5 7695.8 7696.4 7707.3 7584.5 7653.5 7653.7 7656.7 7658.5 7573.4 7643.8 7644.9 7646.7 7647.2 34664.5 34933.1 34952.3 34968.3 34986.0 3444.4 3463.4 3464.4 3466.4 3480.8 29709.6 29907.1 29916.4 29975.4 30048.2 4903.6 5104.4 5111.6 5124.6 5186.9 175538.0 176071.0 176589.0 176641.0 177414.0 44058.8 44796.3 44807.6 45025.8 45089.8 46354.5 46527.0 46583.5 46590.0 46652.3 15550.0 15620.5 15639.0 15639.3 15770.2 12802.4 12849.8 12860.8 12866.4 12887.4 12998.8 13036.2 13044.0 13121.9 13169.0 5391.2 5475.1 5475.2 5481.3 5495.2 979.3 983.9 1001.3 1001.5 1003.7 20397.3 20461.9 20583.8 20684.3 20986.3 1072.6 1078.7 1102.4 1109.0 1109.2 10572.0 10572.3 10674.6 10903.3 11075.1 220446.0 221134.0 221459.0 221544.0 221811.0 41784.0 42718.0 46967.0 47499.5 49568.5 1376.3 1411.2 1415.9 1424.6 1437.9 25766.7 26016.3 26087.2 26150.0 26583.6 47898.8 48176.0 48439.0 48440.3 48450.3 201977.0 204036.0 204376.0 204430.0 205346.0 33841.7 34016.0 34122.0 34133.3 34223.3 40828.8 40980.4 44085.3 45108.8 45239.3 32156.6 32258.0 32292.4 32338.0 32567.8 31926.4 32136.7 32443.7 32459.7 33007.5 36706.3 36821.1 36836.4 37138.4 37850.2 32026.2 32362.4 32410.2 32437.4 32541.0 23830.2 23905.9 24131.0 24383.0 24858.9 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | [#2] jquery.html Cycles:180 Average:33305.55 Median:13010.58 stddev:75860.18 (583.1%) stddev-sans-first:76033.13 18:36:02 INFO - PID 13560 | Values: 553.4 554.4 570.7 574.1 574.6 20985.2 21605.6 21618.9 21639.7 22546.8 24196.0 24883.3 24950.5 25014.7 25017.0 6423.2 6532.1 6533.8 6537.7 6558.7 6105.7 6331.9 6365.2 6468.2 6513.8 6321.5 6329.7 6334.1 6342.7 6347.0 6331.9 6347.6 6348.6 6349.0 6351.8 5877.6 5881.9 5893.0 5898.2 5901.5 28245.4 28304.0 28774.6 28818.1 28865.0 2041.8 2092.8 2094.2 2094.4 2095.7 18676.2 19243.0 19297.6 19299.9 19311.4 2383.6 2410.3 2424.8 2429.5 2492.6 130261.0 134574.0 135062.0 135077.3 135495.3 14216.2 14748.1 14840.3 14877.3 14969.3 22192.0 22196.2 22242.6 22244.9 22341.0 9727.9 9902.6 9922.6 9944.2 10010.2 7990.1 8999.7 9026.5 9107.7 9310.6 7961.8 8856.8 8858.1 9022.1 9032.4 2596.9 2605.4 2628.3 2663.5 2734.8 924.6 943.5 958.3 961.1 965.0 19074.3 19200.7 19557.4 19640.3 20056.5 993.1 999.6 1007.8 1014.2 1019.5 10499.8 10579.5 10612.6 10676.6 11007.7 441360.0 441712.0 447546.0 450851.0 461473.0 105467.5 109591.0 111373.4 111515.0 111533.4 1103.6 1131.0 1148.5 1149.5 1152.0 16709.7 16912.2 17226.6 17355.4 17567.2 54787.0 55021.4 55030.7 55871.4 56724.4 66732.0 66944.0 66990.0 68829.3 70498.0 11871.7 12317.0 13946.3 14320.2 20985.4 23587.5 23637.5 24024.0 24378.5 24384.4 17747.4 18164.5 18839.8 18943.9 18962.0 16991.2 17419.0 17458.5 17618.2 17650.2 19540.4 19659.5 19766.0 19881.1 20034.6 20628.4 20682.9 21682.6 21684.3 21713.9 18406.7 18866.1 19002.8 19027.6 19029.5 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | [#3] mootools.html Cycles:180 Average:8844.60 Median:2711.17 stddev:6389.82 (235.7%) stddev-sans-first:6382.60 18:36:02 INFO - PID 13560 | Values: 1299.8 1357.9 1361.2 1361.5 1367.1 14353.9 14948.9 15017.0 15030.4 15194.6 14980.3 15289.7 15323.4 15336.0 15344.7 2711.1 2711.3 2743.2 2743.3 2743.3 2763.5 2787.4 2787.5 2789.6 2789.9 2764.5 2765.6 2779.5 2782.9 2787.1 2571.9 2613.4 2614.8 2614.9 2625.5 2584.2 2593.4 2597.6 2610.0 2613.2 14268.6 14571.2 14601.3 14612.8 14625.3 7351.8 7556.5 7582.1 7620.6 7625.0 5235.6 5359.0 5464.7 5501.7 5508.5 1917.8 1940.2 1941.7 1943.7 1974.1 24770.4 24813.9 24818.4 24857.6 24861.3 6524.0 6607.7 6644.7 6809.7 6818.4 16430.9 16455.3 16584.3 16616.5 16621.6 7323.0 7413.1 7473.5 7476.4 7478.8 5707.7 5996.2 6080.8 6942.9 6960.3 5104.7 5151.4 5207.7 5226.7 5343.2 1477.4 1540.4 1546.9 1550.6 1554.9 861.2 871.2 878.0 880.3 888.0 7072.9 7094.8 7125.9 7164.4 7176.0 698.1 698.5 708.8 710.9 715.3 4490.4 4809.3 4815.0 4866.1 5047.8 19782.9 20920.1 21222.7 21378.1 21688.5 20312.9 20663.7 20669.0 20805.3 20847.5 10709.6 11179.8 11207.2 11270.9 11374.3 7458.6 7663.3 7693.0 7730.3 7749.5 17453.1 17536.5 17700.0 17858.3 18139.4 18315.4 18540.7 18605.0 18992.0 19064.5 9968.0 10615.8 10700.8 10715.6 10728.1 12414.6 12511.7 12520.1 12531.2 12538.1 10226.1 10345.0 10393.7 10399.8 10555.7 9432.0 9593.1 9596.4 9718.5 10203.9 8472.2 8874.6 8961.0 8963.1 8968.0 6425.9 6480.6 6542.6 6544.1 6560.2 6380.7 6470.2 6480.5 6501.2 6542.2 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | [#4] prototype.html Cycles:180 Average:5568.99 Median:6248.58 stddev:1571.80 (25.2%) stddev-sans-first:1529.46 18:36:02 INFO - PID 13560 | Values: 499.5 503.5 518.4 520.3 520.8 6439.2 6451.2 6461.4 6486.2 6521.4 6149.4 6255.6 6286.7 6323.2 6338.7 4998.3 5074.8 5114.3 5120.7 5155.0 5071.7 5089.8 5096.4 5136.8 5138.8 4874.9 4983.8 5016.9 5025.5 5037.5 4965.4 4981.0 5030.9 5046.6 5071.7 4430.5 4466.0 4469.3 4491.9 4495.7 6227.6 6231.6 6236.2 6242.9 6254.2 5714.3 5725.4 5749.5 5769.9 5776.4 6590.0 6625.7 6679.8 6740.5 6747.3 4780.6 4814.2 4827.5 4835.4 4837.0 6924.4 6949.4 6981.3 7008.5 7054.7 4990.4 5018.4 5054.1 5075.0 5114.7 5018.5 5085.7 5102.4 5124.8 5144.8 5629.3 5746.2 5755.7 5759.7 5786.4 6612.5 6714.5 6734.7 6747.0 6771.2 1645.5 1721.0 1721.3 1730.7 1741.6 1177.4 1207.6 1222.6 1232.9 1237.3 5813.5 5872.7 5875.9 5933.9 5996.2 6600.8 6637.6 6658.0 6714.9 6773.6 5857.1 5887.0 5891.6 5900.8 5906.5 3523.6 3590.6 3613.9 3640.4 3647.9 6458.6 6517.2 6537.4 6626.7 6692.4 6888.0 6992.8 7050.0 7050.4 7073.9 6519.4 6524.9 6609.2 6627.1 6632.2 6735.2 6740.4 6763.9 6771.0 6836.2 6577.4 6628.2 6642.7 6671.3 6731.6 6363.2 6432.2 6433.7 6559.4 6567.6 5014.3 5026.1 5034.9 5056.3 5056.6 6646.3 6785.4 6787.5 6930.5 6936.3 6498.9 6518.5 6530.5 6580.4 6624.0 6485.8 6488.6 6552.2 6630.4 6658.5 6003.8 6472.2 6481.2 6500.3 6615.4 6495.4 6629.7 6648.2 6689.1 6696.2 6323.0 6357.7 6430.3 6488.7 6546.2 18:36:02 INFO - PID 13560 | 18:36:02 INFO - PID 13560 | [#5] yui.html Cycles:180 Average:23446.67 Median:29524.48 stddev:32512.79 (110.1%) stddev-sans-first:32559.82 18:36:02 INFO - PID 13560 | Values: 875.1 890.8 891.3 898.5 904.8 17035.8 18075.6 18084.5 18101.7 18141.9 17850.6 18428.7 19077.6 19144.9 19253.7 910.2 942.6 948.8 949.5 951.3 907.0 936.9 937.7 947.9 953.2 963.9 964.0 964.2 964.4 987.0 963.9 964.6 964.9 965.3 989.2 709.6 710.0 710.4 716.6 728.7 16674.1 16721.7 16724.5 16733.4 18868.5 20473.0 20629.5 20863.0 21033.0 21062.7 16849.7 16928.2 17052.7 17081.4 17113.2 3694.4 3730.4 3731.0 3732.2 3732.5 125926.4 126768.0 128249.4 129198.4 130535.0 15039.9 15325.3 15475.8 15518.4 15617.7 29513.6 29535.4 30226.4 30257.1 30269.0 8647.5 8830.2 8860.4 8862.6 8877.6 3658.5 3962.5 4130.9 4133.3 4133.4 4359.0 5774.1 5816.9 5819.9 5826.4 1079.0 1097.7 1110.0 1110.1 1122.8 651.3 670.3 671.3 685.0 694.4 11003.3 11054.2 11326.8 11583.9 12091.7 667.1 679.3 684.0 696.8 707.1 4517.1 4781.0 5639.4 5857.6 5956.9 107292.5 107905.0 109688.0 111376.4 113468.0 92419.0 94101.0 95520.0 98256.5 99709.5 78869.6 85754.0 86273.6 87982.6 90992.1 11309.5 11358.0 11438.4 11457.7 12081.2 38560.8 39648.8 39825.2 40582.2 41481.8 75396.0 75446.7 75537.6 75909.2 77375.6 17057.6 17978.0 18016.0 18113.9 18173.8 18667.8 19053.4 19115.9 19128.6 19155.6 15051.6 15176.2 15215.2 15264.3 18135.0 16289.7 16292.9 16500.6 16580.9 16793.0 17452.7 17622.6 17694.5 18003.7 18007.9 11425.5 11436.3 11625.0 12551.0 13615.6 13456.4 13659.4 13888.9 13904.2 14256.8 18:36:02 INFO - PID 13560 | -------- Summary: end -------- 18:36:02 INFO - PID 13560 | 18:36:03 INFO - TEST-INFO | 13560: exit 0 18:36:03 INFO - TEST-OK | dromaeo_css | took 1111650ms 18:36:03 INFO - TEST-START | kraken 18:36:03 INFO - Initialising browser for kraken test... 18:36:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35685/getInfo.html -profile /tmp/tmp8SWSE8/profile 18:36:03 INFO - TEST-INFO | started process 13975 (/builds/slave/test/build/application/firefox/firefox http://localhost:35685/getInfo.html) 18:36:09 INFO - TEST-INFO | 13975: exit 0 18:36:09 INFO - Browser initialized. 18:36:09 INFO - Running cycle 1/1 for kraken test... 18:36:09 INFO - Using env: {'DISPLAY': ':0', 18:36:09 INFO - 'HOME': '/home/cltbld', 18:36:09 INFO - 'JSGC_DISABLE_POISONING': '1', 18:36:09 INFO - 'LANG': 'en_US.UTF-8', 18:36:09 INFO - 'LANGUAGE': 'en_US:en', 18:36:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:36:09 INFO - 'LOGNAME': 'cltbld', 18:36:09 INFO - 'MAIL': '/var/mail/cltbld', 18:36:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:36:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:36:09 INFO - 'MOZ_CRASHREPORTER': '1', 18:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:36:09 INFO - 'MOZ_NO_REMOTE': '1', 18:36:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:36:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:09 INFO - 'NO_EM_RESTART': '1', 18:36:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:09 INFO - 'PWD': '/builds/slave/test', 18:36:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:36:09 INFO - 'RUST_BACKTRACE': 'full', 18:36:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:36:09 INFO - 'SHELL': '/bin/bash', 18:36:09 INFO - 'SHLVL': '1', 18:36:09 INFO - 'STYLO_FORCE_ENABLED': '1', 18:36:09 INFO - 'TERM': 'linux', 18:36:09 INFO - 'TMOUT': '86400', 18:36:09 INFO - 'USER': 'cltbld', 18:36:09 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1503875775.720873-793745818', 18:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:09 INFO - '_': '/tools/buildbot/bin/python'} 18:36:09 INFO - TEST-INFO | started process 14161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8SWSE8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:36:10 INFO - PID 14161 | 18:36:10 INFO - PID 14161 | (/builds/slave/test/build/application/firefox/firefox:14210): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:36:10 INFO - PID 14161 | 18:36:10 INFO - PID 14161 | 18:36:10 INFO - PID 14161 | (/builds/slave/test/build/application/firefox/firefox:14271): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:36:10 INFO - PID 14161 | 18:36:10 INFO - PID 14161 | __metrics Screen width/height:1600/1200 18:36:10 INFO - PID 14161 | colorDepth:24 18:36:10 INFO - PID 14161 | Browser inner width/height: 1024/768 18:36:10 INFO - PID 14161 | __metrics 18:36:20 INFO - PID 14161 | RSS: Main: 174571520 18:36:20 INFO - PID 14161 | 18:36:23 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35685/tests/kraken/driver.html?testName=audio-beat-detection) 18:36:24 INFO - PID 14161 | RSS: Main: 178532352 18:36:24 INFO - PID 14161 | 18:36:26 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35685/tests/kraken/driver.html?testName=audio-dft) 18:36:27 INFO - PID 14161 | RSS: Main: 180797440 18:36:27 INFO - PID 14161 | 18:36:30 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35685/tests/kraken/driver.html?testName=audio-fft) 18:36:30 INFO - PID 14161 | RSS: Main: 174370816 18:36:30 INFO - PID 14161 | 18:36:33 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35685/tests/kraken/driver.html?testName=audio-oscillator) 18:36:33 INFO - PID 14161 | RSS: Main: 174424064 18:36:33 INFO - PID 14161 | 18:36:35 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35685/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:36:36 INFO - PID 14161 | RSS: Main: 175411200 18:36:36 INFO - PID 14161 | 18:36:39 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35685/tests/kraken/driver.html?testName=imaging-darkroom) 18:36:40 INFO - PID 14161 | RSS: Main: 176398336 18:36:40 INFO - PID 14161 | 18:36:43 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35685/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:44 INFO - PID 14161 | RSS: Main: 176074752 18:36:44 INFO - PID 14161 | 18:36:47 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35685/tests/kraken/driver.html?testName=json-parse-financial) 18:36:47 INFO - PID 14161 | RSS: Main: 176971776 18:36:47 INFO - PID 14161 | 18:36:49 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35685/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:50 INFO - PID 14161 | RSS: Main: 176668672 18:36:50 INFO - PID 14161 | 18:36:51 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:36:52 INFO - PID 14161 | RSS: Main: 176611328 18:36:52 INFO - PID 14161 | 18:36:54 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:36:54 INFO - PID 14161 | RSS: Main: 176599040 18:36:54 INFO - PID 14161 | 18:36:57 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:36:58 INFO - PID 14161 | RSS: Main: 177582080 18:36:58 INFO - PID 14161 | 18:37:01 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:37:01 INFO - PID 14161 | RSS: Main: 177516544 18:37:01 INFO - PID 14161 | 18:37:03 INFO - PID 14161 | Cycle 1(1): loaded http://localhost:35685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35685/tests/kraken/driver.html?testName=ai-astar) 18:37:04 INFO - PID 14161 | RSS: Main: 178864128 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | __start_tp_report 18:37:04 INFO - PID 14161 | _x_x_mozilla_page_load 18:37:04 INFO - PID 14161 | _x_x_mozilla_page_load_details 18:37:04 INFO - PID 14161 | |i|pagename|runs| 18:37:04 INFO - PID 14161 | |0;ai-astar;97;115;100;106;106;109;109;99;105;109 18:37:04 INFO - PID 14161 | |1;audio-beat-detection;148;117;145;171;119;141;142;122;169;121 18:37:04 INFO - PID 14161 | |2;audio-dft;164;162;163;163;162;162;159;164;167;169 18:37:04 INFO - PID 14161 | |3;audio-fft;74;74;74;161;75;74;73;74;168;73 18:37:04 INFO - PID 14161 | |4;audio-oscillator;79;79;130;78;79;80;128;78;80;126 18:37:04 INFO - PID 14161 | |5;imaging-gaussian-blur;115;114;113;113;114;114;113;114;113;114 18:37:04 INFO - PID 14161 | |6;imaging-darkroom;146;148;150;147;149;148;148;148;149;148 18:37:04 INFO - PID 14161 | |7;imaging-desaturate;94;93;94;94;94;95;95;94;94;94 18:37:04 INFO - PID 14161 | |8;json-parse-financial;53;53;53;53;53;53;52;53;53;53 18:37:04 INFO - PID 14161 | |9;json-stringify-tinderbox;38;38;39;38;38;37;37;38;38;37 18:37:04 INFO - PID 14161 | |10;stanford-crypto-aes;74;79;75;74;75;75;74;73;74;74 18:37:04 INFO - PID 14161 | |11;stanford-crypto-ccm;121;121;131;124;123;122;118;127;121;155 18:37:04 INFO - PID 14161 | |12;stanford-crypto-pbkdf2;172;180;185;169;167;168;169;171;170;172 18:37:04 INFO - PID 14161 | |13;stanford-crypto-sha256-iterative;68;64;68;66;64;65;64;67;69;64 18:37:04 INFO - PID 14161 | __end_tp_report 18:37:04 INFO - PID 14161 | __start_cc_report 18:37:04 INFO - PID 14161 | _x_x_mozilla_cycle_collect,956 18:37:04 INFO - PID 14161 | __end_cc_report 18:37:04 INFO - PID 14161 | __startTimestamp1503884224002__endTimestamp 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | ------- Summary: start ------- 18:37:04 INFO - PID 14161 | Number of tests: 14 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#0] ai-astar Cycles:10 Average:105.50 Median:109.00 stddev:5.50 (5.0%) stddev-sans-first:4.90 18:37:04 INFO - PID 14161 | Values: 97.0 115.0 100.0 106.0 106.0 109.0 109.0 99.0 105.0 109.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:143.50 stddev:19.86 (13.8%) stddev-sans-first:20.82 18:37:04 INFO - PID 14161 | Values: 148.0 117.0 145.0 171.0 119.0 141.0 142.0 122.0 169.0 121.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:2.80 (1.7%) stddev-sans-first:2.96 18:37:04 INFO - PID 14161 | Values: 164.0 162.0 163.0 163.0 162.0 162.0 159.0 164.0 167.0 169.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#3] audio-fft Cycles:10 Average:92.00 Median:74.00 stddev:38.25 (51.7%) stddev-sans-first:40.01 18:37:04 INFO - PID 14161 | Values: 74.0 74.0 74.0 161.0 75.0 74.0 73.0 74.0 168.0 73.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:79.00 stddev:23.70 (30.0%) stddev-sans-first:24.53 18:37:04 INFO - PID 14161 | Values: 79.0 79.0 130.0 78.0 79.0 80.0 128.0 78.0 80.0 126.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 18:37:04 INFO - PID 14161 | Values: 115.0 114.0 113.0 113.0 114.0 114.0 113.0 114.0 113.0 114.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:1.10 (0.7%) stddev-sans-first:0.87 18:37:04 INFO - PID 14161 | Values: 146.0 148.0 150.0 147.0 149.0 148.0 148.0 148.0 149.0 148.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:37:04 INFO - PID 14161 | Values: 94.0 93.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 18:37:04 INFO - PID 14161 | Values: 53.0 53.0 53.0 53.0 53.0 53.0 52.0 53.0 53.0 53.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 18:37:04 INFO - PID 14161 | Values: 38.0 38.0 39.0 38.0 38.0 37.0 37.0 38.0 38.0 37.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:74.50 stddev:1.64 (2.2%) stddev-sans-first:1.72 18:37:04 INFO - PID 14161 | Values: 74.0 79.0 75.0 74.0 75.0 75.0 74.0 73.0 74.0 74.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:123.50 stddev:10.72 (8.7%) stddev-sans-first:11.20 18:37:04 INFO - PID 14161 | Values: 121.0 121.0 131.0 124.0 123.0 122.0 118.0 127.0 121.0 155.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:171.50 stddev:5.74 (3.3%) stddev-sans-first:6.08 18:37:04 INFO - PID 14161 | Values: 172.0 180.0 185.0 169.0 167.0 168.0 169.0 171.0 170.0 172.0 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.50 stddev:1.97 (3.0%) stddev-sans-first:1.94 18:37:04 INFO - PID 14161 | Values: 68.0 64.0 68.0 66.0 64.0 65.0 64.0 67.0 69.0 64.0 18:37:04 INFO - PID 14161 | -------- Summary: end -------- 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:37:04 INFO - PID 14161 | 18:37:04 INFO - PID 14161 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:37:04 INFO - TEST-INFO | 14161: exit 0 18:37:04 INFO - TEST-OK | kraken | took 61449ms 18:37:04 INFO - SUITE-END | took 1173s 18:37:05 INFO - Completed test suite (00:19:33) 18:37:05 INFO - javascript benchmark 18:37:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8394.4275377024, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3261.59230096238, 3273.1653428375766, 3284.5894263217097, 3289.6545862684434, 3298.2414044769703, 6316.242050953881, 6400.295967443578, 6412.42288194063, 6437.420632143107, 6478.643674597895, 6105.389461053893, 6160.907586386208, 6174.475169610577, 6210.689465526728, 6272.027835685464, 5829.533637309016, 5860.853478663025, 5919.526437884959, 5940.0, 6005.6997150142315, 5817.709114544277, 5818.679972601505, 5822.9417705822825, 5898.557608179382, 5909.084091965752, 5591.74837132328, 5706.229658996024, 5780.1907732917425, 5847.152162936393, 5871.647701137946, 5779.624324418899, 5786.71066446676, 5853.5902486825935, 5867.823965281048, 5868.530517558585, 5640.464155905182, 5690.573207009449, 5708.315002199721, 5739.483446489836, 5841.591088623798, 6231.813045608639, 6273.435390814849, 6284.27730810958, 6372.522060845409, 6407.583507072072, 6758.898616520756, 6798.388145066919, 6838.794836154923, 6841.281665425108, 6873.175218973705, 6803.625800580966, 6806.047153398528, 6814.2845001274645, 6907.861842763167, 6922.550034247808, 6340.207474065742, 6396.136521569575, 6400.4559612432995, 6406.93593064068, 6426.260979987268, 6886.724531018703, 7042.225355210922, 7064.258252883476, 7088.220295767462, 7102.467314951399, 6626.0, 6675.299093595199, 6680.23177334603, 6757.425618822455, 6759.324067593201, 6674.966625166843, 6734.225564060098, 6764.086848275519, 6765.154355705537, 6769.32306769329, 6707.3628005339415, 6719.966400167968, 6725.05849181115, 6725.226598941086, 6728.730850766013, 6615.669216539154, 6753.797386078327, 6757.898631520433, 6824.010518474794, 6825.9317406826285, 5346.732663366816, 5385.407605163429, 5417.8103766367985, 5419.458054194549, 5420.349558053059, 4650.837220697259, 4708.623310135181, 4731.077439899186, 4742.549457801503, 4743.596794272526, 5821.214136091573, 5921.348651648315, 5954.19618351517, 6033.064874944303, 6042.395760423922, 6777.457803375718, 6783.830404239933, 6784.59292442455, 6784.728610855511, 6793.048973143764, 5977.731002104916, 5980.55145864062, 5994.730237139339, 6021.458068773743, 6026.54800889935, 5838.9124163137585, 5919.822405327847, 5920.111983202468, 5929.614575052694, 5932.466078052909, 6456.128422662974, 6542.8364290893105, 6550.377714117151, 6561.376669216417, 6670.9332906670315, 6774.796756097325, 6780.38976492118, 6785.626790526569, 6790.049393084873, 6795.72817087311, 6474.320196379501, 6497.31778163305, 6536.084948107174, 6550.148480697479, 6556.934430655823, 6242.531810114168, 6655.23464801554, 6757.560758550679, 6765.695543700447, 6765.830854228487, 6858.074159988537, 6929.3070692930305, 6943.166819981635, 6944.201416836926, 6950.513464057467, 6570.737170513126, 6576.736930522917, 6586.110875032001, 6676.499262555425, 6680.931051031812, 6486.448651864455, 6565.310642382481, 6574.76988305407, 6634.4692424604955, 6663.566868153554, 6448.032795080775, 6469.385408386196, 6494.415502604599, 6496.480281577368, 6501.057346684799, 6540.869182616416, 6588.901166482602, 6594.241662208908, 6599.043138744951, 6602.306757790363, 6323.841903952438, 6325.177726895474, 6364.331745166691, 6411.358864113738, 6463.61218326883], "value": 6193.199190349305, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.2683531151343, 805.5046146619824, 853.3130829682106, 859.8409294280557, 860.9913900860994, 38127.23745525091, 38850.222995540105, 38862.61137388629, 38894.22211555774, 38895.0, 35570.0, 35767.46348804764, 35968.28063438736, 36130.277394452096, 36151.81924090376, 6902.447804175654, 6999.685014174349, 7006.684699188549, 7011.474139439562, 7028.894566581505, 6925.515213935027, 6984.685689144001, 6996.230414654384, 7015.89476157858, 7020.0, 7518.097828260617, 7694.499857509272, 7695.769126926201, 7696.384289256874, 7707.30634242918, 7584.4690871639, 7653.464257501977, 7653.693852245903, 7656.73201437947, 7658.463907526475, 7573.394128469709, 7643.847123057563, 7644.929709840627, 7646.694132234759, 7647.235276472364, 34664.480032799525, 34933.12667183315, 34952.300953980775, 34968.30063398718, 34986.0, 3444.431668774649, 3463.3592785334795, 3464.3937310970477, 3466.4107101792756, 3480.791152530831, 29709.554356684668, 29907.10278691621, 29916.40167196644, 29975.40049199004, 30048.248793780112, 4903.583195428393, 5104.387473503166, 5111.616628752821, 5124.615653825941, 5186.948130518685, 175538.0, 176071.0, 176589.0, 176641.0, 177414.0, 44058.77970610191, 44796.3280550792, 44807.55192448099, 45025.77487112609, 45089.77455112769, 46354.536454635694, 46527.0, 46583.534164658595, 46590.0, 46652.300215496114, 15550.0, 15620.453284135228, 15639.0, 15639.296231669603, 15770.211489425712, 12802.42389092493, 12849.807252891214, 12860.807087893689, 12866.356682165855, 12887.420066097047, 12998.805017924738, 13036.217826930415, 13044.0, 13121.934390328179, 13169.0, 5391.191321301836, 5475.06923822951, 5475.178723191553, 5481.260029895992, 5495.230667706524, 979.2802290316612, 983.8622592837008, 1001.324106228293, 1001.5442973447064, 1003.6537394598852, 20397.286094986604, 20461.89769051175, 20583.794162058486, 20684.276050338165, 20986.2654807081, 1072.6299426697776, 1078.7087486378634, 1102.3551222534907, 1109.040679811973, 1109.1514991031868, 10571.995660412249, 10572.312799667998, 10674.62638807638, 10903.29128606638, 11075.058620017227, 220446.0, 221134.0, 221459.0, 221544.0, 221811.0, 41784.0, 42718.0, 46967.0, 47499.525004750205, 49568.50431495711, 1376.2636989210723, 1411.181514721459, 1415.9150450972975, 1424.551266351096, 1437.8777803886787, 25766.742332577185, 26016.34959126037, 26087.217383478524, 26150.0, 26583.601245981714, 47898.76050619724, 48176.0, 48439.0, 48440.27339589979, 48450.27324590205, 201977.0, 204036.0, 204376.0, 204430.0, 205346.0, 33841.66158338385, 34016.0, 34122.0, 34133.31733365269, 34223.31553368968, 40828.79585602053, 40980.3852942212, 44085.33871991987, 45108.77445612882, 45239.32141017953, 32156.56868626331, 32258.0, 32292.35415291728, 32338.0, 32567.837160814044, 31926.36147276995, 32136.678633214306, 32443.675563244065, 32459.675403245667, 33007.50488742719, 36706.26587468289, 36821.079473013386, 36836.447453287685, 37138.44292335563, 37850.24299513939, 32026.19934501656, 32362.352752944338, 32410.189745256557, 32437.351252974335, 32541.0, 23830.16594419187, 23905.880470597534, 24131.034758609458, 24383.0, 24858.875705621354], "value": 16673.035619061295, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.4050895287567, 554.4206304411889, 570.7060863655215, 574.1273264637749, 574.6322353693636, 20985.160593576256, 21605.567888642236, 21618.89190554045, 21639.675404868918, 22546.774532254673, 24196.0, 24883.253502394913, 24950.500989980188, 25014.74985250147, 25017.0, 6423.197100362455, 6532.11816404784, 6533.771318003871, 6537.738490460376, 6558.672066396685, 6105.725242364082, 6331.936680633181, 6365.236171659408, 6468.159139311882, 6513.804585862432, 6321.494280457576, 6329.715162817662, 6334.144890439778, 6342.746290148389, 6347.0, 6331.936680633204, 6347.619142851444, 6348.619082855043, 6349.0476428536085, 6351.809445716636, 5877.588568800185, 5881.85295367615, 5893.0, 5898.203742494794, 5901.498372638331, 28245.435091298263, 28304.0, 28774.568381474295, 28818.135455936357, 28865.0, 2041.8468614853796, 2092.843036772233, 2094.183268525276, 2094.3821572635957, 2095.6961240620026, 18676.159572819255, 19243.0, 19297.614047719107, 19299.90350048255, 19311.420657380302, 2383.6305372667266, 2410.301012706313, 2424.818138639609, 2429.5383877063477, 2492.6011838105815, 130261.0, 134574.0, 135062.0, 135077.32461337728, 135495.32252338773, 14216.21810800416, 14748.115113093249, 14840.332185051699, 14877.330520126403, 14969.326380312912, 22192.0, 22196.223132190295, 22242.555148896932, 22244.888775556017, 22341.0, 9727.902720972841, 9902.55438505269, 9922.553485093189, 9944.204463642891, 10010.199184065257, 7990.12108668046, 8999.685011024583, 9026.503542305236, 9107.726768196964, 9310.581023853943, 7961.761147165595, 8856.778580535538, 8858.11418858122, 9022.097790220925, 9032.36773425854, 2596.896124155013, 2605.4398304364654, 2628.250948479674, 2663.480621278871, 2734.8495832728995, 924.611663101499, 943.4527973775193, 958.3148049144895, 961.077365728894, 965.0205041882494, 19074.33239836599, 19200.71198932017, 19557.41327760141, 19640.312589059315, 20056.498587535138, 993.1111655068673, 999.5502024089044, 1007.7631756537207, 1014.2342531388902, 1019.454591793387, 10499.842502362471, 10579.465977433383, 10612.575496980035, 10676.62631807883, 11007.669769906915, 441360.0, 441712.0, 447546.0, 450851.0, 461473.0, 105467.47266263772, 109591.0, 111373.44313278382, 111515.0, 111533.44233278943, 1103.569607852938, 1130.9547618095185, 1148.4831825678477, 1149.4712432281078, 1152.0438036429912, 16709.74935375946, 16912.154392280583, 17226.569335766708, 17355.3925612608, 17567.209475573887, 54787.0, 55021.44978550323, 55030.72484637551, 55871.441285586625, 56724.432755671914, 66732.0, 66944.0, 66990.0, 68829.31170688229, 70498.0, 11871.703207419883, 12317.014638829052, 13946.302684865514, 14320.212388318743, 20985.370438886857, 23587.528249435258, 23637.52724945457, 24024.0, 24378.51242975166, 24384.390390240387, 17747.378841740476, 18164.545886352946, 18839.811601883805, 18943.90528047351, 18962.0, 16991.235394407035, 17419.041952692725, 17458.47624571265, 17618.207180677156, 17650.205740741952, 19540.413787586396, 19659.508512287306, 19766.047536831862, 19881.105350259564, 20034.59930801405, 20628.381148565568, 20682.896585516977, 21682.566348673252, 21684.34946951594, 21713.891430542746, 18406.723899141256, 18866.05669716536, 19002.809971900104, 19027.619447610694, 19029.524261893563], "value": 11875.03024933589, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.8310219671441, 1357.9185248885074, 1361.1628848258317, 1361.5166615851372, 1367.1387026173513, 14353.928230358846, 14948.925255373708, 15017.0, 15030.398784048624, 15194.620134496643, 14980.325885335158, 15289.694206115872, 15323.387064517434, 15336.0, 15344.693106137898, 2711.064682684471, 2711.281510399736, 2743.231895069384, 2743.2867454461884, 2743.327884668249, 2763.5025695374825, 2787.4425114976984, 2787.456445993032, 2789.623400840891, 2789.9442011159763, 2764.530029894923, 2765.612814206003, 2779.4857951278977, 2782.916512504628, 2787.1359878437747, 2571.935701607456, 2613.398918248804, 2614.816962812604, 2614.9346266343496, 2625.5011547805857, 2584.1988983415204, 2593.364625666705, 2597.6493173421536, 2610.0, 2613.2290974162665, 14268.57194284173, 14571.19858407787, 14601.269936503133, 14612.780808287884, 14625.268736563236, 7351.77944661661, 7556.47104702671, 7582.052243469567, 7620.61896905158, 7624.961875190588, 5235.6073294502685, 5359.0, 5464.672119672832, 5501.697406642633, 5508.476694714036, 1917.7890432052463, 1940.2045161483866, 1941.7184508246226, 1943.7375954246138, 1974.1116497576004, 24770.38074048145, 24813.875930620412, 24818.37954051145, 24857.627135592982, 24861.254162375157, 6524.0, 6607.702653380609, 6644.700988455531, 6809.693563789641, 6818.352256535622, 16430.917845410695, 16455.25951332193, 16584.253708583143, 16616.50150495487, 16621.584460388585, 7322.96338518304, 7413.073365829271, 7473.476856619985, 7476.364509016686, 7478.775636730907, 5707.68607726579, 5996.16053752475, 6080.817575472743, 6942.930570694229, 6960.338767817049, 5104.69371837691, 5151.356080489939, 5207.739613019334, 5226.660267082628, 5343.198520221998, 1477.3647331647494, 1540.3607502886177, 1546.8917175797585, 1550.58909389012, 1554.945576904803, 861.1603686405705, 871.1680345270252, 878.0210065776613, 880.2517859819103, 888.0276097673167, 7072.8939065914055, 7094.787156385213, 7125.857482850314, 7164.426845852319, 7175.964120179366, 698.1238545625208, 698.4586945117474, 708.7554793596202, 710.8542748736496, 715.3025799845109, 4490.39379683745, 4809.254565542346, 4814.9518504815205, 4866.075445665312, 5047.823326183566, 19782.90108549448, 20920.058597363157, 21222.681659775117, 21378.14487420517, 21688.457788555414, 20312.898435507726, 20663.69004464904, 20669.0, 20805.271815486387, 20847.478813029797, 10709.625163119254, 11179.832302515631, 11207.215494915441, 11270.887291127148, 11374.31754094757, 7458.644816700869, 7663.31030207284, 7693.0, 7730.265624765639, 7749.496282741604, 17453.127343633023, 17536.47390578285, 17700.026498542702, 17858.285668573113, 18139.365122220657, 18315.402519304218, 18540.727142115997, 18605.0, 18991.9554424508, 19064.523386915436, 9967.950160249442, 10615.787684246117, 10700.83948740754, 10715.571377144826, 10728.141748659933, 12414.62756117318, 12511.749765004466, 12520.123591348518, 12531.18547294423, 12538.122331437076, 10226.07965283128, 10345.017223363768, 10393.740156496147, 10399.844002340122, 10555.736106597396, 9431.952840235756, 9593.088656577615, 9596.424214547149, 9718.465484398426, 10203.897961020592, 8472.152784721726, 8874.556272186235, 8961.0, 8963.103689630985, 8967.955160224157, 6425.935740642721, 6480.611163330215, 6542.640154791342, 6544.051112588743, 6560.245571759308, 6380.712867921047, 6470.22357317125, 6480.546361754632, 6501.1548498694865, 6542.247641521286], "value": 6322.079063083246, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.5039173423655, 503.52668491617874, 518.4193703052582, 520.2820108250613, 520.7578476008655, 6439.195100612424, 6451.225852897653, 6461.3538646135485, 6486.156799616043, 6521.413072823445, 6149.385061493848, 6255.562110652256, 6286.685665716707, 6323.20959880015, 6338.714757835897, 4998.300237966688, 5074.847754567369, 5114.258432527291, 5120.718360490171, 5154.974225128869, 5071.746412679369, 5089.821856235032, 5096.388433387999, 5136.794528218867, 5138.768755405997, 4874.878128046792, 4983.775730092137, 5016.949830501685, 5025.522575355335, 5037.546620804127, 4965.428975667808, 4981.0, 5030.874228144289, 5046.5710414614805, 5071.7320669832725, 4430.534793846664, 4465.955340446587, 4469.307257375112, 4491.932621010687, 4495.707778994388, 6227.626342419469, 6231.626102433868, 6236.220472440945, 6242.906356404657, 6254.249490061176, 5714.285714285715, 5725.370209276976, 5749.453801888814, 5769.8557536061935, 5776.393478684719, 6590.0, 6625.73497060117, 6679.799606011828, 6740.494462915301, 6747.291534388916, 4780.617550595944, 4814.15752243356, 4827.468978412372, 4835.371401717789, 4836.951630483721, 6924.446044316443, 6949.44404447643, 6981.3367730065565, 7008.4957521239385, 7054.6825392857445, 4990.3762029746285, 5018.42288136869, 5054.115529782273, 5074.974625126924, 5114.71869047206, 5018.544622059635, 5085.74571271435, 5102.413222479462, 5124.846254612367, 5144.845654630367, 5629.268195134607, 5746.224259724967, 5755.733738577506, 5759.654420734685, 5786.392428794916, 6612.537122401482, 6714.529982901247, 6734.663266836638, 6747.0, 6771.187457505131, 1645.4569991902642, 1720.9913950430168, 1721.3458885623384, 1730.731736580832, 1741.5907261793468, 1177.3701069927547, 1207.5954555224098, 1222.584321330752, 1232.9321887296285, 1237.2576454127452, 5813.505852002627, 5872.7357268923, 5875.941240587625, 5933.9352543506975, 5996.190514280604, 6600.80197594073, 6637.601743895381, 6657.966710166515, 6714.932850671431, 6773.593584384953, 5857.121431785266, 5886.970565147147, 5891.646501209942, 5900.76396944126, 5906.527477801766, 3523.612402635708, 3590.605033446319, 3613.8915832525067, 3640.4175331946763, 3647.9270414592092, 6458.61248325102, 6517.152770139852, 6537.411632953057, 6626.734930602818, 6692.397684208445, 6887.965560172267, 6992.825179370455, 7050.0482434871665, 7050.365467107882, 7073.929260707327, 6519.4132528072705, 6524.902126468107, 6609.173853268341, 6627.105340779126, 6632.204135503771, 6735.158105236846, 6740.39336459721, 6763.93236067633, 6770.966145169243, 6836.213835408793, 6577.408033277028, 6628.171478565179, 6642.701078451385, 6671.2981242596825, 6731.562448440835, 6363.172787537591, 6432.2281326241155, 6433.710483028369, 6559.392948727402, 6567.573107747981, 5014.272930425141, 5026.145555255688, 5034.899302014012, 5056.292119103402, 5056.570191533614, 6646.335366463315, 6785.355391238022, 6787.457003439812, 6930.48021398387, 6936.306369363023, 6498.935010649833, 6518.543701940818, 6530.510211734044, 6580.374864387875, 6624.0, 6485.77299794505, 6488.610683359014, 6552.180977377828, 6630.436412904764, 6658.467322614276, 6003.8198854034445, 6472.190976127984, 6481.222253329631, 6500.252470965899, 6615.37153970372, 6495.350464953467, 6629.701663425254, 6648.235452922782, 6689.09697190873, 6696.19645642526, 6323.028176756917, 6357.682115894279, 6430.2605200400685, 6488.740450382121, 6546.18172728409], "value": 5101.632831875501, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [875.1161327059668, 890.7594949363672, 891.2825175733534, 898.4519443139687, 904.8371293167231, 17035.829641703578, 18075.638487230248, 18084.457466276, 18101.72847407287, 18141.909290453546, 17850.553736156602, 18428.72356914644, 19077.618447631037, 19144.90427547864, 19253.711194332096, 910.1990248581255, 942.5522876633579, 948.8292107420662, 949.5157469690473, 951.2580187453763, 906.9591868365907, 936.9437833729964, 937.6858752317981, 947.8672985781961, 953.1516949914547, 963.9084286992725, 964.0407794244736, 964.1948972607881, 964.3587014635294, 986.9555869985832, 963.8795150606175, 964.6430820596354, 964.8938616752151, 965.3435663748648, 989.223459584223, 709.6345382128208, 709.9503034787567, 710.350029722803, 716.6380977606327, 728.6903066196899, 16674.082925439096, 16721.749173762404, 16724.498265051945, 16733.414330498374, 18868.528286792804, 20473.0, 20629.4842628934, 20863.0, 21033.0, 21062.684059739116, 16849.747253791204, 16928.238229279712, 17052.744208836753, 17081.402150924783, 17113.22990465432, 3694.3719567673293, 3730.365837807552, 3730.9626903730887, 3732.234891847165, 3732.496113024761, 125926.37036814849, 126768.0, 128249.35875320564, 129198.3540082303, 130535.0, 15039.924800375928, 15325.31036103378, 15475.84524154744, 15518.37926482928, 15617.68764624724, 29513.557296640567, 29535.409291814474, 30226.395472090437, 30257.09228723142, 30269.0, 8647.524386158695, 8830.205281524693, 8860.424072435271, 8862.556872156367, 8877.556122193864, 3658.4695219193068, 3962.465067215889, 4130.938035929464, 4133.297339452301, 4133.42132101506, 4358.978205108954, 5774.133879917962, 5816.854578635568, 5819.88360232793, 5826.388229235954, 1079.0, 1097.6871591596516, 1109.9556017759198, 1110.1451882050937, 1122.848415463902, 651.2900937977627, 670.2794495916909, 671.3454381977631, 684.9589024658437, 694.4097517110516, 11003.284786488852, 11054.15667466024, 11326.773464530663, 11583.942080289544, 12091.730368311377, 667.1427216027381, 679.3037136934582, 683.9794806155824, 696.8257935516122, 707.1266985273184, 4517.141743068806, 4781.019890922353, 5639.379668236491, 5857.589968702235, 5956.873315363899, 107292.46353768182, 107905.0, 109688.0, 111376.4431177839, 113468.0, 92419.0, 94101.0, 95520.0, 98256.50871745739, 99709.50145249227, 78869.60565197137, 85754.0, 86273.56863215644, 87982.56008719915, 90992.09007909968, 11309.547618095185, 11358.0, 11438.42807859587, 11457.656270311903, 12081.154319197572, 38560.80719596384, 39648.80175599104, 39825.2034959305, 40582.1883562333, 41481.79259103685, 75396.0, 75446.73659790216, 75537.62231189029, 75909.24090759242, 77375.61312193403, 17057.573560661083, 17978.0, 18016.00911949803, 18113.909430452764, 18173.818261817214, 18667.813321866608, 19053.42839714811, 19115.904420477807, 19128.617427651647, 19155.616887662447, 15051.623709407353, 15176.24118794078, 15215.239238038275, 15264.313105910484, 18135.0, 16289.67420651604, 16292.859499834898, 16500.587485312964, 16580.917095414447, 16793.0, 17452.73820892662, 17622.559436014202, 17694.469165925042, 18003.72994405059, 18007.909960450637, 11425.485853136463, 11436.256643318158, 11625.0, 12551.0, 13615.591532254048, 13456.394462249027, 13659.38532766008, 13888.861111388756, 13904.165750055028, 14256.786148207993], "value": 8846.830346933646, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1480.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 115.0, 100.0, 106.0, 106.0, 109.0, 109.0, 99.0, 105.0, 109.0], "value": 105.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 145.0, 171.0, 119.0, 141.0, 142.0, 122.0, 169.0, 121.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 163.0, 162.0, 162.0, 159.0, 164.0, 167.0, 169.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 161.0, 75.0, 74.0, 73.0, 74.0, 168.0, 73.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 130.0, 78.0, 79.0, 80.0, 128.0, 78.0, 80.0, 126.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 150.0, 147.0, 149.0, 148.0, 148.0, 148.0, 149.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 53.0, 53.0, 52.0, 53.0, 53.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 38.0, 38.0, 37.0, 37.0, 38.0, 38.0, 37.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 75.0, 74.0, 75.0, 75.0, 74.0, 73.0, 74.0, 74.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 131.0, 124.0, 123.0, 122.0, 118.0, 127.0, 121.0, 155.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 180.0, 185.0, 169.0, 167.0, 168.0, 169.0, 171.0, 170.0, 172.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 68.0, 66.0, 64.0, 65.0, 64.0, 67.0, 69.0, 64.0], "value": 65.9, "unit": "score"}]}]} 18:37:05 INFO - Return code: 0 18:37:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:37:05 INFO - # TBPL SUCCESS # 18:37:05 INFO - Running post-action listener: _package_coverage_data 18:37:05 INFO - Running post-action listener: _resource_record_post_action 18:37:05 INFO - [mozharness: 2017-08-28 01:37:05.312308Z] Finished run-tests step (success) 18:37:05 INFO - Running post-run listener: _resource_record_post_run 18:37:05 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 430137344; Read time: 8; Write time: 266036 18:37:05 INFO - TinderboxPrint: CPU usage
13.1% 18:37:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:37:05 INFO - TinderboxPrint: I/O write bytes / time
430,137,344 / 266,036 18:37:05 INFO - TinderboxPrint: CPU idle
8,250.9 (86.9%) 18:37:05 INFO - TinderboxPrint: CPU user
1,196.5 (12.6%) 18:37:05 INFO - TinderboxPrint: Swap in / out
0 / 0 18:37:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 104660992; Read time: 0; Write time: 84364 18:37:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:37:05 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 325054464; Read time: 0; Write time: 181652 18:37:05 INFO - Running post-run listener: _upload_blobber_files 18:37:05 INFO - Blob upload gear active. 18:37:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:37:05 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 18:37:05 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'] 18:37:05 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 18:37:06 INFO - (blobuploader) - INFO - Open directory for files ... 18:37:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:37:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:37:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:07 INFO - (blobuploader) - INFO - Done attempting. 18:37:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:37:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:37:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:14 INFO - (blobuploader) - INFO - Done attempting. 18:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:15 INFO - (blobuploader) - INFO - Done attempting. 18:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:37:16 INFO - (blobuploader) - INFO - Done attempting. 18:37:16 INFO - (blobuploader) - INFO - Iteration through files over. 18:37:16 INFO - Return code: 0 18:37:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:37:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:37:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79254eeee44adec5f9a6e7a5c3318c934c47ad3e07f26316e6d26f335331b7ea37c46207f43249fcb635a540adc9d112e1f4e7538f897b7dd56728320ef89572", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/864f9c7d3d28abce484b83ca2da11d0c0b2fa7f6ea67fa208045bed2ffdbcaeefab49084f441a45f2a52fd3f301aaa193d4566ad2bd827df82b291fc1f5c4b45", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87748bfdbb0d2ba34084a0b40e3838edd039188b297da49e02f5398b3dd30a91385d7767c8f11aaf0f7624f63346c5f8cd57f5c8b12717297917400a25865016", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79dc2c53e2aa7f63cbc58cc716359bd914f89c6901269ea21509480acfb341981d153f206d2a8e5b4bf3d4bd05fa284f7711e99e3f014a909ba6c27b5fb0ba1e"} 18:37:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:37:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:37:16 INFO - Contents: 18:37:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79254eeee44adec5f9a6e7a5c3318c934c47ad3e07f26316e6d26f335331b7ea37c46207f43249fcb635a540adc9d112e1f4e7538f897b7dd56728320ef89572", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/864f9c7d3d28abce484b83ca2da11d0c0b2fa7f6ea67fa208045bed2ffdbcaeefab49084f441a45f2a52fd3f301aaa193d4566ad2bd827df82b291fc1f5c4b45", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87748bfdbb0d2ba34084a0b40e3838edd039188b297da49e02f5398b3dd30a91385d7767c8f11aaf0f7624f63346c5f8cd57f5c8b12717297917400a25865016", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79dc2c53e2aa7f63cbc58cc716359bd914f89c6901269ea21509480acfb341981d153f206d2a8e5b4bf3d4bd05fa284f7711e99e3f014a909ba6c27b5fb0ba1e"} 18:37:16 INFO - Running post-run listener: copy_logs_to_upload_dir 18:37:16 INFO - Copying logs to upload dir... 18:37:16 INFO - mkdir: /builds/slave/test/build/upload/logs 18:37:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.658601 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-27 18:37:16.765813) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:16.769647) ========= 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=4300f881736b422476174874000001bc-1503875775.720873-793745818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79254eeee44adec5f9a6e7a5c3318c934c47ad3e07f26316e6d26f335331b7ea37c46207f43249fcb635a540adc9d112e1f4e7538f897b7dd56728320ef89572", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/864f9c7d3d28abce484b83ca2da11d0c0b2fa7f6ea67fa208045bed2ffdbcaeefab49084f441a45f2a52fd3f301aaa193d4566ad2bd827df82b291fc1f5c4b45", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87748bfdbb0d2ba34084a0b40e3838edd039188b297da49e02f5398b3dd30a91385d7767c8f11aaf0f7624f63346c5f8cd57f5c8b12717297917400a25865016", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79dc2c53e2aa7f63cbc58cc716359bd914f89c6901269ea21509480acfb341981d153f206d2a8e5b4bf3d4bd05fa284f7711e99e3f014a909ba6c27b5fb0ba1e"} build_url:https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/fJ4-8OdcRqSrwqD2wKF_lQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79254eeee44adec5f9a6e7a5c3318c934c47ad3e07f26316e6d26f335331b7ea37c46207f43249fcb635a540adc9d112e1f4e7538f897b7dd56728320ef89572", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/864f9c7d3d28abce484b83ca2da11d0c0b2fa7f6ea67fa208045bed2ffdbcaeefab49084f441a45f2a52fd3f301aaa193d4566ad2bd827df82b291fc1f5c4b45", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87748bfdbb0d2ba34084a0b40e3838edd039188b297da49e02f5398b3dd30a91385d7767c8f11aaf0f7624f63346c5f8cd57f5c8b12717297917400a25865016", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/79dc2c53e2aa7f63cbc58cc716359bd914f89c6901269ea21509480acfb341981d153f206d2a8e5b4bf3d4bd05fa284f7711e99e3f014a909ba6c27b5fb0ba1e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:16.797538) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:16.797861) ========= 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=4300f881736b422476174874000001bc-1503875775.720873-793745818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:37:16.882099) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:37:16.882419) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 18:37:16.882720) ========= ========= Total master_lag: 0.14 =========