builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-051 starttime: 1506991764.83 results: success (0) revision: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:24.827063) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:24.833567) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:24.833866) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:24.878323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:24.881745) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 17:49:24-- 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% 224M=0s 2017-10-02 17:49:25 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135659 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.038050) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.038366) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020252 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.096731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.097047) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65dac33a5682f3ec5a675e7f3314b0c1520a13fa --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 65dac33a5682f3ec5a675e7f3314b0c1520a13fa --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=5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 17:49:25,139 truncating revision to first 12 chars 2017-10-02 17:49:25,139 Setting DEBUG logging. 2017-10-02 17:49:25,139 attempt 1/10 2017-10-02 17:49:25,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65dac33a5682?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 17:49:25,409 unpacking tar archive at: mozilla-central-65dac33a5682/testing/mozharness/ program finished with exit code 0 elapsedTime=0.564848 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.678132) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.678414) ========= script_repo_revision: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.681777) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.682077) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 17:49:25.696065) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 17:49:25.696340) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:49:25 INFO - MultiFileLogger online at 20171002 17:49:25 in /builds/slave/test 17:49:25 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 17:49:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:49:25 INFO - {'append_to_log': False, 17:49:25 INFO - 'base_work_dir': '/builds/slave/test', 17:49:25 INFO - 'blob_upload_branch': 'Firefox', 17:49:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:49:25 INFO - 'branch': 'Firefox', 17:49:25 INFO - 'buildbot_json_path': 'buildprops.json', 17:49:25 INFO - 'code_coverage': False, 17:49:25 INFO - 'config_files': ('talos/linux_config.py',), 17:49:25 INFO - 'default_actions': ('clobber', 17:49:25 INFO - 'read-buildbot-config', 17:49:25 INFO - 'download-and-extract', 17:49:25 INFO - 'populate-webroot', 17:49:25 INFO - 'create-virtualenv', 17:49:25 INFO - 'install', 17:49:25 INFO - 'setup-mitmproxy', 17:49:25 INFO - 'run-tests'), 17:49:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:49:25 INFO - 'disable_ccov_upload': False, 17:49:25 INFO - 'disable_stylo': False, 17:49:25 INFO - 'download_minidump_stackwalk': True, 17:49:25 INFO - 'download_symbols': 'ondemand', 17:49:25 INFO - 'enable_stylo': False, 17:49:25 INFO - 'enable_webrender': False, 17:49:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:49:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:49:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:49:25 INFO - 'gecko_profile': False, 17:49:25 INFO - 'gecko_profile_interval': 0, 17:49:25 INFO - 'installer_path': 'installer.exe', 17:49:25 INFO - 'log_level': 'info', 17:49:25 INFO - 'log_name': 'talos', 17:49:25 INFO - 'log_to_console': True, 17:49:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:49:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:49:25 INFO - 'opt_config_files': (), 17:49:25 INFO - 'pip_index': False, 17:49:25 INFO - 'suite': 'dromaeojs-e10s', 17:49:25 INFO - 'system_bits': '32', 17:49:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:49:25 INFO - 'title': 'talos-linux64-ix-051', 17:49:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:49:25 INFO - 'use_talos_json': True, 17:49:25 INFO - 'verify': 'False', 17:49:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:49:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:49:25 INFO - 'work_dir': 'build'} 17:49:25 INFO - [mozharness: 2017-10-03 00:49:25.887311Z] Running clobber step. 17:49:25 INFO - Running pre-action listener: _resource_record_pre_action 17:49:25 INFO - Running main action method: clobber 17:49:25 INFO - rmtree: /builds/slave/test/build 17:49:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:49:27 INFO - Running post-action listener: _resource_record_post_action 17:49:27 INFO - [mozharness: 2017-10-03 00:49:27.397999Z] Finished clobber step (success) 17:49:27 INFO - [mozharness: 2017-10-03 00:49:27.398096Z] Running read-buildbot-config step. 17:49:27 INFO - Running pre-action listener: _resource_record_pre_action 17:49:27 INFO - Running main action method: read_buildbot_config 17:49:27 INFO - Using buildbot properties: 17:49:27 INFO - { 17:49:27 INFO - "project": "", 17:49:27 INFO - "product": "firefox", 17:49:27 INFO - "who": "kwierso@gmail.com", 17:49:27 INFO - "installer_path": "public/build/target.tar.bz2", 17:49:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:49:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:49:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 17:49:27 INFO - "stage_platform": "linux64-pgo", 17:49:27 INFO - "basedir": "/builds/slave/test", 17:49:27 INFO - "buildnumber": 227, 17:49:27 INFO - "platform": "ubuntu64_hw", 17:49:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:49:27 INFO - "slavebuilddir": "test", 17:49:27 INFO - "taskId": "GQoTqMHQSg2isvXppVluug", 17:49:27 INFO - "branch": "mozilla-central", 17:49:27 INFO - "script_repo_revision": "production", 17:49:27 INFO - "revision": "65dac33a5682f3ec5a675e7f3314b0c1520a13fa", 17:49:27 INFO - "slavename": "talos-linux64-ix-051", 17:49:27 INFO - "repo_path": "mozilla-central" 17:49:27 INFO - } 17:49:27 INFO - Finding installer, test and symbols from parent task. 17:49:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GQoTqMHQSg2isvXppVluug'}, attempt #1 17:49:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GQoTqMHQSg2isvXppVluug'}, attempt #1 17:49:28 INFO - Task dependencies: GvTuDUuVRuaZlRJDpMYY1Q 17:49:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q'}, attempt #1 17:49:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2 17:49:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.test_packages.json 17:49:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.crashreporter-symbols.zip 17:49:29 INFO - Running post-action listener: _resource_record_post_action 17:49:29 INFO - [mozharness: 2017-10-03 00:49:29.047344Z] Finished read-buildbot-config step (success) 17:49:29 INFO - [mozharness: 2017-10-03 00:49:29.047563Z] Running download-and-extract step. 17:49:29 INFO - Running pre-action listener: _resource_record_pre_action 17:49:29 INFO - Running main action method: download_and_extract 17:49:29 INFO - mkdir: /builds/slave/test/build/tests 17:49:29 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')]} 17:49:29 INFO - trying https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.test_packages.json 17:49:29 INFO - Downloading https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:49:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:49:29 INFO - Downloaded 989 bytes. 17:49:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:49:29 INFO - Using the following test package requirements: 17:49:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:49:29 INFO - u'common': [u'target.common.tests.zip'], 17:49:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:49:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:49:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:49:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:49:29 INFO - u'mozbase': [u'target.common.tests.zip'], 17:49:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:49:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:49:29 INFO - u'web-platform': [u'target.common.tests.zip', 17:49:29 INFO - u'target.web-platform.tests.tar.gz'], 17:49:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:49:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:49:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.common.tests.zip 17:49:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:49:29 INFO - Fetch https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.common.tests.zip into memory 17:49:31 INFO - Content-Length response header: 55903400 17:49:31 INFO - Bytes received: 55903400 17:49:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.talos.tests.zip 17:49:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:49:42 INFO - Fetch https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.talos.tests.zip into memory 17:49:44 INFO - Content-Length response header: 14522427 17:49:44 INFO - Bytes received: 14522427 17:49:44 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')]} 17:49:44 INFO - trying https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2 17:49:44 INFO - Downloading https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:49:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:49:47 INFO - Downloaded 62308381 bytes. 17:49:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2 17:49:47 INFO - mkdir: /builds/slave/test/properties 17:49:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:49:47 INFO - Writing to file /builds/slave/test/properties/build_url 17:49:47 INFO - Contents: 17:49:47 INFO - build_url:https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2 17:49:47 INFO - Running post-action listener: _resource_record_post_action 17:49:47 INFO - Running post-action listener: find_tests_for_verification 17:49:47 INFO - Running post-action listener: set_extra_try_arguments 17:49:47 INFO - [mozharness: 2017-10-03 00:49:47.614762Z] Finished download-and-extract step (success) 17:49:47 INFO - [mozharness: 2017-10-03 00:49:47.614929Z] Running populate-webroot step. 17:49:47 INFO - Running pre-action listener: _resource_record_pre_action 17:49:47 INFO - Running main action method: populate_webroot 17:49:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:49:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['tresize', 17:49:47 INFO - 'tcanvasmark']}, 17:49:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:49:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['dromaeo_css', 17:49:47 INFO - 'kraken']}, 17:49:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:49:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:49:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'tests': ['damp', 'tps']}, 17:49:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['damp', 'tps']}, 17:49:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:49:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['dromaeo_dom']}, 17:49:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:49:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['basic_compositor_video', 17:49:47 INFO - 'glvideo']}, 17:49:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:49:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['ts_paint_webext', 17:49:47 INFO - 'tp5o_webext']}, 17:49:47 INFO - 'other-e10s': {'tests': ['a11yr', 17:49:47 INFO - 'ts_paint', 17:49:47 INFO - 'tpaint', 17:49:47 INFO - 'sessionrestore', 17:49:47 INFO - 'sessionrestore_many_windows', 17:49:47 INFO - 'sessionrestore_no_auto_restore', 17:49:47 INFO - 'tabpaint', 17:49:47 INFO - 'cpstartup']}, 17:49:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['a11yr', 17:49:47 INFO - 'ts_paint', 17:49:47 INFO - 'tpaint', 17:49:47 INFO - 'sessionrestore', 17:49:47 INFO - 'sessionrestore_many_windows', 17:49:47 INFO - 'sessionrestore_no_auto_restore', 17:49:47 INFO - 'tabpaint', 17:49:47 INFO - 'cpstartup']}, 17:49:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:49:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:49:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['perf_reftest_singletons']}, 17:49:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['perf_reftest']}, 17:49:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:49:47 INFO - 'tsvgr_opacity', 17:49:47 INFO - 'tart', 17:49:47 INFO - 'tscrollx', 17:49:47 INFO - 'tsvg_static']}, 17:49:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['tsvgx', 17:49:47 INFO - 'tsvgr_opacity', 17:49:47 INFO - 'tart', 17:49:47 INFO - 'tscrollx', 17:49:47 INFO - 'tsvg_static']}, 17:49:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:49:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'talos_options': ['--disable-stylo'], 17:49:47 INFO - 'tests': ['tp5o']}, 17:49:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:49:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:49:47 INFO - 'talos_options': ['--mitmproxy', 17:49:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:49:47 INFO - '--firstNonBlankPaint'], 17:49:47 INFO - 'tests': ['tp6_google', 17:49:47 INFO - 'tp6_youtube', 17:49:47 INFO - 'tp6_amazon', 17:49:47 INFO - 'tp6_facebook']}, 17:49:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:49:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:49:47 INFO - 'talos_options': ['--disable-stylo', 17:49:47 INFO - '--mitmproxy', 17:49:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:49:47 INFO - '--firstNonBlankPaint'], 17:49:47 INFO - 'tests': ['tp6_google', 17:49:47 INFO - 'tp6_youtube', 17:49:47 INFO - 'tp6_amazon', 17:49:47 INFO - 'tp6_facebook']}, 17:49:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:49:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:49:47 INFO - 'talos_options': ['--stylo-threads=1', 17:49:47 INFO - '--mitmproxy', 17:49:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:49:47 INFO - '--firstNonBlankPaint'], 17:49:47 INFO - 'tests': ['tp6_google', 17:49:47 INFO - 'tp6_youtube', 17:49:47 INFO - 'tp6_amazon', 17:49:47 INFO - 'tp6_facebook']}, 17:49:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'talos_options': ['--xperf_path', 17:49:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:49:47 INFO - 'tests': ['tp5n']}, 17:49:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:49:47 INFO - 'talos_options': ['--disable-stylo', 17:49:47 INFO - '--xperf_path', 17:49:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:49:47 INFO - 'tests': ['tp5n']}}} 17:49:47 INFO - Running post-action listener: _resource_record_post_action 17:49:47 INFO - [mozharness: 2017-10-03 00:49:47.636268Z] Finished populate-webroot step (success) 17:49:47 INFO - [mozharness: 2017-10-03 00:49:47.636356Z] Running create-virtualenv step. 17:49:47 INFO - Running pre-action listener: _resource_record_pre_action 17:49:47 INFO - Running main action method: create_virtualenv 17:49:47 INFO - Creating virtualenv /builds/slave/test/build/venv 17:49:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:49:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:49:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:49:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:49:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:49:48 INFO - Installing distribute.............................................................................................................................................................................................done. 17:49:48 INFO - Installing pip...............done. 17:49:48 INFO - Return code: 0 17:49:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:49:48 INFO - Reading from file tmpfile_stdout 17:49:48 INFO - Output received: 17:49:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:49:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:49:48 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')]} 17:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:49:48 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')]} 17:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:49:48 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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:49:48 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 17:49:48 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 17:49:48 INFO - Using env: {'DISPLAY': ':0', 17:49:48 INFO - 'HOME': '/home/cltbld', 17:49:48 INFO - 'LANG': 'en_US.UTF-8', 17:49:48 INFO - 'LANGUAGE': 'en_US:en', 17:49:48 INFO - 'LOGNAME': 'cltbld', 17:49:48 INFO - 'MAIL': '/var/mail/cltbld', 17:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:48 INFO - 'MOZ_NO_REMOTE': '1', 17:49:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:48 INFO - 'NO_EM_RESTART': '1', 17:49:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:48 INFO - 'PWD': '/builds/slave/test', 17:49:48 INFO - 'SHELL': '/bin/bash', 17:49:48 INFO - 'SHLVL': '1', 17:49:48 INFO - 'TERM': 'linux', 17:49:48 INFO - 'TMOUT': '86400', 17:49:48 INFO - 'USER': 'cltbld', 17:49:48 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:48 INFO - '_': '/tools/buildbot/bin/python'} 17:49:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:49:49 INFO - Downloading/unpacking pip>=1.5 17:49:49 INFO - Running setup.py egg_info for package pip 17:49:49 INFO - warning: no files found matching 'pip/cacert.pem' 17:49:49 INFO - warning: no files found matching '*.html' under directory 'docs' 17:49:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:49:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:49:49 INFO - Installing collected packages: pip 17:49:49 INFO - Found existing installation: pip 1.1 17:49:49 INFO - Uninstalling pip: 17:49:49 INFO - Successfully uninstalled pip 17:49:49 INFO - Running setup.py install for pip 17:49:49 INFO - warning: no files found matching 'pip/cacert.pem' 17:49:49 INFO - warning: no files found matching '*.html' under directory 'docs' 17:49:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:49:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:49:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:49:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:49:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:49:49 INFO - Successfully installed pip 17:49:49 INFO - Cleaning up... 17:49:49 INFO - Return code: 0 17:49:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:49:49 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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:49:49 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 17:49:49 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 17:49:49 INFO - Using env: {'DISPLAY': ':0', 17:49:49 INFO - 'HOME': '/home/cltbld', 17:49:49 INFO - 'LANG': 'en_US.UTF-8', 17:49:49 INFO - 'LANGUAGE': 'en_US:en', 17:49:49 INFO - 'LOGNAME': 'cltbld', 17:49:49 INFO - 'MAIL': '/var/mail/cltbld', 17:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:49 INFO - 'MOZ_NO_REMOTE': '1', 17:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:49 INFO - 'NO_EM_RESTART': '1', 17:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:49 INFO - 'PWD': '/builds/slave/test', 17:49:49 INFO - 'SHELL': '/bin/bash', 17:49:49 INFO - 'SHLVL': '1', 17:49:49 INFO - 'TERM': 'linux', 17:49:49 INFO - 'TMOUT': '86400', 17:49:49 INFO - 'USER': 'cltbld', 17:49:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:49 INFO - '_': '/tools/buildbot/bin/python'} 17:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:49:50 INFO - Downloading/unpacking psutil>=3.1.1 17:49:50 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 17:49:50 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 17:49:50 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 17:49:50 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 17:49:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:49:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:49:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:49:51 INFO - Installing collected packages: psutil 17:49:51 INFO - Running setup.py install for psutil 17:49:51 INFO - building 'psutil._psutil_linux' extension 17:49:51 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 17:49:51 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 17:49:51 INFO - building 'psutil._psutil_posix' extension 17:49:51 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 17:49:51 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 17:49:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:49:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:49:51 INFO - Successfully installed psutil 17:49:51 INFO - Cleaning up... 17:49:51 INFO - Return code: 0 17:49:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:49:51 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')]} 17:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:49:51 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')]} 17:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:49:51 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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:49:51 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 17:49:51 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 17:49:51 INFO - Using env: {'DISPLAY': ':0', 17:49:51 INFO - 'HOME': '/home/cltbld', 17:49:51 INFO - 'LANG': 'en_US.UTF-8', 17:49:51 INFO - 'LANGUAGE': 'en_US:en', 17:49:51 INFO - 'LOGNAME': 'cltbld', 17:49:51 INFO - 'MAIL': '/var/mail/cltbld', 17:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:51 INFO - 'MOZ_NO_REMOTE': '1', 17:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:51 INFO - 'NO_EM_RESTART': '1', 17:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:51 INFO - 'PWD': '/builds/slave/test', 17:49:51 INFO - 'SHELL': '/bin/bash', 17:49:51 INFO - 'SHLVL': '1', 17:49:51 INFO - 'TERM': 'linux', 17:49:51 INFO - 'TMOUT': '86400', 17:49:51 INFO - 'USER': 'cltbld', 17:49:51 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:51 INFO - '_': '/tools/buildbot/bin/python'} 17:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:49:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:49:52 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 17:49:52 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 17:49:52 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 17:49:52 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 17:49:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:49:53 INFO - Installing collected packages: mozsystemmonitor 17:49:53 INFO - Running setup.py install for mozsystemmonitor 17:49:53 INFO - Successfully installed mozsystemmonitor 17:49:53 INFO - Cleaning up... 17:49:53 INFO - Return code: 0 17:49:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:49: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')]} 17:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:49: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')]} 17:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:49:53 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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:49:53 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 17:49:53 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 17:49:53 INFO - Using env: {'DISPLAY': ':0', 17:49:53 INFO - 'HOME': '/home/cltbld', 17:49:53 INFO - 'LANG': 'en_US.UTF-8', 17:49:53 INFO - 'LANGUAGE': 'en_US:en', 17:49:53 INFO - 'LOGNAME': 'cltbld', 17:49:53 INFO - 'MAIL': '/var/mail/cltbld', 17:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:53 INFO - 'MOZ_NO_REMOTE': '1', 17:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:53 INFO - 'NO_EM_RESTART': '1', 17:49:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:53 INFO - 'PWD': '/builds/slave/test', 17:49:53 INFO - 'SHELL': '/bin/bash', 17:49:53 INFO - 'SHLVL': '1', 17:49:53 INFO - 'TERM': 'linux', 17:49:53 INFO - 'TMOUT': '86400', 17:49:53 INFO - 'USER': 'cltbld', 17:49:53 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:53 INFO - '_': '/tools/buildbot/bin/python'} 17:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:49:53 INFO - Downloading/unpacking jsonschema==2.5.1 17:49:53 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 17:49:53 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 17:49:53 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 17:49:53 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 17:49:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:49:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:49:55 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 17:49:55 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 17:49:55 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 17:49:55 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 17:49:55 INFO - Downloading functools32-3.2.3-2.tar.gz 17:49:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:49:55 INFO - warning: no files found matching '*.txt' 17:49:55 INFO - no previously-included directories found matching 'build' 17:49:55 INFO - no previously-included directories found matching 'dist' 17:49:55 INFO - no previously-included directories found matching '.git*' 17:49:55 INFO - Installing collected packages: jsonschema, functools32 17:49:55 INFO - Running setup.py install for functools32 17:49:55 INFO - warning: no files found matching '*.txt' 17:49:55 INFO - no previously-included directories found matching 'build' 17:49:55 INFO - no previously-included directories found matching 'dist' 17:49:55 INFO - no previously-included directories found matching '.git*' 17:49:55 INFO - Successfully installed jsonschema functools32 17:49:55 INFO - Cleaning up... 17:49:55 INFO - Return code: 0 17:49:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:49: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')]} 17:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:49: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')]} 17:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:49: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', '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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:49: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:49: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 functools32==3.2.3-2 17:49:55 INFO - Using env: {'DISPLAY': ':0', 17:49:55 INFO - 'HOME': '/home/cltbld', 17:49:55 INFO - 'LANG': 'en_US.UTF-8', 17:49:55 INFO - 'LANGUAGE': 'en_US:en', 17:49:55 INFO - 'LOGNAME': 'cltbld', 17:49:55 INFO - 'MAIL': '/var/mail/cltbld', 17:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:55 INFO - 'MOZ_NO_REMOTE': '1', 17:49:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:55 INFO - 'NO_EM_RESTART': '1', 17:49:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:55 INFO - 'PWD': '/builds/slave/test', 17:49:55 INFO - 'SHELL': '/bin/bash', 17:49:55 INFO - 'SHLVL': '1', 17:49:55 INFO - 'TERM': 'linux', 17:49:55 INFO - 'TMOUT': '86400', 17:49:55 INFO - 'USER': 'cltbld', 17:49:55 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:55 INFO - '_': '/tools/buildbot/bin/python'} 17:49:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:49:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:49:55 INFO - Cleaning up... 17:49:55 INFO - Return code: 0 17:49:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:49: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')]} 17:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:49: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')]} 17:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:49: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', '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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:49: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:49: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 blobuploader==1.2.4 17:49:55 INFO - Using env: {'DISPLAY': ':0', 17:49:55 INFO - 'HOME': '/home/cltbld', 17:49:55 INFO - 'LANG': 'en_US.UTF-8', 17:49:55 INFO - 'LANGUAGE': 'en_US:en', 17:49:55 INFO - 'LOGNAME': 'cltbld', 17:49:55 INFO - 'MAIL': '/var/mail/cltbld', 17:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:55 INFO - 'MOZ_NO_REMOTE': '1', 17:49:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:55 INFO - 'NO_EM_RESTART': '1', 17:49:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:55 INFO - 'PWD': '/builds/slave/test', 17:49:55 INFO - 'SHELL': '/bin/bash', 17:49:55 INFO - 'SHLVL': '1', 17:49:55 INFO - 'TERM': 'linux', 17:49:55 INFO - 'TMOUT': '86400', 17:49:55 INFO - 'USER': 'cltbld', 17:49:55 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:55 INFO - '_': '/tools/buildbot/bin/python'} 17:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:49:56 INFO - Downloading/unpacking blobuploader==1.2.4 17:49: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 17:49: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 17:49: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 17:49: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 17:49:57 INFO - Downloading blobuploader-1.2.4.tar.gz 17:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:49:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:49:57 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 17:49:57 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 17:49:57 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 17:49:57 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 17:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:49:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:49:57 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 17:49:57 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 17:49:57 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 17:49:57 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 17:49:57 INFO - Downloading docopt-0.6.1.tar.gz 17:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:49:57 INFO - Installing collected packages: blobuploader, requests, docopt 17:49:57 INFO - Running setup.py install for blobuploader 17:49:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:49:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:49:57 INFO - Running setup.py install for requests 17:49:58 INFO - Running setup.py install for docopt 17:49:58 INFO - Successfully installed blobuploader requests docopt 17:49:58 INFO - Cleaning up... 17:49:58 INFO - Return code: 0 17:49:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:49: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')]} 17:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:49: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')]} 17:49:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:49:58 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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:49:58 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 17:49:58 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 17:49:58 INFO - Using env: {'DISPLAY': ':0', 17:49:58 INFO - 'HOME': '/home/cltbld', 17:49:58 INFO - 'LANG': 'en_US.UTF-8', 17:49:58 INFO - 'LANGUAGE': 'en_US:en', 17:49:58 INFO - 'LOGNAME': 'cltbld', 17:49:58 INFO - 'MAIL': '/var/mail/cltbld', 17:49:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:49:58 INFO - 'MOZ_NO_REMOTE': '1', 17:49:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:49:58 INFO - 'NO_EM_RESTART': '1', 17:49:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:49:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:49:58 INFO - 'PWD': '/builds/slave/test', 17:49:58 INFO - 'SHELL': '/bin/bash', 17:49:58 INFO - 'SHLVL': '1', 17:49:58 INFO - 'TERM': 'linux', 17:49:58 INFO - 'TMOUT': '86400', 17:49:58 INFO - 'USER': 'cltbld', 17:49:58 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:49:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:49:58 INFO - '_': '/tools/buildbot/bin/python'} 17:49:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:49:58 INFO - Running setup.py (path:/tmp/pip-12q9Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:49:58 INFO - Running setup.py (path:/tmp/pip-hPZzdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:49:58 INFO - Running setup.py (path:/tmp/pip-mbdjbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:49:58 INFO - Running setup.py (path:/tmp/pip-RMBqBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:49:58 INFO - Running setup.py (path:/tmp/pip-i7i3mi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:49:58 INFO - Running setup.py (path:/tmp/pip-BCGM6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:49:58 INFO - Running setup.py (path:/tmp/pip-EcTML9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:49:59 INFO - Running setup.py (path:/tmp/pip-sfbAg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:49:59 INFO - Running setup.py (path:/tmp/pip-YCwa1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:49:59 INFO - Running setup.py (path:/tmp/pip-CsZsJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:49:59 INFO - Running setup.py (path:/tmp/pip-gDJKRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:49:59 INFO - Running setup.py (path:/tmp/pip-VSDU9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:49:59 INFO - Running setup.py (path:/tmp/pip-1N0X4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:49:59 INFO - Running setup.py (path:/tmp/pip-KykYQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:49:59 INFO - Running setup.py (path:/tmp/pip-PMZSov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:49:59 INFO - Running setup.py (path:/tmp/pip-v3NkTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:49:59 INFO - Running setup.py (path:/tmp/pip-cSb9Xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:49:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:49:59 INFO - Running setup.py install for manifestparser 17:49:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:49:59 INFO - Running setup.py install for mozcrash 17:49:59 INFO - Running setup.py install for mozdebug 17:50:00 INFO - Running setup.py install for mozdevice 17:50:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Running setup.py install for mozfile 17:50:00 INFO - Running setup.py install for mozhttpd 17:50:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Running setup.py install for mozinfo 17:50:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Running setup.py install for mozInstall 17:50:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Running setup.py install for mozleak 17:50:00 INFO - Running setup.py install for mozlog 17:50:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Running setup.py install for moznetwork 17:50:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:50:00 INFO - Running setup.py install for mozprocess 17:50:00 INFO - Running setup.py install for mozprofile 17:50:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:50:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:50:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:50:01 INFO - Running setup.py install for mozrunner 17:50:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:50:01 INFO - Running setup.py install for mozscreenshot 17:50:01 INFO - Running setup.py install for moztest 17:50:01 INFO - Running setup.py install for mozversion 17:50:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:50:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:50:01 INFO - Cleaning up... 17:50:01 INFO - Return code: 0 17:50:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:50: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')]} 17:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50: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')]} 17:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:01 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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:50:01 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 17:50:01 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 17:50:01 INFO - Using env: {'DISPLAY': ':0', 17:50:01 INFO - 'HOME': '/home/cltbld', 17:50:01 INFO - 'LANG': 'en_US.UTF-8', 17:50:01 INFO - 'LANGUAGE': 'en_US:en', 17:50:01 INFO - 'LOGNAME': 'cltbld', 17:50:01 INFO - 'MAIL': '/var/mail/cltbld', 17:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:01 INFO - 'MOZ_NO_REMOTE': '1', 17:50:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:01 INFO - 'NO_EM_RESTART': '1', 17:50:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:01 INFO - 'PWD': '/builds/slave/test', 17:50:01 INFO - 'SHELL': '/bin/bash', 17:50:01 INFO - 'SHLVL': '1', 17:50:01 INFO - 'TERM': 'linux', 17:50:01 INFO - 'TMOUT': '86400', 17:50:01 INFO - 'USER': 'cltbld', 17:50:01 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:01 INFO - '_': '/tools/buildbot/bin/python'} 17:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:50:01 INFO - Running setup.py (path:/tmp/pip-UITXxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:50:01 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)) 17:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:50:01 INFO - Running setup.py (path:/tmp/pip-7eslcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:50:01 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)) 17:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:50:01 INFO - Running setup.py (path:/tmp/pip-gSGrPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:50:01 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)) 17:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:50:01 INFO - Running setup.py (path:/tmp/pip-RjTOre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:50:02 INFO - Running setup.py (path:/tmp/pip-cJSxG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:50:02 INFO - Running setup.py (path:/tmp/pip-47BuLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:50:02 INFO - Running setup.py (path:/tmp/pip-68ncu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:50:02 INFO - Running setup.py (path:/tmp/pip-uJidcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:50:02 INFO - Running setup.py (path:/tmp/pip-LADfIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:50:02 INFO - Running setup.py (path:/tmp/pip-wsPLcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:50:02 INFO - Running setup.py (path:/tmp/pip-kyzdtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:50:02 INFO - Running setup.py (path:/tmp/pip-Cyo6hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:50:02 INFO - Running setup.py (path:/tmp/pip-YcCmFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:50:02 INFO - Running setup.py (path:/tmp/pip-XUeUel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:50:02 INFO - Running setup.py (path:/tmp/pip-RDijhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:50:02 INFO - Running setup.py (path:/tmp/pip-FTc4zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:50:02 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)) 17:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:50:02 INFO - Running setup.py (path:/tmp/pip-S8kCkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:50:02 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)) 17:50:02 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)) 17:50:02 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)) 17:50:02 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)) 17:50:02 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)) 17:50:02 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)) 17:50:02 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)) 17:50:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:50: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 17:50: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 17:50: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 17:50: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 17:50:04 INFO - Downloading blessings-1.6.tar.gz 17:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:50:04 INFO - Installing collected packages: blessings 17:50:04 INFO - Running setup.py install for blessings 17:50:04 INFO - Successfully installed blessings 17:50:04 INFO - Cleaning up... 17:50:04 INFO - Return code: 0 17:50:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:50:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:50:04 INFO - Reading from file tmpfile_stdout 17:50:04 INFO - Current package versions: 17:50:04 INFO - argparse == 1.2.1 17:50:04 INFO - blessings == 1.6 17:50:04 INFO - blobuploader == 1.2.4 17:50:04 INFO - docopt == 0.6.1 17:50:04 INFO - functools32 == 3.2.3-2 17:50:04 INFO - jsonschema == 2.5.1 17:50:04 INFO - manifestparser == 1.1 17:50:04 INFO - mozInstall == 1.13 17:50:04 INFO - mozcrash == 1.0 17:50:04 INFO - mozdebug == 0.1 17:50:04 INFO - mozdevice == 0.51 17:50:04 INFO - mozfile == 1.2 17:50:04 INFO - mozhttpd == 0.7 17:50:04 INFO - mozinfo == 0.10 17:50:04 INFO - mozleak == 0.1 17:50:04 INFO - mozlog == 3.5 17:50:04 INFO - moznetwork == 0.27 17:50:04 INFO - mozprocess == 0.25 17:50:04 INFO - mozprofile == 0.28 17:50:04 INFO - mozrunner == 6.13 17:50:04 INFO - mozscreenshot == 0.1 17:50:04 INFO - mozsystemmonitor == 0.3 17:50:04 INFO - moztest == 0.8 17:50:04 INFO - mozversion == 1.4 17:50:04 INFO - psutil == 3.1.1 17:50:04 INFO - requests == 1.2.3 17:50:04 INFO - wsgiref == 0.1.2 17:50:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:50: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')]} 17:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50: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')]} 17:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:04 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 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:50:04 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 17:50:04 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 17:50:04 INFO - Using env: {'DISPLAY': ':0', 17:50:04 INFO - 'HOME': '/home/cltbld', 17:50:04 INFO - 'LANG': 'en_US.UTF-8', 17:50:04 INFO - 'LANGUAGE': 'en_US:en', 17:50:04 INFO - 'LOGNAME': 'cltbld', 17:50:04 INFO - 'MAIL': '/var/mail/cltbld', 17:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:04 INFO - 'MOZ_NO_REMOTE': '1', 17:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:04 INFO - 'NO_EM_RESTART': '1', 17:50:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:04 INFO - 'PWD': '/builds/slave/test', 17:50:04 INFO - 'SHELL': '/bin/bash', 17:50:04 INFO - 'SHLVL': '1', 17:50:04 INFO - 'TERM': 'linux', 17:50:04 INFO - 'TMOUT': '86400', 17:50:04 INFO - 'USER': 'cltbld', 17:50:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:04 INFO - '_': '/tools/buildbot/bin/python'} 17:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:04 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)) 17:50:04 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)) 17:50:04 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)) 17:50:04 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)) 17:50:04 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)) 17:50:04 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)) 17:50:04 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)) 17:50:04 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)) 17:50:04 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)) 17:50:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:50:05 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)) 17:50:05 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)) 17:50:05 INFO - Installing collected packages: simplejson 17:50:05 INFO - Running setup.py install for simplejson 17:50:06 INFO - building 'simplejson._speedups' extension 17:50:06 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 17:50:06 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 17:50:06 INFO - Successfully installed simplejson 17:50:06 INFO - Cleaning up... 17:50:06 INFO - Return code: 0 17:50:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb1a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xce2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', '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 17:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:50:06 INFO - Using env: {'DISPLAY': ':0', 17:50:06 INFO - 'HOME': '/home/cltbld', 17:50:06 INFO - 'LANG': 'en_US.UTF-8', 17:50:06 INFO - 'LANGUAGE': 'en_US:en', 17:50:06 INFO - 'LOGNAME': 'cltbld', 17:50:06 INFO - 'MAIL': '/var/mail/cltbld', 17:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:06 INFO - 'MOZ_NO_REMOTE': '1', 17:50:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:06 INFO - 'NO_EM_RESTART': '1', 17:50:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:06 INFO - 'PWD': '/builds/slave/test', 17:50:06 INFO - 'SHELL': '/bin/bash', 17:50:06 INFO - 'SHLVL': '1', 17:50:06 INFO - 'TERM': 'linux', 17:50:06 INFO - 'TMOUT': '86400', 17:50:06 INFO - 'USER': 'cltbld', 17:50:06 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:07 INFO - '_': '/tools/buildbot/bin/python'} 17:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:50:07 INFO - Cleaning up... 17:50:07 INFO - Return code: 0 17:50:07 INFO - Running post-action listener: _resource_record_post_action 17:50:07 INFO - Running post-action listener: _start_resource_monitoring 17:50:07 INFO - Starting resource monitoring. 17:50:07 INFO - [mozharness: 2017-10-03 00:50:07.249433Z] Finished create-virtualenv step (success) 17:50:07 INFO - [mozharness: 2017-10-03 00:50:07.249948Z] Running install step. 17:50:07 INFO - Running pre-action listener: _resource_record_pre_action 17:50:07 INFO - Running main action method: install 17:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:50:07 INFO - Reading from file tmpfile_stdout 17:50:07 INFO - Detecting whether we're running mozinstall >=1.0... 17:50:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:50:07 INFO - Reading from file tmpfile_stdout 17:50:07 INFO - Output received: 17:50:07 INFO - Usage: mozinstall [options] installer 17:50:07 INFO - Options: 17:50:07 INFO - -h, --help show this help message and exit 17:50:07 INFO - -d DEST, --destination=DEST 17:50:07 INFO - Directory to install application into. [default: 17:50:07 INFO - "/builds/slave/test"] 17:50:07 INFO - --app=APP Application being installed. [default: firefox] 17:50:07 INFO - mkdir: /builds/slave/test/build/application 17:50:07 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'] 17:50:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:50:25 INFO - Reading from file tmpfile_stdout 17:50:25 INFO - Output received: 17:50:25 INFO - /builds/slave/test/build/application/firefox/firefox 17:50:25 INFO - Running post-action listener: _resource_record_post_action 17:50:25 INFO - [mozharness: 2017-10-03 00:50:25.249193Z] Finished install step (success) 17:50:25 INFO - [mozharness: 2017-10-03 00:50:25.249542Z] Running setup-mitmproxy step. 17:50:25 INFO - Running pre-action listener: _resource_record_pre_action 17:50:25 INFO - Running main action method: setup_mitmproxy 17:50:25 INFO - Skipping: mitmproxy is not required 17:50:25 INFO - Running post-action listener: _resource_record_post_action 17:50:25 INFO - [mozharness: 2017-10-03 00:50:25.251158Z] Finished setup-mitmproxy step (success) 17:50:25 INFO - [mozharness: 2017-10-03 00:50:25.251469Z] Running run-tests step. 17:50:25 INFO - Running pre-action listener: _resource_record_pre_action 17:50:25 INFO - Running pre-action listener: _set_gcov_prefix 17:50:25 INFO - Running main action method: run_tests 17:50:25 WARNING - Try message not found. 17:50:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:50:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:50:25 INFO - Python 2.7.3 17:50:25 INFO - Return code: 0 17:50:25 INFO - grabbing minidump binary from tooltool 17:50:25 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')]} 17:50:25 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 0xe84d60>, '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 0xe85a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe85ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:50:25 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 17:50:25 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 17:50:25 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 17:50:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:50:25 INFO - Return code: 0 17:50:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:50:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:50:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:50:25 INFO - ENV: RUST_BACKTRACE is now full 17:50:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:50:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:50:25 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/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:50:25 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/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 17:50:25 INFO - Using env: {'DISPLAY': ':0', 17:50:25 INFO - 'HOME': '/home/cltbld', 17:50:25 INFO - 'LANG': 'en_US.UTF-8', 17:50:25 INFO - 'LANGUAGE': 'en_US:en', 17:50:25 INFO - 'LOGNAME': 'cltbld', 17:50:25 INFO - 'MAIL': '/var/mail/cltbld', 17:50:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:50:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:50:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:25 INFO - 'MOZ_NO_REMOTE': '1', 17:50:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:50:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:25 INFO - 'NO_EM_RESTART': '1', 17:50:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:25 INFO - 'PWD': '/builds/slave/test', 17:50:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:50:25 INFO - 'RUST_BACKTRACE': 'full', 17:50:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:50:25 INFO - 'SHELL': '/bin/bash', 17:50:25 INFO - 'SHLVL': '1', 17:50:25 INFO - 'TERM': 'linux', 17:50:25 INFO - 'TMOUT': '86400', 17:50:25 INFO - 'USER': 'cltbld', 17:50:25 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:50:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:25 INFO - '_': '/tools/buildbot/bin/python'} 17:50:25 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/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:50:25 INFO - mozversion application_buildid: 20171002232253 17:50:25 INFO - mozversion application_changeset: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa 17:50:25 INFO - mozversion application_display_name: Nightly 17:50:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:50:25 INFO - mozversion application_name: Firefox 17:50:25 INFO - mozversion application_remotingname: firefox 17:50:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:50:25 INFO - mozversion application_vendor: Mozilla 17:50:25 INFO - mozversion application_version: 58.0a1 17:50:25 INFO - mozversion platform_buildid: 20171002232253 17:50:25 INFO - mozversion platform_changeset: 65dac33a5682f3ec5a675e7f3314b0c1520a13fa 17:50:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:50:25 INFO - mozversion platform_version: 58.0a1 17:50:25 INFO - using testdate: 1506991825 17:50:25 INFO - actual date: 1506991825 17:50:25 INFO - starting webserver on 'localhost:55861' 17:50:25 INFO - SUITE-START | Running 2 tests 17:50:25 INFO - TEST-START | dromaeo_css 17:50:25 INFO - Initialising browser for dromaeo_css test... 17:50:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55861/getInfo.html -profile /tmp/tmpNyrpTq/profile 17:50:25 INFO - TEST-INFO | started process 30324 (/builds/slave/test/build/application/firefox/firefox http://localhost:55861/getInfo.html) 17:50:31 INFO - TEST-INFO | 30324: exit 0 17:50:31 INFO - Browser initialized. 17:50:31 INFO - Running cycle 1/1 for dromaeo_css test... 17:50:31 INFO - Using env: {'DISPLAY': ':0', 17:50:31 INFO - 'HOME': '/home/cltbld', 17:50:31 INFO - 'JSGC_DISABLE_POISONING': '1', 17:50:31 INFO - 'LANG': 'en_US.UTF-8', 17:50:31 INFO - 'LANGUAGE': 'en_US:en', 17:50:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:50:31 INFO - 'LOGNAME': 'cltbld', 17:50:31 INFO - 'MAIL': '/var/mail/cltbld', 17:50:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:50:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:50:31 INFO - 'MOZ_CRASHREPORTER': '1', 17:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:50:31 INFO - 'MOZ_NO_REMOTE': '1', 17:50:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:31 INFO - 'NO_EM_RESTART': '1', 17:50:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:50:31 INFO - 'PWD': '/builds/slave/test', 17:50:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:50:31 INFO - 'RUST_BACKTRACE': 'full', 17:50:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:50:31 INFO - 'SHELL': '/bin/bash', 17:50:31 INFO - 'SHLVL': '1', 17:50:31 INFO - 'TERM': 'linux', 17:50:31 INFO - 'TMOUT': '86400', 17:50:31 INFO - 'USER': 'cltbld', 17:50:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 17:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:31 INFO - '_': '/tools/buildbot/bin/python'} 17:50:31 INFO - TEST-INFO | started process 30601 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNyrpTq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:50:32 INFO - PID 30601 | 17:50:32 INFO - PID 30601 | (/builds/slave/test/build/application/firefox/firefox:30650): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:50:32 INFO - PID 30601 | 17:50:42 INFO - PID 30601 | RSS: Main: 146546688 17:50:42 INFO - PID 30601 | 17:53:40 INFO - PID 30601 | Cycle 1(1): loaded http://localhost:55861/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55861/tests/dromaeo/cssquery-ext.html) 17:53:41 INFO - PID 30601 | RSS: Main: 146759680 17:53:41 INFO - PID 30601 | 17:56:42 INFO - PID 30601 | Cycle 1(1): loaded http://localhost:55861/tests/dromaeo/cssquery-ext.html (next: http://localhost:55861/tests/dromaeo/cssquery-jquery.html) 17:56:43 INFO - PID 30601 | RSS: Main: 144351232 17:56:43 INFO - PID 30601 | 17:59:45 INFO - PID 30601 | Cycle 1(1): loaded http://localhost:55861/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55861/tests/dromaeo/cssquery-mootools.html) 17:59:45 INFO - PID 30601 | RSS: Main: 146497536 17:59:45 INFO - PID 30601 | 18:02:47 INFO - PID 30601 | Cycle 1(1): loaded http://localhost:55861/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55861/tests/dromaeo/cssquery-prototype.html) 18:02:47 INFO - PID 30601 | RSS: Main: 146198528 18:02:47 INFO - PID 30601 | 18:05:50 INFO - PID 30601 | Cycle 1(1): loaded http://localhost:55861/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55861/tests/dromaeo/cssquery-yui.html) 18:05:51 INFO - PID 30601 | RSS: Main: 148455424 18:05:51 INFO - PID 30601 | 18:08:52 INFO - PID 30601 | Cycle 1(1): loaded http://localhost:55861/tests/dromaeo/cssquery-yui.html (next: http://localhost:55861/tests/dromaeo/cssquery-dojo.html) 18:08:53 INFO - PID 30601 | RSS: Main: 150093824 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | __start_tp_report 18:08:53 INFO - PID 30601 | _x_x_mozilla_page_load 18:08:53 INFO - PID 30601 | _x_x_mozilla_page_load_details 18:08:53 INFO - PID 30601 | |i|pagename|runs| 18:08:53 INFO - PID 30601 | |0;dojo.html;3899;3902.8243729032192;3919.098607320318;3925.195334956334;3925.234579257044;6182.845428864281;6280.874382512358;6299.275583307926;6304.905426418602;6318.210223722035;6339.207599050119;6344.682765861711;6364.713587888545;6376.9362306376925;6416.069669897874;5658.349289831681;5719;5725.57058220633;5742.540596752271;5758.884822303551;5572.944270557304;5722.856928576798;5745.741441635137;5812.970935145339;5815.244018277639;5675.006873797069;5715.114157305624;5719.399463056381;5775.27809023872;5783.855403614902;5611.887762244733;5663.7168141593165;5693.259876216107;5711.257536520267;5735.913961290584;5679.772809087632;5692.886142277172;5717.399673034313;5724;5729.054705973509;6250.562460627758;6304.022876454157;6322.589031712924;6359.65021923794;6419.582727122722;6733.730650773963;6812.591244525344;6856.588604683735;6864.862702745917;6901.447884169304;6858.417034552069;6898.586084834926;6901.827454313632;6928.826779330557;6962.373386395248;6342.112104305401;6371;6410.038494225857;6463.030545418178;6492.9350706492805;7082.291770822876;7101.183363913216;7129.964350178216;7154.284571542908;7176.390006849372;6740.090087838079;6761;6831.28271531492;6846.7945961621235;6869.210040845267;6755.324467553205;6863.862722745517;6865.588064716133;6878.518503704692;6948.861022779516;6737.9556168793915;6871.553349032297;6902.965485172542;6914.585124892523;6928.1339832520935;6836.282190370039;6843.726250949906;6845.281245469154;6928.411085057725;6936.410405115622;4944.554990050912;4986.301917731448;4986.80052797884;5067.113255180328;5074.644774865724;4493.73037617744;4504.684672072924;4569.29175977724;4592.6555508337005;4648.116857797076;6231.563790534619;6232.345603711636;6301.4328710416285;6322.114903913456;6323.778667746698;6737.191537015589;6775.627340496221;6871.6564171791215;6872;6883.414909732729;6158.014717645245;6233.2208473940755;6295.433410992941;6296.150019747367;6300.086487459293;5512.6692398456225;5520.585956053312;5521.1442226454965;5543.473370029841;5577.191307260506;7071.611061391673;7161.606111663908;7172.175199852084;7173.210946795848;7181.820454488575;6782.118324617768;6841.555298905555;6929.237783843672;6934.16789985205;6962.268961758942;6959.478039146983;6966.024756533988;6987.1266091738535;7023.859522809617;7069.929300707133;6718.899216511854;6722.630255336004;6722.663866806738;6728.966355168192;6842.623655698984;6835.453163746789;6885;6896.206936202401;6897.379235868796;6949.895751563832;6669.399754022162;6686.465082793462;6686.899696504459;6688.431483323972;6691.263960964193;6572.8356791078695;6588.439982601532;6592.044153597797;6669.4664426844765;6720.495962802712;6454.225492940877;6467.223933127867;6547.247066587213;6551.279359270381;6557.442617377576;6719.697613607497;6730.32696730323;6745.089412929192;6751.966240168767;6784.6946887389195;6403.135576697273;6467.062275969864;6538.934610654022;6542.672866356568;6550.541462097607 18:08:53 INFO - PID 30601 | |1;ext.html;914.8764916736238;951.3673407184222;960.6013504395676;964.5659453246041;983.532821909593;44470.33294500585;45312.3203151953;45392.546074539285;45429;45562.544374556244;42290;45315.77342113293;46324.305135423;46328.76835615826;46356.30465543011;8191.303739182162;8267.255946964771;8275.255227029566;8302.83394332116;8317.459365141276;8311.625976831057;8500.192481714228;8511.659533618647;8520.40357174998;8522.616482258281;8505.064442911274;8511.702090426836;8593.742187734379;8598.957005214965;8599.097094805049;8467.957660211721;8470.407071504997;8491.490510569385;8515.446495977772;8530.914690853075;8129.349652027823;8134;8191.262786349198;8198.467099638505;8200.630971606231;41406.1718765623;41564.376534352006;41615.1676966459;41680.58319416797;41730;3780.584135745066;3787.545494540645;3790.507234059555;3803.3724435468116;3820.6561409473;32952.505712414335;33039.50440743391;33098.66901331004;33099.83450082734;33259.6674033259;5072.847814565569;5264.473552644743;5331.440198779111;5346.198070289449;5347.0909945309395;233313.9999999983;234543;234642.99999999828;235435;236895;51739.48260517422;51975.48024519706;52001.47998520042;52083;52205.21692174621;55918.440815592134;56068.43931560714;56235;56338;56478;16800;17112.91443542774;17137.485875423758;17145.914270428817;17148.571285717706;13810.516631917835;13835.515756948458;13840.100393474391;13847.238401887993;13854.376553054935;13855.584332470042;14048.648783780487;14147.221902795445;14327;14422.350994205286;5232.2674825524455;5402.729863506809;5432.91850622241;5474.124140137638;5534.584906132056;1078.951447184863;1079.7246702091074;1085.4355735017746;1097.720081379243;1100.741325788439;24212.39469013289;24411.145609903568;24418.813840526273;24550.29098084265;24750.504989900463;1187.7149484123747;1196.6620998879064;1201.0571701214506;1204.2894692131683;1217.287886586357;12722.363881805873;12814.70933573152;12897.871021289855;12988.740225195632;13043.28261945602;278372;282072;296772;297552;297599;41069.97325066837;41878.371824422655;57753.71123144357;59092;59371.40628593745;1497.2214448486725;1517.9772303415236;1529.785829983803;1534.4092524378195;1536.5543992242124;28787.128836625554;29173;29370.265743356587;29447;30021.69978300189;41454.792726036176;41472.792636036625;41483;41573.1685366297;41630;243703;244236;244349;244924;245586;36117.63882361143;37291.254174916896;37305.25389492249;37580.436293456165;37588;47974.28038579494;48044.5195548054;48086.759566201945;48356.274655880894;48491.27263091127;35992.820035899655;36066;36134.819325903205;36207;36438.4534231992;35292.117697056754;35355.46966795449;35631.64368356387;35717;36086.27827443384;39918.40122398108;39920.2015959685;40068.1986360277;40528.3920741195;40571;34734;34748.65251347454;34844.82577587096;35087;35204.47193292154;24953.625695614217;25088;25248.49503009893;25326.240212793644;25562.872185638953 18:08:53 INFO - PID 30601 | |2;jquery.html;645.5237119091732;645.8325154096818;648.0619696947313;649.6203132415757;656.3502132888436;23274.883625581868;23918.402039949033;23941;23962.76037239627;23991.64012539813;25796.097136600223;25872;26043.08849190279;26058.348541286476;26097.34756631085;6424.935750642504;6634.867302653944;6729.798106056826;6776.69504872282;6825.419839313664;6727.798166055001;6834.829129271759;6839.110915580993;6843.555168914005;6846.589204647712;6460.3862633049785;6602.504812139085;6740.696668649923;6791.083203767477;6796.728130874783;6491.188601424822;6514.7719829805965;6535.803925882207;6543.509236807211;6544.476441884638;6087.969560152215;6092.878142437126;6098.08528720691;6138.14066030756;6196.597221180609;29706.554401683992;30184.396312073855;30195.849020754755;30211.244718882204;30258.54612180819;2071.3785864240817;2079.864808787439;2082.87502749834;2088.6762551804495;2096.8322534197227;20446.182152713875;20992.265270715452;21217.681734773993;21423.464413389633;21433.356999289892;2616.24129002589;2637.3802156493207;2683.1950414875655;2690.367763575558;2691.111933062085;132628;137481;141281;147554.99999999892;147745;15868.44460443879;16543.25555349988;16582.917085414738;17194.1402929853;17211.827881721274;26609.600855987177;26614.600780987912;26641.86679066592;26645.200643980323;26696.866515667687;10336.689899303034;10563.735906602242;10660.62687805923;10715.410652414192;10718.35689858611;9457.574409151604;9503.28725345602;9576.281778866613;9598.66404675833;9621.567029483691;10002.349847259904;10105.747356316004;10116.29185956976;10125.696229113139;10155.695329140137;2917.226934862263;2967.762578993675;3061.6172978377704;3062.973903742227;3186.5857438532844;1033.7725700345911;1033.9379637221791;1040.9687709368732;1047.3977462958828;1052.894710528941;20014.49963750889;20195.495112622008;20235.797642023685;20452.795472045385;20952.58094838125;1093.9945300273448;1099.7085772270354;1114.1365441782684;1117.6758739965483;1126.0372381613602;11039.779204415867;11185.161112916563;11221.94389028066;11283.774324513628;11454.656360309204;636404;643794;648568;655958;668618;133271.33364333116;136686;137069;137223;137558;1224.6570960131178;1231.2489381477292;1261.9684507887375;1263.0779530942464;1263.791474406731;17663.64672706513;17869.195886185415;17879.19543620514;18112.909435452737;18394.540136496693;60551.394486054574;60646.393536065836;61435.38564614297;61902.76194475995;62365.37634623596;76509;76622;76833.23166768484;78586.6070669643;80406.59796700979;15463.613409664847;15720.842791571939;16013.919930400274;16035.759463607821;16099.597510061874;23931.641025384983;23938.88030559836;24275.75724242735;24335.391615209763;24492.14277500279;18726.719099213795;18836.058197089817;18850.24599016024;18995.71506427377;19024.52438689044;17432.389866354617;17494.21276042556;17935.37226197077;18185.727214092065;18357.632847342713;17940.282388704305;18994.335198268545;19145.329913452963;19271.325503607306;19279.036048197813;15457.304421300843;15500.457483988457;15509.22453877279;15530.689386212438;15531.611709707347;18406.447806565826;18574.071296434853;18988.810111899256;19094.61810763805;19198 18:08:53 INFO - PID 30601 | |3;mootools.html;1224.4612370556954;1275.1201670847117;1400.3068481101857;1418.2625034981816;1435.2967046147385;16533.25600347984;17167;17214.48356549304;17228.655426891455;17249.82750172498;16920;16935.407260745877;17022.65954680909;17053.232604532794;17061.658766824658;2824.8728807203725;2838.7019362966903;2887.090566471565;2891.9132426027254;2894.3343031102754;2841.4743272494657;2869.0675530452686;2887.2637477443327;2888.24905524563;2890.494163521386;2831.207261966653;2849.9145025649264;2858.285428642839;2979.7318241358275;2980.1208643450127;2905.141591993652;2925.4149170165947;2960.7483363914093;2961.1708721557998;2962.377900640868;2667.333166708323;2838.9290267743263;2871.540553511449;2879.424115176973;2896.319364949235;17166.485005449857;17296.827031729772;17313.2209050593;17316;17331.65336693272;8828.911710882872;9090.590923408396;9107.043760405195;9110;9111.954440227822;5562.360328562227;5582.972085139589;5730.885382292373;5774.0761478163295;5887.470127688486;2119.279444988696;2127.1385089038963;2156.4177672028463;2172.945676358088;2174.7716489768663;30188.24529386761;30704.69295307063;30778.384432311228;30980;31039.37921241585;7599.582022988679;7677.539347639159;7721.266479684421;7820.100688420791;7875.409344299201;20056.598868022556;20084.297049603487;20095.095720692603;20110.195592176147;20229.393118206477;8857.37998340123;9001.729948101567;9006.234470069987;9008.324375671851;9012.369134160677;7921.464452278456;7953.761387158279;8096.433249672466;8134.471259368122;8263.710770123016;5919.940800592025;6008.03871380577;6019.819405417845;6049.455549000611;6185.969070154621;1778.848797852197;1790.8925464471913;1792.8027916929127;1823.972640410395;1837.9172937217857;911.7948461596088;916.2395211974045;919.1543779722671;926.9119433653792;929.5398777604994;8207.261346478846;8307.460015098999;8328.791780205544;8338.958305208434;8352.164783521599;774.4578794843632;786.7797016835295;788.66087582339;791.2087912087912;794.3089512124488;5667.1782591524;5737.340205876294;5799.159121927381;5955.136505206808;6047.15339852421;25183;25944;26140.215793526226;26151;26653.86673066661;25148.24555263345;25450.618240726402;25529.106481273066;25848.8707556461;26342.86828565883;11532.904374084448;11754;11916.165868389304;12128.514859405701;12362.62912112656;9352.906470935204;9470.14768670823;9482.336236463381;9517.571709272961;9544.236461082957;21194.258200962893;21291.574168516854;21442;21567.676484853055;21726.45683857916;22065;22485.66271505896;22491.43771405728;22599.435014124778;22639.88680056589;13065.216087034429;13099.803502947654;13347.06570540053;13350.265735384648;13472.259025753285;15688.372465101269;15832.445864394691;15869.682606348038;15915.602109947344;16025.198740062717;12634.557790477289;12657.367131643197;12879.935600321938;12943.09398342107;12986.480540778262;12624.116311858083;12835.743285134431;12940.611781646565;13051.673708157372;13121.08152429359;10938.890611093788;11169.832452513381;11174.553017879194;11178.049865761175;11182.161337899854;7668.348190403655;7800.843983120192;7826.882596761167;7842.568658723825;7884.84230315402;7854.293113619801;7870.370370370471;7914.683412663429;8075.878861817195;8152.144024877302 18:08:53 INFO - PID 30601 | |4;prototype.html;562.2746656812711;563.5378989228832;586.9148973398858;589.6815719511465;592.2685483427965;6182.845428864275;6188.876222475547;6212.285587157467;6213.813585592439;6281.932071547836;6124.816255512342;6155.569110162291;6177;6180.115064905202;6289.716962736677;4974.626902982272;5042.697438153704;5118.4625614310335;5120.385553733558;5162.174052151647;5015.348004759376;5036.773345199456;5120.873407850286;5122.180451127821;5123.692578445287;4973;5015.197568389059;5052.393712754475;5064.341635587387;5128.1282182029145;5017.523335283142;5037.269595908591;5039.218921067204;5082.771275292602;5115.309433226504;4663.836765713184;4664.39362882823;4667.13657973276;4684.836030738942;4717.905641887178;6133.754649814002;6215.316315205324;6220.47125994291;6287.245530536319;6337.397947195055;5960.344362120163;5970.432808883149;5978.760849566012;5980.43185897339;6004.639721616673;6689.297623749533;6712.899306510406;6730.865382692367;6736.932630673679;6752.426043786284;4981.900361992776;5146.150885103953;5201.375834899798;5227.817026404057;5252.763625636817;6691.59850408977;6784.8303792405095;6894.276101009372;6934.826629334257;6940.583564986117;5280.260763493114;5296.496832800878;5297.708626025606;5337.866553336197;5383.78464861409;5429.53848922846;5443.646162999393;5446.863828404322;5449.209864569694;5467.371252305956;5983.850403739942;5989.891870004063;5998.70006499682;6010.128531363015;6030.125631783367;6747.797566073025;6748.763793267212;6807.693653785592;6814.557053791487;6838.589684618939;1822.48059303098;1824.5256233379152;1862.0224382199183;1866.9626607467774;1886.0946745562208;1303.1659737767648;1321.9754690115237;1338.0633556510481;1359.7756370199004;1365.6585853536617;6038.215032045894;6067.7269522871575;6112.113743507167;6203.2556093268195;6204.13142160098;6586.967065164644;6683.365080317362;6723.2770727855295;6727.360900714424;6771.5259931805285;6095.451409373178;6105.664188469676;6125.1731016313115;6165.564601982282;6182.6599537025895;3508.1755787389943;3521.8767343142868;3566.3937130687846;3574.4995700601935;3608.296382205497;7064.470164737665;7084.822879428056;7093.538919970186;7094.50338476312;7116.0393346898545;6992.230854605989;7002.809522381105;7023.543469674455;7058.223595404502;7106.18278897923;6922.342377474132;6990.965045174741;7010.929890701028;7012.544184627983;7072.646367681698;6617.139771829824;6733.865322693617;6802.897956530557;6821.249662537216;6832.316768323327;6651.235107962646;6682.966585167043;6701.932980670131;6738.561993470409;6738.6967586459705;7062.15254169503;7086.291370862872;7107.928920710727;7113.2175460698245;7163.820904477429;5331.733413329241;5359.330083739533;5362.088444964444;5364.570834333322;5386.542143917811;6682.9331706682315;6730.6971186297715;6781.864362712817;6785.592864427953;6822.522423430511;6118.418750218722;6553.099601115814;6574.046763219402;6589.5387322888855;6706.396424321834;6464.450521705519;6557.311482294291;6566.961318788815;6619.437347825488;6660.800175994728;6350.237971443456;6371.6814159290925;6430.5177111715875;6471.320511346241;6473.287938326875;6554.14796076507;6573.704183311666;6577.276499584946;6585.407313341822;6607.636579988147;6365.840853978502;6418.679066046772;6446.419822216023;6457.257415397289;6491.805245842632 18:08:53 INFO - PID 30601 | |5;yui.html;951.7477868364884;966.6181858166025;977.1742877268705;979.4612962870419;981.874242265495;20762.169513219473;20828.166873325066;21143.154273829048;21192.682109768364;21194.682079768816;19256.711149332736;19342.323018694347;19474.123664435134;19506.512337191543;19527.218911243534;1011.7571782772119;1026.558579810681;1030.6135199300263;1031.2729525684374;1033.860428842108;1051.6950084475532;1052.1162223732063;1052.8947105289487;1052.947352632369;1054.0671505717457;1048.9947550262239;1087.086847048479;1090.983635245472;1095.780843831233;1099.3458891959272;1081.853949716786;1082.491229122319;1082.642727899795;1083.3716444462232;1084.6637542361855;795.2524626850742;796.9760907172794;797.8165022044963;797.9706179029046;798.7284323330096;21762.89118554413;21883.452913677127;22044.779552204433;22181.77818221813;22350.10599576015;23685.644715329283;23703.170389036473;24275.63586546203;24322.391940201636;24378.878105609358;18406.90796546022;18639.81360186394;18950.336738214097;18953.526161846064;18993.905030474758;4005.13889513753;4063.3904914262803;4067.2272268268935;4078.490188726409;4079.9184016319805;124215.37892310572;152198;165286;167220.99999999878;167376;18580.44258672242;18635.627287454445;18748.53128671767;18749.906250468932;18807.15367808452;34126.14684632854;34126.48810267848;34161.31677366439;34203;34251.657483424846;9959;10003.2997690161;10031.498425078717;10057.094861462358;10100.191984641211;4578.542145785462;4598.149342371672;4663.160631086437;4681.438227412732;4682.812687492462;5779.653220806765;6020.127081573148;6028.4272994065495;6077.665728384981;6087.96956015226;1179.1273098122879;1211.4124649544963;1213.3751118174148;1215.4348228073989;1221.285547954439;723.4393345157545;732.9047223860971;737.1596380126659;752.3529764402609;758.2000988956704;13675.452981880811;13955.720885582434;14211.431542738175;14388.496402625859;14481.20353380553;734.5262305812721;745.5824738146655;754.6151462754035;762.1502025241815;763.6143747407634;6361.809145725636;6515.2181738190775;6543.247526534415;6543.723973825154;6549.377809108127;152335;156945;157881;164147;165213.86965325722;138008;139174;141562.29218853838;143860.2452503972;144019;119450;121336;121586;122253;123783.76216237902;11800.645980620595;11881.049516038524;12076;12298.631041068424;12306.630801075624;54175.18737219024;54382.456175437736;54670.72664636811;55052.449475506335;55689;73800.63099684467;73862;74056;74405;74961.25038749543;16536.834631654012;17424.12879356002;17541.912290438468;17565.560860978578;17580.472585822445;18571.628567428306;19134.425967221006;19272.518187044876;19410.51473713112;19509;18174.5456363592;18317;18418;18472.445826625222;18508.259669613064;17871.821281787015;17887;17963.371282005068;17999.550011249823;18044.639107218045;17424.73862892083;17670.91164544169;17811.19849606745;17826.732599011284;17853.196606153015;13152;13302.467901284228;13323.866761332263;13426.530071447452;13548.59354219375;13831.792523112363;13849.861501384858;13877.444902203799;13914.791278130633;14027.789583156056 18:08:53 INFO - PID 30601 | __end_tp_report 18:08:53 INFO - PID 30601 | __start_cc_report 18:08:53 INFO - PID 30601 | _x_x_mozilla_cycle_collect,247 18:08:53 INFO - PID 30601 | __end_cc_report 18:08:53 INFO - PID 30601 | __startTimestamp1506992933195__endTimestamp 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | ------- Summary: start ------- 18:08:53 INFO - PID 30601 | Number of tests: 6 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | [#0] dojo.html Cycles:165 Average:6315.61 Median:6550.54 stddev:746.39 (11.4%) stddev-sans-first:724.20 18:08:53 INFO - PID 30601 | Values: 3899.0 3902.8 3919.1 3925.2 3925.2 6182.8 6280.9 6299.3 6304.9 6318.2 6339.2 6344.7 6364.7 6376.9 6416.1 5658.3 5719.0 5725.6 5742.5 5758.9 5572.9 5722.9 5745.7 5813.0 5815.2 5675.0 5715.1 5719.4 5775.3 5783.9 5611.9 5663.7 5693.3 5711.3 5735.9 5679.8 5692.9 5717.4 5724.0 5729.1 6250.6 6304.0 6322.6 6359.7 6419.6 6733.7 6812.6 6856.6 6864.9 6901.4 6858.4 6898.6 6901.8 6928.8 6962.4 6342.1 6371.0 6410.0 6463.0 6492.9 7082.3 7101.2 7130.0 7154.3 7176.4 6740.1 6761.0 6831.3 6846.8 6869.2 6755.3 6863.9 6865.6 6878.5 6948.9 6738.0 6871.6 6903.0 6914.6 6928.1 6836.3 6843.7 6845.3 6928.4 6936.4 4944.6 4986.3 4986.8 5067.1 5074.6 4493.7 4504.7 4569.3 4592.7 4648.1 6231.6 6232.3 6301.4 6322.1 6323.8 6737.2 6775.6 6871.7 6872.0 6883.4 6158.0 6233.2 6295.4 6296.2 6300.1 5512.7 5520.6 5521.1 5543.5 5577.2 7071.6 7161.6 7172.2 7173.2 7181.8 6782.1 6841.6 6929.2 6934.2 6962.3 6959.5 6966.0 6987.1 7023.9 7069.9 6718.9 6722.6 6722.7 6729.0 6842.6 6835.5 6885.0 6896.2 6897.4 6949.9 6669.4 6686.5 6686.9 6688.4 6691.3 6572.8 6588.4 6592.0 6669.5 6720.5 6454.2 6467.2 6547.2 6551.3 6557.4 6719.7 6730.3 6745.1 6752.0 6784.7 6403.1 6467.1 6538.9 6542.7 6550.5 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | [#1] ext.html Cycles:180 Average:43537.06 Median:3784.06 stddev:67060.94 (1772.2%) stddev-sans-first:67172.70 18:08:53 INFO - PID 30601 | Values: 914.9 951.4 960.6 964.6 983.5 44470.3 45312.3 45392.5 45429.0 45562.5 42290.0 45315.8 46324.3 46328.8 46356.3 8191.3 8267.3 8275.3 8302.8 8317.5 8311.6 8500.2 8511.7 8520.4 8522.6 8505.1 8511.7 8593.7 8599.0 8599.1 8468.0 8470.4 8491.5 8515.4 8530.9 8129.3 8134.0 8191.3 8198.5 8200.6 41406.2 41564.4 41615.2 41680.6 41730.0 3780.6 3787.5 3790.5 3803.4 3820.7 32952.5 33039.5 33098.7 33099.8 33259.7 5072.8 5264.5 5331.4 5346.2 5347.1 233314.0 234543.0 234643.0 235435.0 236895.0 51739.5 51975.5 52001.5 52083.0 52205.2 55918.4 56068.4 56235.0 56338.0 56478.0 16800.0 17112.9 17137.5 17145.9 17148.6 13810.5 13835.5 13840.1 13847.2 13854.4 13855.6 14048.6 14147.2 14327.0 14422.4 5232.3 5402.7 5432.9 5474.1 5534.6 1079.0 1079.7 1085.4 1097.7 1100.7 24212.4 24411.1 24418.8 24550.3 24750.5 1187.7 1196.7 1201.1 1204.3 1217.3 12722.4 12814.7 12897.9 12988.7 13043.3 278372.0 282072.0 296772.0 297552.0 297599.0 41070.0 41878.4 57753.7 59092.0 59371.4 1497.2 1518.0 1529.8 1534.4 1536.6 28787.1 29173.0 29370.3 29447.0 30021.7 41454.8 41472.8 41483.0 41573.2 41630.0 243703.0 244236.0 244349.0 244924.0 245586.0 36117.6 37291.3 37305.3 37580.4 37588.0 47974.3 48044.5 48086.8 48356.3 48491.3 35992.8 36066.0 36134.8 36207.0 36438.5 35292.1 35355.5 35631.6 35717.0 36086.3 39918.4 39920.2 40068.2 40528.4 40571.0 34734.0 34748.7 34844.8 35087.0 35204.5 24953.6 25088.0 25248.5 25326.2 25562.9 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | [#2] jquery.html Cycles:180 Average:40735.65 Median:11544.55 stddev:108267.65 (937.8%) stddev-sans-first:108529.52 18:08:53 INFO - PID 30601 | Values: 645.5 645.8 648.1 649.6 656.4 23274.9 23918.4 23941.0 23962.8 23991.6 25796.1 25872.0 26043.1 26058.3 26097.3 6424.9 6634.9 6729.8 6776.7 6825.4 6727.8 6834.8 6839.1 6843.6 6846.6 6460.4 6602.5 6740.7 6791.1 6796.7 6491.2 6514.8 6535.8 6543.5 6544.5 6088.0 6092.9 6098.1 6138.1 6196.6 29706.6 30184.4 30195.8 30211.2 30258.5 2071.4 2079.9 2082.9 2088.7 2096.8 20446.2 20992.3 21217.7 21423.5 21433.4 2616.2 2637.4 2683.2 2690.4 2691.1 132628.0 137481.0 141281.0 147555.0 147745.0 15868.4 16543.3 16582.9 17194.1 17211.8 26609.6 26614.6 26641.9 26645.2 26696.9 10336.7 10563.7 10660.6 10715.4 10718.4 9457.6 9503.3 9576.3 9598.7 9621.6 10002.3 10105.7 10116.3 10125.7 10155.7 2917.2 2967.8 3061.6 3063.0 3186.6 1033.8 1033.9 1041.0 1047.4 1052.9 20014.5 20195.5 20235.8 20452.8 20952.6 1094.0 1099.7 1114.1 1117.7 1126.0 11039.8 11185.2 11221.9 11283.8 11454.7 636404.0 643794.0 648568.0 655958.0 668618.0 133271.3 136686.0 137069.0 137223.0 137558.0 1224.7 1231.2 1262.0 1263.1 1263.8 17663.6 17869.2 17879.2 18112.9 18394.5 60551.4 60646.4 61435.4 61902.8 62365.4 76509.0 76622.0 76833.2 78586.6 80406.6 15463.6 15720.8 16013.9 16035.8 16099.6 23931.6 23938.9 24275.8 24335.4 24492.1 18726.7 18836.1 18850.2 18995.7 19024.5 17432.4 17494.2 17935.4 18185.7 18357.6 17940.3 18994.3 19145.3 19271.3 19279.0 15457.3 15500.5 15509.2 15530.7 15531.6 18406.4 18574.1 18988.8 19094.6 19198.0 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | [#3] mootools.html Cycles:180 Average:10615.09 Median:2845.69 stddev:7883.27 (277.0%) stddev-sans-first:7873.81 18:08:53 INFO - PID 30601 | Values: 1224.5 1275.1 1400.3 1418.3 1435.3 16533.3 17167.0 17214.5 17228.7 17249.8 16920.0 16935.4 17022.7 17053.2 17061.7 2824.9 2838.7 2887.1 2891.9 2894.3 2841.5 2869.1 2887.3 2888.2 2890.5 2831.2 2849.9 2858.3 2979.7 2980.1 2905.1 2925.4 2960.7 2961.2 2962.4 2667.3 2838.9 2871.5 2879.4 2896.3 17166.5 17296.8 17313.2 17316.0 17331.7 8828.9 9090.6 9107.0 9110.0 9112.0 5562.4 5583.0 5730.9 5774.1 5887.5 2119.3 2127.1 2156.4 2172.9 2174.8 30188.2 30704.7 30778.4 30980.0 31039.4 7599.6 7677.5 7721.3 7820.1 7875.4 20056.6 20084.3 20095.1 20110.2 20229.4 8857.4 9001.7 9006.2 9008.3 9012.4 7921.5 7953.8 8096.4 8134.5 8263.7 5919.9 6008.0 6019.8 6049.5 6186.0 1778.8 1790.9 1792.8 1824.0 1837.9 911.8 916.2 919.2 926.9 929.5 8207.3 8307.5 8328.8 8339.0 8352.2 774.5 786.8 788.7 791.2 794.3 5667.2 5737.3 5799.2 5955.1 6047.2 25183.0 25944.0 26140.2 26151.0 26653.9 25148.2 25450.6 25529.1 25848.9 26342.9 11532.9 11754.0 11916.2 12128.5 12362.6 9352.9 9470.1 9482.3 9517.6 9544.2 21194.3 21291.6 21442.0 21567.7 21726.5 22065.0 22485.7 22491.4 22599.4 22639.9 13065.2 13099.8 13347.1 13350.3 13472.3 15688.4 15832.4 15869.7 15915.6 16025.2 12634.6 12657.4 12879.9 12943.1 12986.5 12624.1 12835.7 12940.6 13051.7 13121.1 10938.9 11169.8 11174.6 11178.0 11182.2 7668.3 7800.8 7826.9 7842.6 7884.8 7854.3 7870.4 7914.7 8075.9 8152.1 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | [#4] prototype.html Cycles:180 Average:5680.27 Median:6196.07 stddev:1566.11 (25.3%) stddev-sans-first:1522.66 18:08:53 INFO - PID 30601 | Values: 562.3 563.5 586.9 589.7 592.3 6182.8 6188.9 6212.3 6213.8 6281.9 6124.8 6155.6 6177.0 6180.1 6289.7 4974.6 5042.7 5118.5 5120.4 5162.2 5015.3 5036.8 5120.9 5122.2 5123.7 4973.0 5015.2 5052.4 5064.3 5128.1 5017.5 5037.3 5039.2 5082.8 5115.3 4663.8 4664.4 4667.1 4684.8 4717.9 6133.8 6215.3 6220.5 6287.2 6337.4 5960.3 5970.4 5978.8 5980.4 6004.6 6689.3 6712.9 6730.9 6736.9 6752.4 4981.9 5146.2 5201.4 5227.8 5252.8 6691.6 6784.8 6894.3 6934.8 6940.6 5280.3 5296.5 5297.7 5337.9 5383.8 5429.5 5443.6 5446.9 5449.2 5467.4 5983.9 5989.9 5998.7 6010.1 6030.1 6747.8 6748.8 6807.7 6814.6 6838.6 1822.5 1824.5 1862.0 1867.0 1886.1 1303.2 1322.0 1338.1 1359.8 1365.7 6038.2 6067.7 6112.1 6203.3 6204.1 6587.0 6683.4 6723.3 6727.4 6771.5 6095.5 6105.7 6125.2 6165.6 6182.7 3508.2 3521.9 3566.4 3574.5 3608.3 7064.5 7084.8 7093.5 7094.5 7116.0 6992.2 7002.8 7023.5 7058.2 7106.2 6922.3 6991.0 7010.9 7012.5 7072.6 6617.1 6733.9 6802.9 6821.2 6832.3 6651.2 6683.0 6701.9 6738.6 6738.7 7062.2 7086.3 7107.9 7113.2 7163.8 5331.7 5359.3 5362.1 5364.6 5386.5 6682.9 6730.7 6781.9 6785.6 6822.5 6118.4 6553.1 6574.0 6589.5 6706.4 6464.5 6557.3 6567.0 6619.4 6660.8 6350.2 6371.7 6430.5 6471.3 6473.3 6554.1 6573.7 6577.3 6585.4 6607.6 6365.8 6418.7 6446.4 6457.3 6491.8 18:08:53 INFO - PID 30601 | 18:08:53 INFO - PID 30601 | [#5] yui.html Cycles:180 Average:29132.88 Median:18576.04 stddev:43858.55 (236.1%) stddev-sans-first:43930.54 18:08:53 INFO - PID 30601 | Values: 951.7 966.6 977.2 979.5 981.9 20762.2 20828.2 21143.2 21192.7 21194.7 19256.7 19342.3 19474.1 19506.5 19527.2 1011.8 1026.6 1030.6 1031.3 1033.9 1051.7 1052.1 1052.9 1052.9 1054.1 1049.0 1087.1 1091.0 1095.8 1099.3 1081.9 1082.5 1082.6 1083.4 1084.7 795.3 797.0 797.8 798.0 798.7 21762.9 21883.5 22044.8 22181.8 22350.1 23685.6 23703.2 24275.6 24322.4 24378.9 18406.9 18639.8 18950.3 18953.5 18993.9 4005.1 4063.4 4067.2 4078.5 4079.9 124215.4 152198.0 165286.0 167221.0 167376.0 18580.4 18635.6 18748.5 18749.9 18807.2 34126.1 34126.5 34161.3 34203.0 34251.7 9959.0 10003.3 10031.5 10057.1 10100.2 4578.5 4598.1 4663.2 4681.4 4682.8 5779.7 6020.1 6028.4 6077.7 6088.0 1179.1 1211.4 1213.4 1215.4 1221.3 723.4 732.9 737.2 752.4 758.2 13675.5 13955.7 14211.4 14388.5 14481.2 734.5 745.6 754.6 762.2 763.6 6361.8 6515.2 6543.2 6543.7 6549.4 152335.0 156945.0 157881.0 164147.0 165213.9 138008.0 139174.0 141562.3 143860.2 144019.0 119450.0 121336.0 121586.0 122253.0 123783.8 11800.6 11881.0 12076.0 12298.6 12306.6 54175.2 54382.5 54670.7 55052.4 55689.0 73800.6 73862.0 74056.0 74405.0 74961.3 16536.8 17424.1 17541.9 17565.6 17580.5 18571.6 19134.4 19272.5 19410.5 19509.0 18174.5 18317.0 18418.0 18472.4 18508.3 17871.8 17887.0 17963.4 17999.6 18044.6 17424.7 17670.9 17811.2 17826.7 17853.2 13152.0 13302.5 13323.9 13426.5 13548.6 13831.8 13849.9 13877.4 13914.8 14027.8 18:08:53 INFO - PID 30601 | -------- Summary: end -------- 18:08:53 INFO - PID 30601 | 18:08:53 INFO - TEST-INFO | 30601: exit 0 18:08:53 INFO - TEST-OK | dromaeo_css | took 1108125ms 18:08:53 INFO - TEST-START | kraken 18:08:53 INFO - Initialising browser for kraken test... 18:08:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55861/getInfo.html -profile /tmp/tmpIVc2DG/profile 18:08:53 INFO - TEST-INFO | started process 31008 (/builds/slave/test/build/application/firefox/firefox http://localhost:55861/getInfo.html) 18:08:58 INFO - TEST-INFO | 31008: exit 0 18:08:58 INFO - Browser initialized. 18:08:58 INFO - Running cycle 1/1 for kraken test... 18:08:58 INFO - Using env: {'DISPLAY': ':0', 18:08:58 INFO - 'HOME': '/home/cltbld', 18:08:58 INFO - 'JSGC_DISABLE_POISONING': '1', 18:08:58 INFO - 'LANG': 'en_US.UTF-8', 18:08:58 INFO - 'LANGUAGE': 'en_US:en', 18:08:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:08:58 INFO - 'LOGNAME': 'cltbld', 18:08:58 INFO - 'MAIL': '/var/mail/cltbld', 18:08:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:08:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:08:58 INFO - 'MOZ_CRASHREPORTER': '1', 18:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:08:58 INFO - 'MOZ_NO_REMOTE': '1', 18:08:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:58 INFO - 'NO_EM_RESTART': '1', 18:08:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:58 INFO - 'PWD': '/builds/slave/test', 18:08:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:08:58 INFO - 'RUST_BACKTRACE': 'full', 18:08:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:08:58 INFO - 'SHELL': '/bin/bash', 18:08:58 INFO - 'SHLVL': '1', 18:08:58 INFO - 'TERM': 'linux', 18:08:58 INFO - 'TMOUT': '86400', 18:08:58 INFO - 'USER': 'cltbld', 18:08:58 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431', 18:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:58 INFO - '_': '/tools/buildbot/bin/python'} 18:08:58 INFO - TEST-INFO | started process 31285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIVc2DG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:08:59 INFO - PID 31285 | 18:08:59 INFO - PID 31285 | (/builds/slave/test/build/application/firefox/firefox:31334): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:08:59 INFO - PID 31285 | 18:09:09 INFO - PID 31285 | RSS: Main: 146726912 18:09:09 INFO - PID 31285 | 18:09:12 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55861/tests/kraken/driver.html?testName=audio-beat-detection) 18:09:13 INFO - PID 31285 | RSS: Main: 153284608 18:09:13 INFO - PID 31285 | 18:09:15 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55861/tests/kraken/driver.html?testName=audio-dft) 18:09:16 INFO - PID 31285 | RSS: Main: 153694208 18:09:16 INFO - PID 31285 | 18:09:19 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55861/tests/kraken/driver.html?testName=audio-fft) 18:09:20 INFO - PID 31285 | RSS: Main: 148738048 18:09:20 INFO - PID 31285 | 18:09:22 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55861/tests/kraken/driver.html?testName=audio-oscillator) 18:09:22 INFO - PID 31285 | RSS: Main: 149864448 18:09:22 INFO - PID 31285 | 18:09:24 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55861/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:09:25 INFO - PID 31285 | RSS: Main: 148987904 18:09:25 INFO - PID 31285 | 18:09:28 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55861/tests/kraken/driver.html?testName=imaging-darkroom) 18:09:29 INFO - PID 31285 | RSS: Main: 149729280 18:09:29 INFO - PID 31285 | 18:09:32 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55861/tests/kraken/driver.html?testName=imaging-desaturate) 18:09:33 INFO - PID 31285 | RSS: Main: 150994944 18:09:33 INFO - PID 31285 | 18:09:36 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55861/tests/kraken/driver.html?testName=json-parse-financial) 18:09:36 INFO - PID 31285 | RSS: Main: 152776704 18:09:36 INFO - PID 31285 | 18:09:38 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55861/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:09:38 INFO - PID 31285 | RSS: Main: 152571904 18:09:38 INFO - PID 31285 | 18:09:40 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:09:41 INFO - PID 31285 | RSS: Main: 152510464 18:09:41 INFO - PID 31285 | 18:09:42 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:09:43 INFO - PID 31285 | RSS: Main: 149368832 18:09:43 INFO - PID 31285 | 18:09:46 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:09:46 INFO - PID 31285 | RSS: Main: 149221376 18:09:46 INFO - PID 31285 | 18:09:49 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:09:49 INFO - PID 31285 | RSS: Main: 149516288 18:09:49 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | Cycle 1(1): loaded http://localhost:55861/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55861/tests/kraken/driver.html?testName=ai-astar) 18:09:51 INFO - PID 31285 | RSS: Main: 151117824 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | __start_tp_report 18:09:51 INFO - PID 31285 | _x_x_mozilla_page_load 18:09:51 INFO - PID 31285 | _x_x_mozilla_page_load_details 18:09:51 INFO - PID 31285 | |i|pagename|runs| 18:09:51 INFO - PID 31285 | |0;ai-astar;107;107;105;109;106;108;105;108;108;106 18:09:51 INFO - PID 31285 | |1;audio-beat-detection;114;115;112;135;111;111;115;115;116;119 18:09:51 INFO - PID 31285 | |2;audio-dft;163;162;163;163;162;162;162;162;165;171 18:09:51 INFO - PID 31285 | |3;audio-fft;74;73;73;75;72;73;74;73;74;74 18:09:51 INFO - PID 31285 | |4;audio-oscillator;78;78;84;77;78;79;81;79;78;78 18:09:51 INFO - PID 31285 | |5;imaging-gaussian-blur;113;114;114;114;110;114;114;114;115;114 18:09:51 INFO - PID 31285 | |6;imaging-darkroom;150;149;154;149;149;151;149;151;150;150 18:09:51 INFO - PID 31285 | |7;imaging-desaturate;94;95;94;97;95;95;94;93;95;94 18:09:51 INFO - PID 31285 | |8;json-parse-financial;46;46;43;43;46;44;43;46;45;42 18:09:51 INFO - PID 31285 | |9;json-stringify-tinderbox;38;40;38;38;38;38;39;38;38;38 18:09:51 INFO - PID 31285 | |10;stanford-crypto-aes;68;74;69;69;69;74;67;67;69;69 18:09:51 INFO - PID 31285 | |11;stanford-crypto-ccm;108;105;108;110;108;110;114;104;104;106 18:09:51 INFO - PID 31285 | |12;stanford-crypto-pbkdf2;153;149;152;155;143;150;153;154;152;149 18:09:51 INFO - PID 31285 | |13;stanford-crypto-sha256-iterative;63;62;58;59;66;61;59;61;59;59 18:09:51 INFO - PID 31285 | __end_tp_report 18:09:51 INFO - PID 31285 | __start_cc_report 18:09:51 INFO - PID 31285 | _x_x_mozilla_cycle_collect,679 18:09:51 INFO - PID 31285 | __end_cc_report 18:09:51 INFO - PID 31285 | __startTimestamp1506992991881__endTimestamp 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | ------- Summary: start ------- 18:09:51 INFO - PID 31285 | Number of tests: 14 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#0] ai-astar Cycles:10 Average:106.90 Median:107.50 stddev:1.37 (1.3%) stddev-sans-first:1.45 18:09:51 INFO - PID 31285 | Values: 107.0 107.0 105.0 109.0 106.0 108.0 105.0 108.0 108.0 106.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:115.00 stddev:7.01 (6.1%) stddev-sans-first:7.38 18:09:51 INFO - PID 31285 | Values: 114.0 115.0 112.0 135.0 111.0 111.0 115.0 115.0 116.0 119.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:2.80 (1.7%) stddev-sans-first:2.96 18:09:51 INFO - PID 31285 | Values: 163.0 162.0 163.0 163.0 162.0 162.0 162.0 162.0 165.0 171.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#3] audio-fft Cycles:10 Average:73.50 Median:74.00 stddev:0.85 (1.1%) stddev-sans-first:0.88 18:09:51 INFO - PID 31285 | Values: 74.0 73.0 73.0 75.0 72.0 73.0 74.0 73.0 74.0 74.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:78.50 stddev:2.05 (2.6%) stddev-sans-first:2.15 18:09:51 INFO - PID 31285 | Values: 78.0 78.0 84.0 77.0 78.0 79.0 81.0 79.0 78.0 78.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:1.35 (1.2%) stddev-sans-first:1.41 18:09:51 INFO - PID 31285 | Values: 113.0 114.0 114.0 114.0 110.0 114.0 114.0 114.0 115.0 114.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:1.55 (1.0%) stddev-sans-first:1.64 18:09:51 INFO - PID 31285 | Values: 150.0 149.0 154.0 149.0 149.0 151.0 149.0 151.0 150.0 150.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:1.07 (1.1%) stddev-sans-first:1.12 18:09:51 INFO - PID 31285 | Values: 94.0 95.0 94.0 97.0 95.0 95.0 94.0 93.0 95.0 94.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#8] json-parse-financial Cycles:10 Average:44.40 Median:45.50 stddev:1.58 (3.5%) stddev-sans-first:1.56 18:09:51 INFO - PID 31285 | Values: 46.0 46.0 43.0 43.0 46.0 44.0 43.0 46.0 45.0 42.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.67 (1.8%) stddev-sans-first:0.71 18:09:51 INFO - PID 31285 | Values: 38.0 40.0 38.0 38.0 38.0 38.0 39.0 38.0 38.0 38.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#10] stanford-crypto-aes Cycles:10 Average:69.50 Median:69.00 stddev:2.51 (3.6%) stddev-sans-first:2.60 18:09:51 INFO - PID 31285 | Values: 68.0 74.0 69.0 69.0 69.0 74.0 67.0 67.0 69.0 69.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#11] stanford-crypto-ccm Cycles:10 Average:107.70 Median:108.00 stddev:3.13 (2.9%) stddev-sans-first:3.32 18:09:51 INFO - PID 31285 | Values: 108.0 105.0 108.0 110.0 108.0 110.0 114.0 104.0 104.0 106.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.00 Median:152.50 stddev:3.46 (2.3%) stddev-sans-first:3.60 18:09:51 INFO - PID 31285 | Values: 153.0 149.0 152.0 155.0 143.0 150.0 153.0 154.0 152.0 149.0 18:09:51 INFO - PID 31285 | 18:09:51 INFO - PID 31285 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:61.00 stddev:2.45 (4.0%) stddev-sans-first:2.46 18:09:51 INFO - PID 31285 | Values: 63.0 62.0 58.0 59.0 66.0 61.0 59.0 61.0 59.0 59.0 18:09:51 INFO - PID 31285 | -------- Summary: end -------- 18:09:51 INFO - PID 31285 | 18:09:52 INFO - PID 31285 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:09:52 INFO - TEST-INFO | 31285: exit 0 18:09:52 INFO - TEST-OK | kraken | took 58776ms 18:09:52 INFO - SUITE-END | took 1166s 18:09:52 INFO - Completed test suite (00:19:26) 18:09:52 INFO - javascript benchmark 18:09:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9138.098139665619, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3899.0, 3902.8243729032192, 3919.098607320318, 3925.195334956334, 3925.234579257044, 6182.845428864281, 6280.874382512358, 6299.275583307926, 6304.905426418602, 6318.210223722035, 6339.207599050119, 6344.682765861711, 6364.713587888545, 6376.9362306376925, 6416.069669897874, 5658.349289831681, 5719.0, 5725.57058220633, 5742.540596752271, 5758.884822303551, 5572.944270557304, 5722.856928576798, 5745.741441635137, 5812.970935145339, 5815.244018277639, 5675.006873797069, 5715.114157305624, 5719.399463056381, 5775.27809023872, 5783.855403614902, 5611.887762244733, 5663.7168141593165, 5693.259876216107, 5711.257536520267, 5735.913961290584, 5679.772809087632, 5692.886142277172, 5717.399673034313, 5724.0, 5729.054705973509, 6250.562460627758, 6304.022876454157, 6322.589031712924, 6359.65021923794, 6419.582727122722, 6733.730650773963, 6812.591244525344, 6856.588604683735, 6864.862702745917, 6901.447884169304, 6858.417034552069, 6898.586084834926, 6901.827454313632, 6928.826779330557, 6962.373386395248, 6342.112104305401, 6371.0, 6410.038494225857, 6463.030545418178, 6492.9350706492805, 7082.291770822876, 7101.183363913216, 7129.964350178216, 7154.284571542908, 7176.390006849372, 6740.090087838079, 6761.0, 6831.28271531492, 6846.7945961621235, 6869.210040845267, 6755.324467553205, 6863.862722745517, 6865.588064716133, 6878.518503704692, 6948.861022779516, 6737.9556168793915, 6871.553349032297, 6902.965485172542, 6914.585124892523, 6928.1339832520935, 6836.282190370039, 6843.726250949906, 6845.281245469154, 6928.411085057725, 6936.410405115622, 4944.554990050912, 4986.301917731448, 4986.80052797884, 5067.113255180328, 5074.644774865724, 4493.73037617744, 4504.684672072924, 4569.29175977724, 4592.6555508337005, 4648.116857797076, 6231.563790534619, 6232.345603711636, 6301.4328710416285, 6322.114903913456, 6323.778667746698, 6737.191537015589, 6775.627340496221, 6871.6564171791215, 6872.0, 6883.414909732729, 6158.014717645245, 6233.2208473940755, 6295.433410992941, 6296.150019747367, 6300.086487459293, 5512.6692398456225, 5520.585956053312, 5521.1442226454965, 5543.473370029841, 5577.191307260506, 7071.611061391673, 7161.606111663908, 7172.175199852084, 7173.210946795848, 7181.820454488575, 6782.118324617768, 6841.555298905555, 6929.237783843672, 6934.16789985205, 6962.268961758942, 6959.478039146983, 6966.024756533988, 6987.1266091738535, 7023.859522809617, 7069.929300707133, 6718.899216511854, 6722.630255336004, 6722.663866806738, 6728.966355168192, 6842.623655698984, 6835.453163746789, 6885.0, 6896.206936202401, 6897.379235868796, 6949.895751563832, 6669.399754022162, 6686.465082793462, 6686.899696504459, 6688.431483323972, 6691.263960964193, 6572.8356791078695, 6588.439982601532, 6592.044153597797, 6669.4664426844765, 6720.495962802712, 6454.225492940877, 6467.223933127867, 6547.247066587213, 6551.279359270381, 6557.442617377576, 6719.697613607497, 6730.32696730323, 6745.089412929192, 6751.966240168767, 6784.6946887389195, 6403.135576697273, 6467.062275969864, 6538.934610654022, 6542.672866356568, 6550.541462097607], "value": 6265.142061119098, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [914.8764916736238, 951.3673407184222, 960.6013504395676, 964.5659453246041, 983.532821909593, 44470.33294500585, 45312.3203151953, 45392.546074539285, 45429.0, 45562.544374556244, 42290.0, 45315.77342113293, 46324.305135423, 46328.76835615826, 46356.30465543011, 8191.303739182162, 8267.255946964771, 8275.255227029566, 8302.83394332116, 8317.459365141276, 8311.625976831057, 8500.192481714228, 8511.659533618647, 8520.40357174998, 8522.616482258281, 8505.064442911274, 8511.702090426836, 8593.742187734379, 8598.957005214965, 8599.097094805049, 8467.957660211721, 8470.407071504997, 8491.490510569385, 8515.446495977772, 8530.914690853075, 8129.349652027823, 8134.0, 8191.262786349198, 8198.467099638505, 8200.630971606231, 41406.1718765623, 41564.376534352006, 41615.1676966459, 41680.58319416797, 41730.0, 3780.584135745066, 3787.545494540645, 3790.507234059555, 3803.3724435468116, 3820.6561409473, 32952.505712414335, 33039.50440743391, 33098.66901331004, 33099.83450082734, 33259.6674033259, 5072.847814565569, 5264.473552644743, 5331.440198779111, 5346.198070289449, 5347.0909945309395, 233313.9999999983, 234543.0, 234642.99999999828, 235435.0, 236895.0, 51739.48260517422, 51975.48024519706, 52001.47998520042, 52083.0, 52205.21692174621, 55918.440815592134, 56068.43931560714, 56235.0, 56338.0, 56478.0, 16800.0, 17112.91443542774, 17137.485875423758, 17145.914270428817, 17148.571285717706, 13810.516631917835, 13835.515756948458, 13840.100393474391, 13847.238401887993, 13854.376553054935, 13855.584332470042, 14048.648783780487, 14147.221902795445, 14327.0, 14422.350994205286, 5232.2674825524455, 5402.729863506809, 5432.91850622241, 5474.124140137638, 5534.584906132056, 1078.951447184863, 1079.7246702091074, 1085.4355735017746, 1097.720081379243, 1100.741325788439, 24212.39469013289, 24411.145609903568, 24418.813840526273, 24550.29098084265, 24750.504989900463, 1187.7149484123747, 1196.6620998879064, 1201.0571701214506, 1204.2894692131683, 1217.287886586357, 12722.363881805873, 12814.70933573152, 12897.871021289855, 12988.740225195632, 13043.28261945602, 278372.0, 282072.0, 296772.0, 297552.0, 297599.0, 41069.97325066837, 41878.371824422655, 57753.71123144357, 59092.0, 59371.40628593745, 1497.2214448486725, 1517.9772303415236, 1529.785829983803, 1534.4092524378195, 1536.5543992242124, 28787.128836625554, 29173.0, 29370.265743356587, 29447.0, 30021.69978300189, 41454.792726036176, 41472.792636036625, 41483.0, 41573.1685366297, 41630.0, 243703.0, 244236.0, 244349.0, 244924.0, 245586.0, 36117.63882361143, 37291.254174916896, 37305.25389492249, 37580.436293456165, 37588.0, 47974.28038579494, 48044.5195548054, 48086.759566201945, 48356.274655880894, 48491.27263091127, 35992.820035899655, 36066.0, 36134.819325903205, 36207.0, 36438.4534231992, 35292.117697056754, 35355.46966795449, 35631.64368356387, 35717.0, 36086.27827443384, 39918.40122398108, 39920.2015959685, 40068.1986360277, 40528.3920741195, 40571.0, 34734.0, 34748.65251347454, 34844.82577587096, 35087.0, 35204.47193292154, 24953.625695614217, 25088.0, 25248.49503009893, 25326.240212793644, 25562.872185638953], "value": 18729.355529470435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.5237119091732, 645.8325154096818, 648.0619696947313, 649.6203132415757, 656.3502132888436, 23274.883625581868, 23918.402039949033, 23941.0, 23962.76037239627, 23991.64012539813, 25796.097136600223, 25872.0, 26043.08849190279, 26058.348541286476, 26097.34756631085, 6424.935750642504, 6634.867302653944, 6729.798106056826, 6776.69504872282, 6825.419839313664, 6727.798166055001, 6834.829129271759, 6839.110915580993, 6843.555168914005, 6846.589204647712, 6460.3862633049785, 6602.504812139085, 6740.696668649923, 6791.083203767477, 6796.728130874783, 6491.188601424822, 6514.7719829805965, 6535.803925882207, 6543.509236807211, 6544.476441884638, 6087.969560152215, 6092.878142437126, 6098.08528720691, 6138.14066030756, 6196.597221180609, 29706.554401683992, 30184.396312073855, 30195.849020754755, 30211.244718882204, 30258.54612180819, 2071.3785864240817, 2079.864808787439, 2082.87502749834, 2088.6762551804495, 2096.8322534197227, 20446.182152713875, 20992.265270715452, 21217.681734773993, 21423.464413389633, 21433.356999289892, 2616.24129002589, 2637.3802156493207, 2683.1950414875655, 2690.367763575558, 2691.111933062085, 132628.0, 137481.0, 141281.0, 147554.99999999892, 147745.0, 15868.44460443879, 16543.25555349988, 16582.917085414738, 17194.1402929853, 17211.827881721274, 26609.600855987177, 26614.600780987912, 26641.86679066592, 26645.200643980323, 26696.866515667687, 10336.689899303034, 10563.735906602242, 10660.62687805923, 10715.410652414192, 10718.35689858611, 9457.574409151604, 9503.28725345602, 9576.281778866613, 9598.66404675833, 9621.567029483691, 10002.349847259904, 10105.747356316004, 10116.29185956976, 10125.696229113139, 10155.695329140137, 2917.226934862263, 2967.762578993675, 3061.6172978377704, 3062.973903742227, 3186.5857438532844, 1033.7725700345911, 1033.9379637221791, 1040.9687709368732, 1047.3977462958828, 1052.894710528941, 20014.49963750889, 20195.495112622008, 20235.797642023685, 20452.795472045385, 20952.58094838125, 1093.9945300273448, 1099.7085772270354, 1114.1365441782684, 1117.6758739965483, 1126.0372381613602, 11039.779204415867, 11185.161112916563, 11221.94389028066, 11283.774324513628, 11454.656360309204, 636404.0, 643794.0, 648568.0, 655958.0, 668618.0, 133271.33364333116, 136686.0, 137069.0, 137223.0, 137558.0, 1224.6570960131178, 1231.2489381477292, 1261.9684507887375, 1263.0779530942464, 1263.791474406731, 17663.64672706513, 17869.195886185415, 17879.19543620514, 18112.909435452737, 18394.540136496693, 60551.394486054574, 60646.393536065836, 61435.38564614297, 61902.76194475995, 62365.37634623596, 76509.0, 76622.0, 76833.23166768484, 78586.6070669643, 80406.59796700979, 15463.613409664847, 15720.842791571939, 16013.919930400274, 16035.759463607821, 16099.597510061874, 23931.641025384983, 23938.88030559836, 24275.75724242735, 24335.391615209763, 24492.14277500279, 18726.719099213795, 18836.058197089817, 18850.24599016024, 18995.71506427377, 19024.52438689044, 17432.389866354617, 17494.21276042556, 17935.37226197077, 18185.727214092065, 18357.632847342713, 17940.282388704305, 18994.335198268545, 19145.329913452963, 19271.325503607306, 19279.036048197813, 15457.304421300843, 15500.457483988457, 15509.22453877279, 15530.689386212438, 15531.611709707347, 18406.447806565826, 18574.071296434853, 18988.810111899256, 19094.61810763805, 19198.0], "value": 12739.26169290282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1224.4612370556954, 1275.1201670847117, 1400.3068481101857, 1418.2625034981816, 1435.2967046147385, 16533.25600347984, 17167.0, 17214.48356549304, 17228.655426891455, 17249.82750172498, 16920.0, 16935.407260745877, 17022.65954680909, 17053.232604532794, 17061.658766824658, 2824.8728807203725, 2838.7019362966903, 2887.090566471565, 2891.9132426027254, 2894.3343031102754, 2841.4743272494657, 2869.0675530452686, 2887.2637477443327, 2888.24905524563, 2890.494163521386, 2831.207261966653, 2849.9145025649264, 2858.285428642839, 2979.7318241358275, 2980.1208643450127, 2905.141591993652, 2925.4149170165947, 2960.7483363914093, 2961.1708721557998, 2962.377900640868, 2667.333166708323, 2838.9290267743263, 2871.540553511449, 2879.424115176973, 2896.319364949235, 17166.485005449857, 17296.827031729772, 17313.2209050593, 17316.0, 17331.65336693272, 8828.911710882872, 9090.590923408396, 9107.043760405195, 9110.0, 9111.954440227822, 5562.360328562227, 5582.972085139589, 5730.885382292373, 5774.0761478163295, 5887.470127688486, 2119.279444988696, 2127.1385089038963, 2156.4177672028463, 2172.945676358088, 2174.7716489768663, 30188.24529386761, 30704.69295307063, 30778.384432311228, 30980.0, 31039.37921241585, 7599.582022988679, 7677.539347639159, 7721.266479684421, 7820.100688420791, 7875.409344299201, 20056.598868022556, 20084.297049603487, 20095.095720692603, 20110.195592176147, 20229.393118206477, 8857.37998340123, 9001.729948101567, 9006.234470069987, 9008.324375671851, 9012.369134160677, 7921.464452278456, 7953.761387158279, 8096.433249672466, 8134.471259368122, 8263.710770123016, 5919.940800592025, 6008.03871380577, 6019.819405417845, 6049.455549000611, 6185.969070154621, 1778.848797852197, 1790.8925464471913, 1792.8027916929127, 1823.972640410395, 1837.9172937217857, 911.7948461596088, 916.2395211974045, 919.1543779722671, 926.9119433653792, 929.5398777604994, 8207.261346478846, 8307.460015098999, 8328.791780205544, 8338.958305208434, 8352.164783521599, 774.4578794843632, 786.7797016835295, 788.66087582339, 791.2087912087912, 794.3089512124488, 5667.1782591524, 5737.340205876294, 5799.159121927381, 5955.136505206808, 6047.15339852421, 25183.0, 25944.0, 26140.215793526226, 26151.0, 26653.86673066661, 25148.24555263345, 25450.618240726402, 25529.106481273066, 25848.8707556461, 26342.86828565883, 11532.904374084448, 11754.0, 11916.165868389304, 12128.514859405701, 12362.62912112656, 9352.906470935204, 9470.14768670823, 9482.336236463381, 9517.571709272961, 9544.236461082957, 21194.258200962893, 21291.574168516854, 21442.0, 21567.676484853055, 21726.45683857916, 22065.0, 22485.66271505896, 22491.43771405728, 22599.435014124778, 22639.88680056589, 13065.216087034429, 13099.803502947654, 13347.06570540053, 13350.265735384648, 13472.259025753285, 15688.372465101269, 15832.445864394691, 15869.682606348038, 15915.602109947344, 16025.198740062717, 12634.557790477289, 12657.367131643197, 12879.935600321938, 12943.09398342107, 12986.480540778262, 12624.116311858083, 12835.743285134431, 12940.611781646565, 13051.673708157372, 13121.08152429359, 10938.890611093788, 11169.832452513381, 11174.553017879194, 11178.049865761175, 11182.161337899854, 7668.348190403655, 7800.843983120192, 7826.882596761167, 7842.568658723825, 7884.84230315402, 7854.293113619801, 7870.370370370471, 7914.683412663429, 8075.878861817195, 8152.144024877302], "value": 7377.19127616014, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.2746656812711, 563.5378989228832, 586.9148973398858, 589.6815719511465, 592.2685483427965, 6182.845428864275, 6188.876222475547, 6212.285587157467, 6213.813585592439, 6281.932071547836, 6124.816255512342, 6155.569110162291, 6177.0, 6180.115064905202, 6289.716962736677, 4974.626902982272, 5042.697438153704, 5118.4625614310335, 5120.385553733558, 5162.174052151647, 5015.348004759376, 5036.773345199456, 5120.873407850286, 5122.180451127821, 5123.692578445287, 4973.0, 5015.197568389059, 5052.393712754475, 5064.341635587387, 5128.1282182029145, 5017.523335283142, 5037.269595908591, 5039.218921067204, 5082.771275292602, 5115.309433226504, 4663.836765713184, 4664.39362882823, 4667.13657973276, 4684.836030738942, 4717.905641887178, 6133.754649814002, 6215.316315205324, 6220.47125994291, 6287.245530536319, 6337.397947195055, 5960.344362120163, 5970.432808883149, 5978.760849566012, 5980.43185897339, 6004.639721616673, 6689.297623749533, 6712.899306510406, 6730.865382692367, 6736.932630673679, 6752.426043786284, 4981.900361992776, 5146.150885103953, 5201.375834899798, 5227.817026404057, 5252.763625636817, 6691.59850408977, 6784.8303792405095, 6894.276101009372, 6934.826629334257, 6940.583564986117, 5280.260763493114, 5296.496832800878, 5297.708626025606, 5337.866553336197, 5383.78464861409, 5429.53848922846, 5443.646162999393, 5446.863828404322, 5449.209864569694, 5467.371252305956, 5983.850403739942, 5989.891870004063, 5998.70006499682, 6010.128531363015, 6030.125631783367, 6747.797566073025, 6748.763793267212, 6807.693653785592, 6814.557053791487, 6838.589684618939, 1822.48059303098, 1824.5256233379152, 1862.0224382199183, 1866.9626607467774, 1886.0946745562208, 1303.1659737767648, 1321.9754690115237, 1338.0633556510481, 1359.7756370199004, 1365.6585853536617, 6038.215032045894, 6067.7269522871575, 6112.113743507167, 6203.2556093268195, 6204.13142160098, 6586.967065164644, 6683.365080317362, 6723.2770727855295, 6727.360900714424, 6771.5259931805285, 6095.451409373178, 6105.664188469676, 6125.1731016313115, 6165.564601982282, 6182.6599537025895, 3508.1755787389943, 3521.8767343142868, 3566.3937130687846, 3574.4995700601935, 3608.296382205497, 7064.470164737665, 7084.822879428056, 7093.538919970186, 7094.50338476312, 7116.0393346898545, 6992.230854605989, 7002.809522381105, 7023.543469674455, 7058.223595404502, 7106.18278897923, 6922.342377474132, 6990.965045174741, 7010.929890701028, 7012.544184627983, 7072.646367681698, 6617.139771829824, 6733.865322693617, 6802.897956530557, 6821.249662537216, 6832.316768323327, 6651.235107962646, 6682.966585167043, 6701.932980670131, 6738.561993470409, 6738.6967586459705, 7062.15254169503, 7086.291370862872, 7107.928920710727, 7113.2175460698245, 7163.820904477429, 5331.733413329241, 5359.330083739533, 5362.088444964444, 5364.570834333322, 5386.542143917811, 6682.9331706682315, 6730.6971186297715, 6781.864362712817, 6785.592864427953, 6822.522423430511, 6118.418750218722, 6553.099601115814, 6574.046763219402, 6589.5387322888855, 6706.396424321834, 6464.450521705519, 6557.311482294291, 6566.961318788815, 6619.437347825488, 6660.800175994728, 6350.237971443456, 6371.6814159290925, 6430.5177111715875, 6471.320511346241, 6473.287938326875, 6554.14796076507, 6573.704183311666, 6577.276499584946, 6585.407313341822, 6607.636579988147, 6365.840853978502, 6418.679066046772, 6446.419822216023, 6457.257415397289, 6491.805245842632], "value": 5234.664945664906, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [951.7477868364884, 966.6181858166025, 977.1742877268705, 979.4612962870419, 981.874242265495, 20762.169513219473, 20828.166873325066, 21143.154273829048, 21192.682109768364, 21194.682079768816, 19256.711149332736, 19342.323018694347, 19474.123664435134, 19506.512337191543, 19527.218911243534, 1011.7571782772119, 1026.558579810681, 1030.6135199300263, 1031.2729525684374, 1033.860428842108, 1051.6950084475532, 1052.1162223732063, 1052.8947105289487, 1052.947352632369, 1054.0671505717457, 1048.9947550262239, 1087.086847048479, 1090.983635245472, 1095.780843831233, 1099.3458891959272, 1081.853949716786, 1082.491229122319, 1082.642727899795, 1083.3716444462232, 1084.6637542361855, 795.2524626850742, 796.9760907172794, 797.8165022044963, 797.9706179029046, 798.7284323330096, 21762.89118554413, 21883.452913677127, 22044.779552204433, 22181.77818221813, 22350.10599576015, 23685.644715329283, 23703.170389036473, 24275.63586546203, 24322.391940201636, 24378.878105609358, 18406.90796546022, 18639.81360186394, 18950.336738214097, 18953.526161846064, 18993.905030474758, 4005.13889513753, 4063.3904914262803, 4067.2272268268935, 4078.490188726409, 4079.9184016319805, 124215.37892310572, 152198.0, 165286.0, 167220.99999999878, 167376.0, 18580.44258672242, 18635.627287454445, 18748.53128671767, 18749.906250468932, 18807.15367808452, 34126.14684632854, 34126.48810267848, 34161.31677366439, 34203.0, 34251.657483424846, 9959.0, 10003.2997690161, 10031.498425078717, 10057.094861462358, 10100.191984641211, 4578.542145785462, 4598.149342371672, 4663.160631086437, 4681.438227412732, 4682.812687492462, 5779.653220806765, 6020.127081573148, 6028.4272994065495, 6077.665728384981, 6087.96956015226, 1179.1273098122879, 1211.4124649544963, 1213.3751118174148, 1215.4348228073989, 1221.285547954439, 723.4393345157545, 732.9047223860971, 737.1596380126659, 752.3529764402609, 758.2000988956704, 13675.452981880811, 13955.720885582434, 14211.431542738175, 14388.496402625859, 14481.20353380553, 734.5262305812721, 745.5824738146655, 754.6151462754035, 762.1502025241815, 763.6143747407634, 6361.809145725636, 6515.2181738190775, 6543.247526534415, 6543.723973825154, 6549.377809108127, 152335.0, 156945.0, 157881.0, 164147.0, 165213.86965325722, 138008.0, 139174.0, 141562.29218853838, 143860.2452503972, 144019.0, 119450.0, 121336.0, 121586.0, 122253.0, 123783.76216237902, 11800.645980620595, 11881.049516038524, 12076.0, 12298.631041068424, 12306.630801075624, 54175.18737219024, 54382.456175437736, 54670.72664636811, 55052.449475506335, 55689.0, 73800.63099684467, 73862.0, 74056.0, 74405.0, 74961.25038749543, 16536.834631654012, 17424.12879356002, 17541.912290438468, 17565.560860978578, 17580.472585822445, 18571.628567428306, 19134.425967221006, 19272.518187044876, 19410.51473713112, 19509.0, 18174.5456363592, 18317.0, 18418.0, 18472.445826625222, 18508.259669613064, 17871.821281787015, 17887.0, 17963.371282005068, 17999.550011249823, 18044.639107218045, 17424.73862892083, 17670.91164544169, 17811.19849606745, 17826.732599011284, 17853.196606153015, 13152.0, 13302.467901284228, 13323.866761332263, 13426.530071447452, 13548.59354219375, 13831.792523112363, 13849.861501384858, 13877.444902203799, 13914.791278130633, 14027.789583156056], "value": 10086.270880401255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1369.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 105.0, 109.0, 106.0, 108.0, 105.0, 108.0, 108.0, 106.0], "value": 106.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 112.0, 135.0, 111.0, 111.0, 115.0, 115.0, 116.0, 119.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 163.0, 162.0, 162.0, 162.0, 162.0, 165.0, 171.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 75.0, 72.0, 73.0, 74.0, 73.0, 74.0, 74.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 84.0, 77.0, 78.0, 79.0, 81.0, 79.0, 78.0, 78.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 110.0, 114.0, 114.0, 114.0, 115.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 154.0, 149.0, 149.0, 151.0, 149.0, 151.0, 150.0, 150.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 97.0, 95.0, 95.0, 94.0, 93.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 46.0, 43.0, 43.0, 46.0, 44.0, 43.0, 46.0, 45.0, 42.0], "value": 44.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 40.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 69.0, 69.0, 69.0, 74.0, 67.0, 67.0, 69.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 105.0, 108.0, 110.0, 108.0, 110.0, 114.0, 104.0, 104.0, 106.0], "value": 107.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 152.0, 155.0, 143.0, 150.0, 153.0, 154.0, 152.0, 149.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 58.0, 59.0, 66.0, 61.0, 59.0, 61.0, 59.0, 59.0], "value": 60.7, "unit": "score"}]}]} 18:09:52 INFO - Return code: 0 18:09:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:09:52 INFO - # TBPL SUCCESS # 18:09:52 INFO - Running post-action listener: _package_coverage_data 18:09:52 INFO - Running post-action listener: _resource_record_post_action 18:09:52 INFO - [mozharness: 2017-10-03 01:09:52.663889Z] Finished run-tests step (success) 18:09:52 INFO - Running post-run listener: _resource_record_post_run 18:09:52 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331030528; Read time: 164; Write time: 241696 18:09:52 INFO - TinderboxPrint: CPU usage
13.1% 18:09:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 164 18:09:52 INFO - TinderboxPrint: I/O write bytes / time
331,030,528 / 241,696 18:09:52 INFO - TinderboxPrint: CPU idle
8,210.3 (86.9%) 18:09:52 INFO - TinderboxPrint: CPU user
1,188.7 (12.6%) 18:09:52 INFO - TinderboxPrint: Swap in / out
0 / 0 18:09:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12521472; Read time: 0; Write time: 2140 18:09:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:09:52 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317894656; Read time: 164; Write time: 239556 18:09:53 INFO - Running post-run listener: _upload_blobber_files 18:09:53 INFO - Blob upload gear active. 18:09:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:09:53 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:09:53 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:09:53 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:09:53 INFO - (blobuploader) - INFO - Open directory for files ... 18:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:09:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:09:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:09:55 INFO - (blobuploader) - INFO - Done attempting. 18:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:09:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:09:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:09:56 INFO - (blobuploader) - INFO - Done attempting. 18:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:10:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:10:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:10:03 INFO - (blobuploader) - INFO - Done attempting. 18:10:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:10:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:10:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:10:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:10:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:10:04 INFO - (blobuploader) - INFO - Done attempting. 18:10:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:10:04 INFO - Return code: 0 18:10:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:10:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:10:04 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81418861f68ffc0e3a06e145f3da5cfde77b3b4ea5459c5dee3592d6c20471a768c215c3e65bc6cbbfce69fa75c347ee6e990b096d5ee9d4997fbcb4d48fd433", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1dc6838e018947f2ceda320d495e75234f6d98277459d086d2bc3c78e6f8ba04ce57db80dabea46f3ac16d5e1774f746325182f3184fbad0efa1a0d115e68e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e6c561b4efe03035e80d495b999ad5bed3f02dd6c8517f918855ac65f0409aed53c26206e047c6562851ca69bd48846295b7aced1ebfd97c094ca794536f1c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29efe8d5774fd8bf48baabab43ecfc63469d9613c9bce11fe5b0dc918be7d005beeb1fad252dd2d4bdb17a6198fed446156b8a3dac04a5b3cacaa7b17d24e9e9"} 18:10:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:10:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:10:04 INFO - Contents: 18:10:04 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81418861f68ffc0e3a06e145f3da5cfde77b3b4ea5459c5dee3592d6c20471a768c215c3e65bc6cbbfce69fa75c347ee6e990b096d5ee9d4997fbcb4d48fd433", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1dc6838e018947f2ceda320d495e75234f6d98277459d086d2bc3c78e6f8ba04ce57db80dabea46f3ac16d5e1774f746325182f3184fbad0efa1a0d115e68e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e6c561b4efe03035e80d495b999ad5bed3f02dd6c8517f918855ac65f0409aed53c26206e047c6562851ca69bd48846295b7aced1ebfd97c094ca794536f1c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29efe8d5774fd8bf48baabab43ecfc63469d9613c9bce11fe5b0dc918be7d005beeb1fad252dd2d4bdb17a6198fed446156b8a3dac04a5b3cacaa7b17d24e9e9"} 18:10:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:10:04 INFO - Copying logs to upload dir... 18:10:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:10:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.183815 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 18:10:04.929214) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 18:10:04.934067) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81418861f68ffc0e3a06e145f3da5cfde77b3b4ea5459c5dee3592d6c20471a768c215c3e65bc6cbbfce69fa75c347ee6e990b096d5ee9d4997fbcb4d48fd433", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1dc6838e018947f2ceda320d495e75234f6d98277459d086d2bc3c78e6f8ba04ce57db80dabea46f3ac16d5e1774f746325182f3184fbad0efa1a0d115e68e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e6c561b4efe03035e80d495b999ad5bed3f02dd6c8517f918855ac65f0409aed53c26206e047c6562851ca69bd48846295b7aced1ebfd97c094ca794536f1c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29efe8d5774fd8bf48baabab43ecfc63469d9613c9bce11fe5b0dc918be7d005beeb1fad252dd2d4bdb17a6198fed446156b8a3dac04a5b3cacaa7b17d24e9e9"} build_url:https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011474 build_url: 'https://queue.taskcluster.net/v1/task/GvTuDUuVRuaZlRJDpMYY1Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81418861f68ffc0e3a06e145f3da5cfde77b3b4ea5459c5dee3592d6c20471a768c215c3e65bc6cbbfce69fa75c347ee6e990b096d5ee9d4997fbcb4d48fd433", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1dc6838e018947f2ceda320d495e75234f6d98277459d086d2bc3c78e6f8ba04ce57db80dabea46f3ac16d5e1774f746325182f3184fbad0efa1a0d115e68e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e6c561b4efe03035e80d495b999ad5bed3f02dd6c8517f918855ac65f0409aed53c26206e047c6562851ca69bd48846295b7aced1ebfd97c094ca794536f1c0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29efe8d5774fd8bf48baabab43ecfc63469d9613c9bce11fe5b0dc918be7d005beeb1fad252dd2d4bdb17a6198fed446156b8a3dac04a5b3cacaa7b17d24e9e9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 18:10:04.965797) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 18:10:04.966115) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506991645.461968-1840292431 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004730 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 18:10:05.044497) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 18:10:05.044822) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 18:10:05.045121) ========= ========= Total master_lag: 0.22 =========