builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-034 starttime: 1505343014.84 results: success (0) revision: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:14.840634) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:14.841061) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:14.841342) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:14.859082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:14.859510) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 15:50:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2017-09-13 15:50:15 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.333368 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:15.212553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:15.212880) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020112 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:15.262358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:15.262702) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 --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 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 --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=d6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 15:50:15,349 truncating revision to first 12 chars 2017-09-13 15:50:15,349 Setting DEBUG logging. 2017-09-13 15:50:15,349 attempt 1/10 2017-09-13 15:50:15,349 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0b3646aa9cbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 15:50:15,813 unpacking tar archive at: mozilla-central-0b3646aa9cbf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.736900 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:16.023426) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:16.023729) ========= script_repo_revision: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:16.024120) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:16.024392) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:50:16.037828) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-13 15:50:16.038209) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=d6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:50:16 INFO - MultiFileLogger online at 20170913 15:50:16 in /builds/slave/test 15:50:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:50:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:16 INFO - {'append_to_log': False, 15:50:16 INFO - 'base_work_dir': '/builds/slave/test', 15:50:16 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:50:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:16 INFO - 'branch': 'Firefox-Non-PGO', 15:50:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:16 INFO - 'code_coverage': False, 15:50:16 INFO - 'config_files': ('talos/linux_config.py',), 15:50:16 INFO - 'default_actions': ('clobber', 15:50:16 INFO - 'read-buildbot-config', 15:50:16 INFO - 'download-and-extract', 15:50:16 INFO - 'populate-webroot', 15:50:16 INFO - 'create-virtualenv', 15:50:16 INFO - 'install', 15:50:16 INFO - 'setup-mitmproxy', 15:50:16 INFO - 'run-tests'), 15:50:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:16 INFO - 'disable_ccov_upload': False, 15:50:16 INFO - 'disable_stylo': False, 15:50:16 INFO - 'download_minidump_stackwalk': True, 15:50:16 INFO - 'download_symbols': 'ondemand', 15:50:16 INFO - 'enable_stylo': False, 15:50:16 INFO - 'enable_webrender': False, 15:50:16 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:50:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:16 INFO - 'gecko_profile': False, 15:50:16 INFO - 'gecko_profile_interval': 0, 15:50:16 INFO - 'installer_path': 'installer.exe', 15:50:16 INFO - 'log_level': 'info', 15:50:16 INFO - 'log_name': 'talos', 15:50:16 INFO - 'log_to_console': True, 15:50:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:50:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:50:16 INFO - 'opt_config_files': (), 15:50:16 INFO - 'pip_index': False, 15:50:16 INFO - 'suite': 'dromaeojs-e10s', 15:50:16 INFO - 'system_bits': '32', 15:50:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:16 INFO - 'title': 'talos-linux64-ix-034', 15:50:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:16 INFO - 'use_talos_json': True, 15:50:16 INFO - 'verify': 'False', 15:50:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:16 INFO - 'work_dir': 'build'} 15:50:16 INFO - [mozharness: 2017-09-13 22:50:16.258882Z] Running clobber step. 15:50:16 INFO - Running pre-action listener: _resource_record_pre_action 15:50:16 INFO - Running main action method: clobber 15:50:16 INFO - rmtree: /builds/slave/test/build 15:50:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:17 INFO - Running post-action listener: _resource_record_post_action 15:50:17 INFO - [mozharness: 2017-09-13 22:50:17.846162Z] Finished clobber step (success) 15:50:17 INFO - [mozharness: 2017-09-13 22:50:17.846260Z] Running read-buildbot-config step. 15:50:17 INFO - Running pre-action listener: _resource_record_pre_action 15:50:17 INFO - Running main action method: read_buildbot_config 15:50:17 INFO - Using buildbot properties: 15:50:17 INFO - { 15:50:17 INFO - "project": "", 15:50:17 INFO - "product": "firefox", 15:50:17 INFO - "who": "archaeopteryx@coole-files.de", 15:50:17 INFO - "installer_path": "public/build/target.tar.bz2", 15:50:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:50:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:50:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:50:17 INFO - "stage_platform": "linux64", 15:50:17 INFO - "basedir": "/builds/slave/test", 15:50:17 INFO - "buildnumber": 819, 15:50:17 INFO - "platform": "ubuntu64_hw", 15:50:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:50:17 INFO - "slavebuilddir": "test", 15:50:17 INFO - "taskId": "PLBECX0uQSi6m_iaJS52FQ", 15:50:17 INFO - "branch": "mozilla-central", 15:50:17 INFO - "script_repo_revision": "production", 15:50:17 INFO - "revision": "0b3646aa9cbf3f77ee574a553ade9685e9229ab5", 15:50:17 INFO - "slavename": "talos-linux64-ix-034", 15:50:17 INFO - "repo_path": "mozilla-central" 15:50:17 INFO - } 15:50:17 INFO - Finding installer, test and symbols from parent task. 15:50:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PLBECX0uQSi6m_iaJS52FQ'}, attempt #1 15:50:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PLBECX0uQSi6m_iaJS52FQ'}, attempt #1 15:50:18 INFO - Task dependencies: JQ2c2hz0RGOZPHj6Jy9JKA 15:50:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA'}, attempt #1 15:50:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2 15:50:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.test_packages.json 15:50:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.crashreporter-symbols.zip 15:50:19 INFO - Running post-action listener: _resource_record_post_action 15:50:19 INFO - [mozharness: 2017-09-13 22:50:19.228546Z] Finished read-buildbot-config step (success) 15:50:19 INFO - [mozharness: 2017-09-13 22:50:19.228764Z] Running download-and-extract step. 15:50:19 INFO - Running pre-action listener: _resource_record_pre_action 15:50:19 INFO - Running main action method: download_and_extract 15:50:19 INFO - mkdir: /builds/slave/test/build/tests 15:50:19 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')]} 15:50:19 INFO - trying https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.test_packages.json 15:50:19 INFO - Downloading https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:50:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:50:19 INFO - Downloaded 989 bytes. 15:50:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:50:19 INFO - Using the following test package requirements: 15:50:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:50:19 INFO - u'common': [u'target.common.tests.zip'], 15:50:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:50:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:50:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:50:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:50:19 INFO - u'mozbase': [u'target.common.tests.zip'], 15:50:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:50:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:50:19 INFO - u'web-platform': [u'target.common.tests.zip', 15:50:19 INFO - u'target.web-platform.tests.tar.gz'], 15:50:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:50:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:50:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.common.tests.zip 15:50:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:50:19 INFO - Fetch https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.common.tests.zip into memory 15:50:20 INFO - Content-Length response header: 54572968 15:50:20 INFO - Bytes received: 54572968 15:50:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.talos.tests.zip 15:50:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:50:31 INFO - Fetch https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.talos.tests.zip into memory 15:50:32 INFO - Content-Length response header: 14499226 15:50:32 INFO - Bytes received: 14499226 15:50:32 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')]} 15:50:32 INFO - trying https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2 15:50:32 INFO - Downloading https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:50:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:50:33 INFO - Downloaded 61501949 bytes. 15:50:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2 15:50:34 INFO - mkdir: /builds/slave/test/properties 15:50:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:50:34 INFO - Writing to file /builds/slave/test/properties/build_url 15:50:34 INFO - Contents: 15:50:34 INFO - build_url:https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2 15:50:34 INFO - Running post-action listener: _resource_record_post_action 15:50:34 INFO - Running post-action listener: find_tests_for_verification 15:50:34 INFO - Running post-action listener: set_extra_try_arguments 15:50:34 INFO - [mozharness: 2017-09-13 22:50:34.334203Z] Finished download-and-extract step (success) 15:50:34 INFO - [mozharness: 2017-09-13 22:50:34.334380Z] Running populate-webroot step. 15:50:34 INFO - Running pre-action listener: _resource_record_pre_action 15:50:34 INFO - Running main action method: populate_webroot 15:50:34 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:50:34 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['tresize', 15:50:34 INFO - 'tcanvasmark']}, 15:50:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:50:34 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['dromaeo_css', 15:50:34 INFO - 'kraken']}, 15:50:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:34 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'tests': ['damp', 'tps']}, 15:50:34 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['damp', 'tps']}, 15:50:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:50:34 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['dromaeo_dom']}, 15:50:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:50:34 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['basic_compositor_video', 15:50:34 INFO - 'glvideo']}, 15:50:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:50:34 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['ts_paint_webext', 15:50:34 INFO - 'tp5o_webext']}, 15:50:34 INFO - 'other-e10s': {'tests': ['a11yr', 15:50:34 INFO - 'ts_paint', 15:50:34 INFO - 'tpaint', 15:50:34 INFO - 'sessionrestore', 15:50:34 INFO - 'sessionrestore_many_windows', 15:50:34 INFO - 'sessionrestore_no_auto_restore', 15:50:34 INFO - 'tabpaint']}, 15:50:34 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['a11yr', 15:50:34 INFO - 'ts_paint', 15:50:34 INFO - 'tpaint', 15:50:34 INFO - 'sessionrestore', 15:50:34 INFO - 'sessionrestore_many_windows', 15:50:34 INFO - 'sessionrestore_no_auto_restore', 15:50:34 INFO - 'tabpaint']}, 15:50:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:50:34 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:50:34 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['bloom_basic_singleton']}, 15:50:34 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['bloom_basic']}, 15:50:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:50:34 INFO - 'tsvgr_opacity', 15:50:34 INFO - 'tart', 15:50:34 INFO - 'tscrollx', 15:50:34 INFO - 'tsvg_static']}, 15:50:34 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['tsvgx', 15:50:34 INFO - 'tsvgr_opacity', 15:50:34 INFO - 'tart', 15:50:34 INFO - 'tscrollx', 15:50:34 INFO - 'tsvg_static']}, 15:50:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:50:34 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'talos_options': ['--disable-stylo'], 15:50:34 INFO - 'tests': ['tp5o']}, 15:50:34 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:34 INFO - 'talos_options': ['--mitmproxy', 15:50:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:34 INFO - '--firstNonBlankPaint'], 15:50:34 INFO - 'tests': ['tp6_google', 15:50:34 INFO - 'tp6_youtube', 15:50:34 INFO - 'tp6_amazon', 15:50:34 INFO - 'tp6_facebook']}, 15:50:34 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:34 INFO - 'talos_options': ['--disable-stylo', 15:50:34 INFO - '--mitmproxy', 15:50:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:34 INFO - '--firstNonBlankPaint'], 15:50:34 INFO - 'tests': ['tp6_google', 15:50:34 INFO - 'tp6_youtube', 15:50:34 INFO - 'tp6_amazon', 15:50:34 INFO - 'tp6_facebook']}, 15:50:34 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:50:34 INFO - 'talos_options': ['--stylo-threads=1', 15:50:34 INFO - '--mitmproxy', 15:50:34 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:50:34 INFO - '--firstNonBlankPaint'], 15:50:34 INFO - 'tests': ['tp6_google', 15:50:34 INFO - 'tp6_youtube', 15:50:34 INFO - 'tp6_amazon', 15:50:34 INFO - 'tp6_facebook']}, 15:50:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'talos_options': ['--xperf_path', 15:50:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:34 INFO - 'tests': ['tp5n']}, 15:50:34 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:50:34 INFO - 'talos_options': ['--disable-stylo', 15:50:34 INFO - '--xperf_path', 15:50:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:34 INFO - 'tests': ['tp5n']}}} 15:50:34 INFO - Running post-action listener: _resource_record_post_action 15:50:34 INFO - [mozharness: 2017-09-13 22:50:34.357348Z] Finished populate-webroot step (success) 15:50:34 INFO - [mozharness: 2017-09-13 22:50:34.357438Z] Running create-virtualenv step. 15:50:34 INFO - Running pre-action listener: _resource_record_pre_action 15:50:34 INFO - Running main action method: create_virtualenv 15:50:34 INFO - Creating virtualenv /builds/slave/test/build/venv 15:50:34 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:50:34 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:50:34 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:50:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:50:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:50:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:50:35 INFO - Installing pip...............done. 15:50:35 INFO - Return code: 0 15:50:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:50:35 INFO - Reading from file tmpfile_stdout 15:50:35 INFO - Output received: 15:50:35 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:50:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:35 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:35 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 15:50:35 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 15:50:35 INFO - Using env: {'DISPLAY': ':0', 15:50:35 INFO - 'HOME': '/home/cltbld', 15:50:35 INFO - 'LANG': 'en_US.UTF-8', 15:50:35 INFO - 'LANGUAGE': 'en_US:en', 15:50:35 INFO - 'LOGNAME': 'cltbld', 15:50:35 INFO - 'MAIL': '/var/mail/cltbld', 15:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:35 INFO - 'MOZ_NO_REMOTE': '1', 15:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:35 INFO - 'NO_EM_RESTART': '1', 15:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:35 INFO - 'PWD': '/builds/slave/test', 15:50:35 INFO - 'SHELL': '/bin/bash', 15:50:35 INFO - 'SHLVL': '1', 15:50:35 INFO - 'TERM': 'linux', 15:50:35 INFO - 'TMOUT': '86400', 15:50:35 INFO - 'USER': 'cltbld', 15:50:35 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:35 INFO - '_': '/tools/buildbot/bin/python'} 15:50:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:50:36 INFO - Downloading/unpacking pip>=1.5 15:50:36 INFO - Running setup.py egg_info for package pip 15:50:36 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:36 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:36 INFO - Installing collected packages: pip 15:50:36 INFO - Found existing installation: pip 1.1 15:50:36 INFO - Uninstalling pip: 15:50:36 INFO - Successfully uninstalled pip 15:50:36 INFO - Running setup.py install for pip 15:50:36 INFO - warning: no files found matching 'pip/cacert.pem' 15:50:36 INFO - warning: no files found matching '*.html' under directory 'docs' 15:50:36 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:50:36 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:50:36 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:50:36 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:50:36 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:50:36 INFO - Successfully installed pip 15:50:36 INFO - Cleaning up... 15:50:36 INFO - Return code: 0 15:50:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:50:36 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')]} 15:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:36 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')]} 15:50:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:36 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:36 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 15:50:36 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 15:50:36 INFO - Using env: {'DISPLAY': ':0', 15:50:36 INFO - 'HOME': '/home/cltbld', 15:50:36 INFO - 'LANG': 'en_US.UTF-8', 15:50:36 INFO - 'LANGUAGE': 'en_US:en', 15:50:36 INFO - 'LOGNAME': 'cltbld', 15:50:36 INFO - 'MAIL': '/var/mail/cltbld', 15:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:36 INFO - 'MOZ_NO_REMOTE': '1', 15:50:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:36 INFO - 'NO_EM_RESTART': '1', 15:50:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:36 INFO - 'PWD': '/builds/slave/test', 15:50:36 INFO - 'SHELL': '/bin/bash', 15:50:36 INFO - 'SHLVL': '1', 15:50:36 INFO - 'TERM': 'linux', 15:50:36 INFO - 'TMOUT': '86400', 15:50:36 INFO - 'USER': 'cltbld', 15:50:36 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:36 INFO - '_': '/tools/buildbot/bin/python'} 15:50:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:36 INFO - Downloading/unpacking psutil>=3.1.1 15:50:36 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 15:50:36 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 15:50:36 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 15:50:36 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 15:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:37 INFO - Installing collected packages: psutil 15:50:37 INFO - Running setup.py install for psutil 15:50:38 INFO - building 'psutil._psutil_linux' extension 15:50:38 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 15:50:38 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 15:50:38 INFO - building 'psutil._psutil_posix' extension 15:50:38 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 15:50:38 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 15:50:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:38 INFO - Successfully installed psutil 15:50:38 INFO - Cleaning up... 15:50:38 INFO - Return code: 0 15:50:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:38 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:38 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 15:50:38 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 15:50:38 INFO - Using env: {'DISPLAY': ':0', 15:50:38 INFO - 'HOME': '/home/cltbld', 15:50:38 INFO - 'LANG': 'en_US.UTF-8', 15:50:38 INFO - 'LANGUAGE': 'en_US:en', 15:50:38 INFO - 'LOGNAME': 'cltbld', 15:50:38 INFO - 'MAIL': '/var/mail/cltbld', 15:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:38 INFO - 'MOZ_NO_REMOTE': '1', 15:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:38 INFO - 'NO_EM_RESTART': '1', 15:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:38 INFO - 'PWD': '/builds/slave/test', 15:50:38 INFO - 'SHELL': '/bin/bash', 15:50:38 INFO - 'SHLVL': '1', 15:50:38 INFO - 'TERM': 'linux', 15:50:38 INFO - 'TMOUT': '86400', 15:50:38 INFO - 'USER': 'cltbld', 15:50:38 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:38 INFO - '_': '/tools/buildbot/bin/python'} 15:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:50:38 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 15:50:38 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 15:50:38 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 15:50:38 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 15:50:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:50:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:50:39 INFO - Installing collected packages: mozsystemmonitor 15:50:39 INFO - Running setup.py install for mozsystemmonitor 15:50:40 INFO - Successfully installed mozsystemmonitor 15:50:40 INFO - Cleaning up... 15:50:40 INFO - Return code: 0 15:50:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:50:40 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')]} 15:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:40 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')]} 15:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:40 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:40 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 15:50:40 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 15:50:40 INFO - Using env: {'DISPLAY': ':0', 15:50:40 INFO - 'HOME': '/home/cltbld', 15:50:40 INFO - 'LANG': 'en_US.UTF-8', 15:50:40 INFO - 'LANGUAGE': 'en_US:en', 15:50:40 INFO - 'LOGNAME': 'cltbld', 15:50:40 INFO - 'MAIL': '/var/mail/cltbld', 15:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:40 INFO - 'MOZ_NO_REMOTE': '1', 15:50:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:40 INFO - 'NO_EM_RESTART': '1', 15:50:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:40 INFO - 'PWD': '/builds/slave/test', 15:50:40 INFO - 'SHELL': '/bin/bash', 15:50:40 INFO - 'SHLVL': '1', 15:50:40 INFO - 'TERM': 'linux', 15:50:40 INFO - 'TMOUT': '86400', 15:50:40 INFO - 'USER': 'cltbld', 15:50:40 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:40 INFO - '_': '/tools/buildbot/bin/python'} 15:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:40 INFO - Downloading/unpacking jsonschema==2.5.1 15:50:40 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 15:50:40 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 15:50:40 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 15:50:40 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 15:50:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:50:41 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 15:50:41 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 15:50:41 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 15:50:41 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 15:50:41 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:41 INFO - warning: no files found matching '*.txt' 15:50:41 INFO - no previously-included directories found matching 'build' 15:50:41 INFO - no previously-included directories found matching 'dist' 15:50:41 INFO - no previously-included directories found matching '.git*' 15:50:41 INFO - Installing collected packages: jsonschema, functools32 15:50:42 INFO - Running setup.py install for functools32 15:50:43 INFO - warning: no files found matching '*.txt' 15:50:43 INFO - no previously-included directories found matching 'build' 15:50:43 INFO - no previously-included directories found matching 'dist' 15:50:43 INFO - no previously-included directories found matching '.git*' 15:50:43 INFO - Successfully installed jsonschema functools32 15:50:43 INFO - Cleaning up... 15:50:43 INFO - Return code: 0 15:50:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:43 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:43 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 15:50:43 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 15:50:43 INFO - Using env: {'DISPLAY': ':0', 15:50:43 INFO - 'HOME': '/home/cltbld', 15:50:43 INFO - 'LANG': 'en_US.UTF-8', 15:50:43 INFO - 'LANGUAGE': 'en_US:en', 15:50:43 INFO - 'LOGNAME': 'cltbld', 15:50:43 INFO - 'MAIL': '/var/mail/cltbld', 15:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:43 INFO - 'MOZ_NO_REMOTE': '1', 15:50:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:43 INFO - 'NO_EM_RESTART': '1', 15:50:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:43 INFO - 'PWD': '/builds/slave/test', 15:50:43 INFO - 'SHELL': '/bin/bash', 15:50:43 INFO - 'SHLVL': '1', 15:50:43 INFO - 'TERM': 'linux', 15:50:43 INFO - 'TMOUT': '86400', 15:50:43 INFO - 'USER': 'cltbld', 15:50:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:43 INFO - '_': '/tools/buildbot/bin/python'} 15:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:50:43 INFO - Cleaning up... 15:50:43 INFO - Return code: 0 15:50:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:43 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:43 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 15:50:43 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 15:50:43 INFO - Using env: {'DISPLAY': ':0', 15:50:43 INFO - 'HOME': '/home/cltbld', 15:50:43 INFO - 'LANG': 'en_US.UTF-8', 15:50:43 INFO - 'LANGUAGE': 'en_US:en', 15:50:43 INFO - 'LOGNAME': 'cltbld', 15:50:43 INFO - 'MAIL': '/var/mail/cltbld', 15:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:43 INFO - 'MOZ_NO_REMOTE': '1', 15:50:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:43 INFO - 'NO_EM_RESTART': '1', 15:50:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:43 INFO - 'PWD': '/builds/slave/test', 15:50:43 INFO - 'SHELL': '/bin/bash', 15:50:43 INFO - 'SHLVL': '1', 15:50:43 INFO - 'TERM': 'linux', 15:50:43 INFO - 'TMOUT': '86400', 15:50:43 INFO - 'USER': 'cltbld', 15:50:43 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:43 INFO - '_': '/tools/buildbot/bin/python'} 15:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:43 INFO - Downloading/unpacking blobuploader==1.2.4 15:50:43 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 15:50:43 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 15:50:43 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 15:50:43 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 15:50:44 INFO - Downloading blobuploader-1.2.4.tar.gz 15:50:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:50:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:50:44 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 15:50:44 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 15:50:44 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 15:50:44 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 15:50:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:50:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:50:45 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 15:50:45 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 15:50:45 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 15:50:45 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 15:50:45 INFO - Downloading docopt-0.6.1.tar.gz 15:50:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:50:45 INFO - Installing collected packages: blobuploader, requests, docopt 15:50:45 INFO - Running setup.py install for blobuploader 15:50:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:50:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:50:45 INFO - Running setup.py install for requests 15:50:45 INFO - Running setup.py install for docopt 15:50:45 INFO - Successfully installed blobuploader requests docopt 15:50:45 INFO - Cleaning up... 15:50:46 INFO - Return code: 0 15:50:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:46 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')]} 15:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:46 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')]} 15:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:46 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:46 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 15:50:46 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 15:50:46 INFO - Using env: {'DISPLAY': ':0', 15:50:46 INFO - 'HOME': '/home/cltbld', 15:50:46 INFO - 'LANG': 'en_US.UTF-8', 15:50:46 INFO - 'LANGUAGE': 'en_US:en', 15:50:46 INFO - 'LOGNAME': 'cltbld', 15:50:46 INFO - 'MAIL': '/var/mail/cltbld', 15:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:46 INFO - 'MOZ_NO_REMOTE': '1', 15:50:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:46 INFO - 'NO_EM_RESTART': '1', 15:50:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:46 INFO - 'PWD': '/builds/slave/test', 15:50:46 INFO - 'SHELL': '/bin/bash', 15:50:46 INFO - 'SHLVL': '1', 15:50:46 INFO - 'TERM': 'linux', 15:50:46 INFO - 'TMOUT': '86400', 15:50:46 INFO - 'USER': 'cltbld', 15:50:46 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:46 INFO - '_': '/tools/buildbot/bin/python'} 15:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:46 INFO - Running setup.py (path:/tmp/pip-of1PA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:46 INFO - Running setup.py (path:/tmp/pip-6CSZvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:46 INFO - Running setup.py (path:/tmp/pip-1Ydq5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:46 INFO - Running setup.py (path:/tmp/pip-5B6jH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:46 INFO - Running setup.py (path:/tmp/pip-OD4GDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:46 INFO - Running setup.py (path:/tmp/pip-Tz5M_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:46 INFO - Running setup.py (path:/tmp/pip-FSzTZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:46 INFO - Running setup.py (path:/tmp/pip-TO86p8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:46 INFO - Running setup.py (path:/tmp/pip-Uirxm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:46 INFO - Running setup.py (path:/tmp/pip-CseNCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:46 INFO - Running setup.py (path:/tmp/pip-JCVjEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:46 INFO - Running setup.py (path:/tmp/pip-C7Nnwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:47 INFO - Running setup.py (path:/tmp/pip-4XU0MW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:47 INFO - Running setup.py (path:/tmp/pip-yKwDSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:47 INFO - Running setup.py (path:/tmp/pip-2m0XPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:47 INFO - Running setup.py (path:/tmp/pip-0xCsQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:47 INFO - Running setup.py (path:/tmp/pip-3f6Saq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:50:47 INFO - Running setup.py install for manifestparser 15:50:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Running setup.py install for mozcrash 15:50:47 INFO - Running setup.py install for mozdebug 15:50:47 INFO - Running setup.py install for mozdevice 15:50:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Running setup.py install for mozfile 15:50:47 INFO - Running setup.py install for mozhttpd 15:50:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Running setup.py install for mozinfo 15:50:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Running setup.py install for mozInstall 15:50:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Running setup.py install for mozleak 15:50:48 INFO - Running setup.py install for mozlog 15:50:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Running setup.py install for moznetwork 15:50:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Running setup.py install for mozprocess 15:50:48 INFO - Running setup.py install for mozprofile 15:50:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Running setup.py install for mozrunner 15:50:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:50:48 INFO - Running setup.py install for mozscreenshot 15:50:48 INFO - Running setup.py install for moztest 15:50:49 INFO - Running setup.py install for mozversion 15:50:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:50:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:50:49 INFO - Cleaning up... 15:50:49 INFO - Return code: 0 15:50:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50: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')]} 15:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:49 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:49 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 15:50:49 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 15:50:49 INFO - Using env: {'DISPLAY': ':0', 15:50:49 INFO - 'HOME': '/home/cltbld', 15:50:49 INFO - 'LANG': 'en_US.UTF-8', 15:50:49 INFO - 'LANGUAGE': 'en_US:en', 15:50:49 INFO - 'LOGNAME': 'cltbld', 15:50:49 INFO - 'MAIL': '/var/mail/cltbld', 15:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:49 INFO - 'MOZ_NO_REMOTE': '1', 15:50:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:49 INFO - 'NO_EM_RESTART': '1', 15:50:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:49 INFO - 'PWD': '/builds/slave/test', 15:50:49 INFO - 'SHELL': '/bin/bash', 15:50:49 INFO - 'SHLVL': '1', 15:50:49 INFO - 'TERM': 'linux', 15:50:49 INFO - 'TMOUT': '86400', 15:50:49 INFO - 'USER': 'cltbld', 15:50:49 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:49 INFO - '_': '/tools/buildbot/bin/python'} 15:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:49 INFO - Running setup.py (path:/tmp/pip-XSLE78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:49 INFO - Running setup.py (path:/tmp/pip-EBgVQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:49 INFO - Running setup.py (path:/tmp/pip-YQFYOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:49 INFO - Running setup.py (path:/tmp/pip-cI2YMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:49 INFO - Running setup.py (path:/tmp/pip-vEjfPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:49 INFO - Running setup.py (path:/tmp/pip-weRubP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:49 INFO - Running setup.py (path:/tmp/pip-uthf_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:49 INFO - Running setup.py (path:/tmp/pip-Kraq18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:49 INFO - Running setup.py (path:/tmp/pip-FXdhHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:50 INFO - Running setup.py (path:/tmp/pip-wdkaSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:50 INFO - Running setup.py (path:/tmp/pip-x8XzHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:50 INFO - Running setup.py (path:/tmp/pip-fgLG5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:50 INFO - Running setup.py (path:/tmp/pip-E110kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:50 INFO - Running setup.py (path:/tmp/pip-JfBLeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:50 INFO - Running setup.py (path:/tmp/pip-vP_KTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:50 INFO - Running setup.py (path:/tmp/pip-4MVSz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:50 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)) 15:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:50 INFO - Running setup.py (path:/tmp/pip-Nv8Ltw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:50 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)) 15:50:50 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)) 15:50:50 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)) 15:50:50 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)) 15:50:50 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)) 15:50:50 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)) 15:50:50 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)) 15:50:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:50: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 15:50: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 15:50: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 15:50: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 15:50:51 INFO - Downloading blessings-1.6.tar.gz 15:50:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:50:51 INFO - Installing collected packages: blessings 15:50:51 INFO - Running setup.py install for blessings 15:50:51 INFO - Successfully installed blessings 15:50:51 INFO - Cleaning up... 15:50:51 INFO - Return code: 0 15:50:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:50:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:52 INFO - Reading from file tmpfile_stdout 15:50:52 INFO - Current package versions: 15:50:52 INFO - argparse == 1.2.1 15:50:52 INFO - blessings == 1.6 15:50:52 INFO - blobuploader == 1.2.4 15:50:52 INFO - docopt == 0.6.1 15:50:52 INFO - functools32 == 3.2.3-2 15:50:52 INFO - jsonschema == 2.5.1 15:50:52 INFO - manifestparser == 1.1 15:50:52 INFO - mozInstall == 1.13 15:50:52 INFO - mozcrash == 1.0 15:50:52 INFO - mozdebug == 0.1 15:50:52 INFO - mozdevice == 0.51 15:50:52 INFO - mozfile == 1.2 15:50:52 INFO - mozhttpd == 0.7 15:50:52 INFO - mozinfo == 0.10 15:50:52 INFO - mozleak == 0.1 15:50:52 INFO - mozlog == 3.5 15:50:52 INFO - moznetwork == 0.27 15:50:52 INFO - mozprocess == 0.25 15:50:52 INFO - mozprofile == 0.28 15:50:52 INFO - mozrunner == 6.13 15:50:52 INFO - mozscreenshot == 0.1 15:50:52 INFO - mozsystemmonitor == 0.3 15:50:52 INFO - moztest == 0.8 15:50:52 INFO - mozversion == 1.4 15:50:52 INFO - psutil == 3.1.1 15:50:52 INFO - requests == 1.2.3 15:50:52 INFO - wsgiref == 0.1.2 15:50:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:52 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')]} 15:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:52 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')]} 15:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:52 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:52 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 15:50:52 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 15:50:52 INFO - Using env: {'DISPLAY': ':0', 15:50:52 INFO - 'HOME': '/home/cltbld', 15:50:52 INFO - 'LANG': 'en_US.UTF-8', 15:50:52 INFO - 'LANGUAGE': 'en_US:en', 15:50:52 INFO - 'LOGNAME': 'cltbld', 15:50:52 INFO - 'MAIL': '/var/mail/cltbld', 15:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:52 INFO - 'MOZ_NO_REMOTE': '1', 15:50:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:52 INFO - 'NO_EM_RESTART': '1', 15:50:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:52 INFO - 'PWD': '/builds/slave/test', 15:50:52 INFO - 'SHELL': '/bin/bash', 15:50:52 INFO - 'SHLVL': '1', 15:50:52 INFO - 'TERM': 'linux', 15:50:52 INFO - 'TMOUT': '86400', 15:50:52 INFO - 'USER': 'cltbld', 15:50:52 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:52 INFO - '_': '/tools/buildbot/bin/python'} 15:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:52 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)) 15:50:52 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)) 15:50:52 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)) 15:50:52 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)) 15:50:52 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)) 15:50:52 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)) 15:50:52 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)) 15:50:52 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)) 15:50:52 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)) 15:50:52 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:50: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 15:50: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 15:50: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 15:50: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 15:50:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:50:53 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)) 15:50:53 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)) 15:50:53 INFO - Installing collected packages: simplejson 15:50:53 INFO - Running setup.py install for simplejson 15:50:53 INFO - building 'simplejson._speedups' extension 15:50:53 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 15:50:54 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 15:50:54 INFO - Successfully installed simplejson 15:50:54 INFO - Cleaning up... 15:50:54 INFO - Return code: 0 15:50:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:54 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 0x1ea61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x206fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2212a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', '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 15:50:54 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 15:50:54 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 15:50:54 INFO - Using env: {'DISPLAY': ':0', 15:50:54 INFO - 'HOME': '/home/cltbld', 15:50:54 INFO - 'LANG': 'en_US.UTF-8', 15:50:54 INFO - 'LANGUAGE': 'en_US:en', 15:50:54 INFO - 'LOGNAME': 'cltbld', 15:50:54 INFO - 'MAIL': '/var/mail/cltbld', 15:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:54 INFO - 'MOZ_NO_REMOTE': '1', 15:50:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:54 INFO - 'NO_EM_RESTART': '1', 15:50:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:54 INFO - 'PWD': '/builds/slave/test', 15:50:54 INFO - 'SHELL': '/bin/bash', 15:50:54 INFO - 'SHLVL': '1', 15:50:54 INFO - 'TERM': 'linux', 15:50:54 INFO - 'TMOUT': '86400', 15:50:54 INFO - 'USER': 'cltbld', 15:50:54 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:54 INFO - '_': '/tools/buildbot/bin/python'} 15:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:50:54 INFO - Cleaning up... 15:50:54 INFO - Return code: 0 15:50:54 INFO - Running post-action listener: _resource_record_post_action 15:50:54 INFO - Running post-action listener: _start_resource_monitoring 15:50:54 INFO - Starting resource monitoring. 15:50:54 INFO - [mozharness: 2017-09-13 22:50:54.848321Z] Finished create-virtualenv step (success) 15:50:54 INFO - [mozharness: 2017-09-13 22:50:54.848894Z] Running install step. 15:50:54 INFO - Running pre-action listener: _resource_record_pre_action 15:50:54 INFO - Running main action method: install 15:50:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:55 INFO - Reading from file tmpfile_stdout 15:50:55 INFO - Detecting whether we're running mozinstall >=1.0... 15:50:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:50:55 INFO - Reading from file tmpfile_stdout 15:50:55 INFO - Output received: 15:50:55 INFO - Usage: mozinstall [options] installer 15:50:55 INFO - Options: 15:50:55 INFO - -h, --help show this help message and exit 15:50:55 INFO - -d DEST, --destination=DEST 15:50:55 INFO - Directory to install application into. [default: 15:50:55 INFO - "/builds/slave/test"] 15:50:55 INFO - --app=APP Application being installed. [default: firefox] 15:50:55 INFO - mkdir: /builds/slave/test/build/application 15:50:55 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'] 15:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:51:12 INFO - Reading from file tmpfile_stdout 15:51:12 INFO - Output received: 15:51:12 INFO - /builds/slave/test/build/application/firefox/firefox 15:51:12 INFO - Running post-action listener: _resource_record_post_action 15:51:12 INFO - [mozharness: 2017-09-13 22:51:12.946707Z] Finished install step (success) 15:51:12 INFO - [mozharness: 2017-09-13 22:51:12.946852Z] Running setup-mitmproxy step. 15:51:12 INFO - Running pre-action listener: _resource_record_pre_action 15:51:12 INFO - Running main action method: setup_mitmproxy 15:51:12 INFO - Skipping: mitmproxy is not required 15:51:12 INFO - Running post-action listener: _resource_record_post_action 15:51:12 INFO - [mozharness: 2017-09-13 22:51:12.947381Z] Finished setup-mitmproxy step (success) 15:51:12 INFO - [mozharness: 2017-09-13 22:51:12.947486Z] Running run-tests step. 15:51:12 INFO - Running pre-action listener: _resource_record_pre_action 15:51:12 INFO - Running pre-action listener: _set_gcov_prefix 15:51:12 INFO - Running main action method: run_tests 15:51:12 WARNING - Try message not found. 15:51:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:51:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:51:12 INFO - Python 2.7.3 15:51:12 INFO - Return code: 0 15:51:12 INFO - grabbing minidump binary from tooltool 15:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:12 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 0x2216090>, '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 0x2216d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2210f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:51:12 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 15:51:12 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 15:51:12 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 15:51:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:51:13 INFO - Return code: 0 15:51:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:51:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:51:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:51:13 INFO - ENV: RUST_BACKTRACE is now full 15:51:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:51:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:51:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 15:51:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 15:51:13 INFO - Using env: {'DISPLAY': ':0', 15:51:13 INFO - 'HOME': '/home/cltbld', 15:51:13 INFO - 'LANG': 'en_US.UTF-8', 15:51:13 INFO - 'LANGUAGE': 'en_US:en', 15:51:13 INFO - 'LOGNAME': 'cltbld', 15:51:13 INFO - 'MAIL': '/var/mail/cltbld', 15:51:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:13 INFO - 'MOZ_NO_REMOTE': '1', 15:51:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:13 INFO - 'NO_EM_RESTART': '1', 15:51:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:13 INFO - 'PWD': '/builds/slave/test', 15:51:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:13 INFO - 'RUST_BACKTRACE': 'full', 15:51:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:13 INFO - 'SHELL': '/bin/bash', 15:51:13 INFO - 'SHLVL': '1', 15:51:13 INFO - 'TERM': 'linux', 15:51:13 INFO - 'TMOUT': '86400', 15:51:13 INFO - 'USER': 'cltbld', 15:51:13 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:13 INFO - '_': '/tools/buildbot/bin/python'} 15:51:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 15:51:13 INFO - mozversion application_buildid: 20170913215701 15:51:13 INFO - mozversion application_changeset: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 15:51:13 INFO - mozversion application_display_name: Nightly 15:51:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:13 INFO - mozversion application_name: Firefox 15:51:13 INFO - mozversion application_remotingname: firefox 15:51:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:51:13 INFO - mozversion application_vendor: Mozilla 15:51:13 INFO - mozversion application_version: 57.0a1 15:51:13 INFO - mozversion platform_buildid: 20170913215701 15:51:13 INFO - mozversion platform_changeset: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 15:51:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:51:13 INFO - mozversion platform_version: 57.0a1 15:51:13 INFO - using testdate: 1505343073 15:51:13 INFO - actual date: 1505343073 15:51:13 INFO - starting webserver on 'localhost:58380' 15:51:13 INFO - SUITE-START | Running 2 tests 15:51:13 INFO - TEST-START | dromaeo_css 15:51:13 INFO - Initialising browser for dromaeo_css test... 15:51:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58380/getInfo.html -profile /tmp/tmpSqilWV/profile 15:51:13 INFO - TEST-INFO | started process 24200 (/builds/slave/test/build/application/firefox/firefox http://localhost:58380/getInfo.html) 15:51:18 INFO - TEST-INFO | 24200: exit 0 15:51:18 INFO - Browser initialized. 15:51:18 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:18 INFO - Using env: {'DISPLAY': ':0', 15:51:18 INFO - 'HOME': '/home/cltbld', 15:51:18 INFO - 'JSGC_DISABLE_POISONING': '1', 15:51:18 INFO - 'LANG': 'en_US.UTF-8', 15:51:18 INFO - 'LANGUAGE': 'en_US:en', 15:51:18 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:51:18 INFO - 'LOGNAME': 'cltbld', 15:51:18 INFO - 'MAIL': '/var/mail/cltbld', 15:51:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:18 INFO - 'MOZ_CRASHREPORTER': '1', 15:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:18 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:51:18 INFO - 'MOZ_NO_REMOTE': '1', 15:51:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:18 INFO - 'NO_EM_RESTART': '1', 15:51:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:18 INFO - 'PWD': '/builds/slave/test', 15:51:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:18 INFO - 'RUST_BACKTRACE': 'full', 15:51:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:51:18 INFO - 'SHELL': '/bin/bash', 15:51:18 INFO - 'SHLVL': '1', 15:51:18 INFO - 'TERM': 'linux', 15:51:18 INFO - 'TMOUT': '86400', 15:51:18 INFO - 'USER': 'cltbld', 15:51:18 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 15:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:18 INFO - '_': '/tools/buildbot/bin/python'} 15:51:18 INFO - TEST-INFO | started process 24428 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSqilWV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:19 INFO - PID 24428 | 15:51:19 INFO - PID 24428 | (/builds/slave/test/build/application/firefox/firefox:24480): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:51:19 INFO - PID 24428 | 15:51:30 INFO - PID 24428 | RSS: Main: 175800320 15:51:30 INFO - PID 24428 | 15:54:27 INFO - PID 24428 | Cycle 1(1): loaded http://localhost:58380/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58380/tests/dromaeo/cssquery-ext.html) 15:54:28 INFO - PID 24428 | RSS: Main: 178950144 15:54:28 INFO - PID 24428 | 15:57:30 INFO - PID 24428 | Cycle 1(1): loaded http://localhost:58380/tests/dromaeo/cssquery-ext.html (next: http://localhost:58380/tests/dromaeo/cssquery-jquery.html) 15:57:31 INFO - PID 24428 | RSS: Main: 175427584 15:57:31 INFO - PID 24428 | 16:00:32 INFO - PID 24428 | Cycle 1(1): loaded http://localhost:58380/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58380/tests/dromaeo/cssquery-mootools.html) 16:00:33 INFO - PID 24428 | RSS: Main: 177512448 16:00:33 INFO - PID 24428 | 16:03:35 INFO - PID 24428 | Cycle 1(1): loaded http://localhost:58380/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58380/tests/dromaeo/cssquery-prototype.html) 16:03:35 INFO - PID 24428 | RSS: Main: 175435776 16:03:35 INFO - PID 24428 | 16:06:39 INFO - PID 24428 | Cycle 1(1): loaded http://localhost:58380/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58380/tests/dromaeo/cssquery-yui.html) 16:06:39 INFO - PID 24428 | RSS: Main: 177131520 16:06:39 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | Cycle 1(1): loaded http://localhost:58380/tests/dromaeo/cssquery-yui.html (next: http://localhost:58380/tests/dromaeo/cssquery-dojo.html) 16:09:41 INFO - PID 24428 | RSS: Main: 179609600 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | __start_tp_report 16:09:41 INFO - PID 24428 | _x_x_mozilla_page_load 16:09:41 INFO - PID 24428 | _x_x_mozilla_page_load_details 16:09:41 INFO - PID 24428 | |i|pagename|runs| 16:09:41 INFO - PID 24428 | |0;dojo.html;3138.309571894183;3150.2439414540518;3155.274286914009;3160.8577614007368;3164.9208769780753;6194.411530904568;6252.812415627533;6263.279722831876;6371.649559274244;6386.010168423896;6255.906161407571;6274.090256912757;6322.399372059658;6418.614883107017;6426.325235850239;1311.4505637298325;5833.970830145843;5883.058710606304;5911.9113213301835;5933.406659334075;5692.743826527795;5775.133729940521;5845.2401187845735;5860.970695146519;5863.530917526609;5793.478586927196;5798.246227990356;5826.854328641797;5849.181114643953;5864.824055278348;5743.368229494752;5768.163616275617;5850.444207800252;5852.180694702745;5853.09277062056;5756.079027355603;5765.971170144165;5812.505936995318;5834.6790926499025;5870.0607902735355;6104.4200800923845;6227.1282020517165;6269.780557680505;6333.524985626096;6345.143405640272;6565.376289252561;6641.203055633355;6645.269020407752;6652.368025037614;6710.93289067108;6774.898376524356;6780;6796.116504854387;6819.965900170517;6821.556598821061;6156.907646385308;6257.436830685215;6266.530010249249;6299.968500157515;6314.116023756677;6881.10545629075;7012.789616311519;7013.26360732126;7034.331738484836;7034.40207582351;6629.337066293332;6631.071649969008;6702;6722.6302553359055;6723.5293529452465;6715.731370745116;6750.7299708012115;6805.93194068053;6811.046453496514;6820.113385259884;6699.665016749143;6701;6756.0879281297375;6759.932400676029;6802.081718967975;6738.292479289702;6751.054852320679;6823.38589526945;6850.897236541456;6851.623160726108;5217.243499692523;5314.707691077027;5316.73416329182;5400.621956463088;5404.594655400782;4593.127305811952;4621.861344159681;4698.600618947427;4707.905841883212;4731.645126615517;5800.187973683687;5803.303603567599;5863.736131874076;5888.263967004124;5893.911591326134;6705.530612857053;6790.422814060761;6820.079289295981;6874.553154044971;6884.862302753917;5732.082866741302;5734.5125664319;5773.740181691834;5788.334341550775;5790.652560846363;5713.22871412354;5798.652080875161;5804.390538993429;5810.4189581041555;5824.767009319665;6495.123158373559;6498.805035848932;6500.284968653539;6597.736090556324;6612.338766123446;6591.176102987222;6667.799966001027;6697.598144111369;6842.897356539556;6858.074159988537;6527.67361631926;6558.114654521769;6570.310117437601;6598.769043083469;6602.471802255904;6598.37315455031;6695.06269122333;6764.3573860483175;6774.559653622498;6780.830479238059;6687.431568316553;6770.356816102462;6773.119494465688;6811.761588344384;6923.515353925177;6498.642574658439;6518.57629254097;6532.5753826001155;6603.20761508622;6644.568103073285;6532.738690452328;6575.704093315717;6593.703283352356;6604.24051234095;6641.402273795382;6460.580062296123;6469.805905822833;6475.579087359307;6575.112359831553;6588.374104460068;6546.541742078009;6641.302663220292;6644.269130395749;6669.333066693486;6704.6982885769285;6451.580647257913;6505.804825855232;6514.1531600891585;6516.152900122954;6538.476921846336 16:09:41 INFO - PID 24428 | |1;ext.html;789.7551758954721;803.4937989066889;827.4332082523472;831.5052543736479;839.2111415269642;40437.59562404375;41316.58683413169;41361.17277654445;41426.58573414265;41479.377809332844;41434;42433.78783106088;42440.36339454911;42441.78779106108;42448.57551424485;7706.344960678349;7860;7860.056793184825;7864.174261702525;7868.252516010998;7764.3788496920115;7883.448158628898;7894.289513943744;7903.446758726892;7907.683692652287;7878;7903.328217101524;7918.604069796516;7922.36621070313;7924.603769811515;7875.212478752136;7901.051873775126;7911.327537159348;7911.367090632764;7916.604169791487;7761;7784.182660820646;7789.454738168331;7814.609269536557;7825.608719564056;37548.43677344815;37734.43398349027;37795.81102094472;37795.811020944995;37834.43248351277;3503.8248087595775;3528.2943411317838;3529.6823285904393;3536.7701099428714;3539.132912436444;30739.692603073905;30848.38303233923;31146.844265778753;31147.532787008215;31291.217719556964;5001.624878134155;5130.897382052338;5170.069387510247;5185.922211166836;5195.064888320101;182729;182889;182922.99999999866;183750;184817;45972.54027459749;45987.08025839464;46099.30851037237;46148.07741342024;46286;48448.27327590089;48464.7576762114;48686.75656621694;48688.5131148684;48721.512784871695;15284;15648.295826687827;15658.765118523232;15661;15674.9216253918;12925.482980680856;12928.159669621444;12930.935345323212;12957.676058098434;12963.092583519063;12743.808842867364;12963.935180324039;12975.675608109872;13001;13107.606771796862;5217.921731174036;5223.817166399158;5282.973585132127;5321.122014867581;5324.8136315228785;995.2883688162854;998.6005597760809;1008.8574091704288;1009.4195837393528;1012.8480727890875;22368;22518.66222006671;22686.319410417713;22921;22954;1076.34343050739;1103.2056919018294;1113.4098927568402;1116.1126904111252;1117.284937639911;11687.649370518899;11781.705457363634;12119.757604847853;12138.817917731241;12148.088893333035;221839;224085;224509;224547;224903;50219;56096;59257.111143332884;59680.70159649261;59856.10215846766;1467.449706360115;1481.9703605928037;1490.4708828365774;1506.7664512000658;1509.7886295918581;27304.59043114315;27506.587401189397;27694.861525692242;27928.30179245535;28021;37783.2443351137;38057.42913856239;38065.23869522649;38151.42772858354;38183.618163818006;204086;205449;205453;205485;205695;34672.47991280082;35309.823450882584;35433.82283088568;35672.28655426929;35703.464448032784;41676.37485437781;43153;43530.12939741124;44040.55959440365;44165.33751993787;30695.2326191847;31175.376492470477;31213.68786312108;31246.37507249797;31321.37357252888;30927.845360773954;30978.535321970638;31079.533806993364;31294.37411251808;31432.68567314389;34743.47884781781;34749.82625086858;34791.130221743646;34817.30365392729;34819.65180348164;30608.693913060586;30650.540241896837;30679.2330191747;31256.437178141456;31391.215219618778;22236.424915740856;22573;22800.087996479953;22805;22940.197093101662 16:09:41 INFO - PID 24428 | |2;jquery.html;561.5591760468033;562.6483447845097;567.4212303450478;576.8673205162816;592.860677740731;21854;21997.890010549923;22766;22791.658125128135;22891.084356625714;23495.765042349572;25449.363765905862;25469;25501.489970200586;25508.362290942736;6372.681365931708;6506.642134682591;6518.837029074263;6520.543561950665;6520.576162549442;5905.881882362351;6458.677066146697;6486.286508484087;6489.351064893496;6502.2197336319705;6446.323136070715;6457.515686323521;6462.353764623524;6464.418202361787;6467.83830404239;6408.263049749292;6454.903176452357;6454.967725161391;6458.579112595245;6468.029795530708;5986.042233242661;5994.340622531518;6003.339632640406;6005.279366476007;6007.579469437142;28843.42313153746;28998.42003159925;29415.11754647364;29439.558406623917;29629.962951296602;1970.9113089910988;2014.224523558426;2016.1431391658487;2017.6368253714325;2018.1624625780285;18944.336948206885;19173.424797256106;20192.69710954322;20209.79790202094;20238;2385.2128797496785;2390.270967354956;2450.0689737899484;2464.162184857157;2465.963010554843;148328;155247;155575.22212388978;156261;156374;14618.565010373573;15025.422330655343;15198.696026079637;15198.848011519964;15360;23348;23407.297781066594;23418.882905585364;23424.414389640053;23446.296611101352;9853.753656158653;9999.200063994864;10068;10151.492425378701;10331.070203681706;9266.351355405057;9382.437053776659;9424.387414818015;9454.85817712735;9471.857922131174;8961.910380896237;9115.589798459085;9312.463443531877;9412.199963003084;9555.331126821056;2677.2102229842244;2700.7434293742062;2713.253855189799;2757.2215920731014;2820.7461328480535;957.7797106665506;984.3945973226557;986.7187851462443;987.8814542254831;1003.7239759066315;19990.60018799616;20048.59902801936;20061.598768024556;20117.698234526495;20282.69575956362;1040.5109598488698;1043.3896170740202;1050.0811789684026;1053.0732954999705;1053.1785207538132;11079.612213572485;11089.207735495946;11129.777404451866;11145.331280123057;11267.38029408374;417069;420716;427254;428997;431839;111315.44342278237;111650.44174779237;112020.43989780163;112975;113685.43157284326;1047.994760026195;1095.6384393150402;1135.647949135754;1178.092868491274;1194.3073017649742;20551.280705175246;20680.276190333265;20786.480337991066;20937.685934710687;20987.685184721937;52720.73639631777;54102;54165;54609.45390546044;55890.44109559015;66577;67112;67451;67831;68354;14394.136351818925;14707.779383309473;14771.778423323443;14811.555653330417;14811.777823332442;23069.88465057664;23081.769182307962;23129.653055204522;23138;23445.88277058604;18389;18585.34951276699;18657.72013419827;18691.25234990585;18799.530011749815;15736.685266294839;16583.336666533203;16603.750943736097;16735.246913889147;17134.143292835557;18521.53696157607;18580.81419185791;18901.905490472458;18992.620147596695;19082.236710531422;14387.712245754818;14486.637834054232;14506.927465362605;14513;14529.927350363181;18224.088795560434;18348.26606935761;18356.632867342847;18538.073096345397;18581.535461613566 16:09:41 INFO - PID 24428 | |3;mootools.html;1298.7986862036385;1359.4086572341032;1363.72725454909;1369.965750856229;1382.0878220374548;14467.421303147861;14862.554123376289;14875.776863347044;14877.553673389815;15029.173395463245;14651.267436628179;14708.117512949231;14739.410423583071;14750.188739619316;14751;2602.7397260273915;2630.1452028090853;2672.2384120525653;2704.634874291975;2705.201965420206;2614.7777438917715;2645.0213421034246;2646.4310173312733;2657.0833062593374;2662.2279538933685;2484.627305904111;2617.371830760613;2690.9730902691017;2709.133077415228;2714.8506832124226;2511.2842839790665;2529.304441278645;2542.6058960861096;2553.6041913503436;2556.0925871315785;2183.323169817346;2252.256755270757;2636.775874050708;2642.4979253941688;2654.8008899332626;14500.637484062878;14925.402983880631;14955.775663365059;14956.40174393023;14959.252037398195;7652.196519365442;7859;7937.2459616336355;7942.324902383304;7961.1640777718085;5299.205119232146;5451.182322651594;5473.726313684339;5483.835484935458;5528.281323427928;1813.2837529175902;1892.9716054259197;1893.6307420053095;1940.9514762130918;1951.6487032334173;26225;26536.203913882808;26557.867210663822;26607.467850643072;26620.73379266202;6547.574407663582;6583.63789991555;6592.307807680125;6743.5953842769595;6889.655517224119;17046.829531704523;17087.316507339816;17101;17111.22999465027;17126.40057597978;7442.8883566746545;7635.541867487968;7648.73229436967;7668.961655191688;7679.232076792276;6938.618375989369;6980.162380514269;7065.4700897432895;7085.185203701537;7087.397571206505;5128.769205385766;5154.639175257696;5176.611754118456;5199.740012999335;5394.757235924393;1439.647286414814;1441.92069436182;1455.163281113364;1458.5624312706145;1474.4544518528214;852.3053711225332;875.1336177184542;877.0790669796688;897.7351681254046;902.6479672927433;7323.290153634749;7338.4496162788;7348.154962179311;7354.77935661931;7386.372158366599;663.5620490476263;678.3420082519948;682.3688088518101;703.6235613946515;721.9278072192843;4766.523347665206;4994.450610432849;5084.669496482717;5100.38795344561;5140.254663073833;20824.687629685566;21174.15303387878;21388.35834924955;21809.127634894743;21873.78126218749;20255.898720506302;21583.352499425044;21623;21708.674369884466;21771.129154833947;9959.900400995897;10471.371717696962;10484.528196231187;10508.159347252202;10510.316829406063;7921.920780792118;7947.3244774194845;8057.234562716532;8088.757337279891;8099.757007289791;17524.03617801033;17713.29146834112;18204.4538663835;18329.541761455537;18657.160427780513;18329.633407332047;18917.337893173673;19086.809131908503;19216.039198040322;19235.615287693887;10627.89372106269;10829.891701082888;10840.45797710127;10857.40284284372;10870.673879783619;13474.865251347754;13968;14043.297835108;14045.859541404456;14056;10971.506282217479;11080.445977700922;11147.888521114684;11196.832047519456;11329.830052549383;10861.782764344827;11144.888551114385;11254.099672026383;11372.374519401512;11413.486393112164;9271;9545.045495450677;9616.23070154372;9620.37467564606;9629.807403852024;6905.930940690529;7023.613701246481;7038.2961703829205;7052.153741550842;7112.751053713096;6883.518153729191;6907.6891539881835;7092.219855815959;7096;7183.497155199086 16:09:41 INFO - PID 24428 | |4;prototype.html;496.19138241111176;501.0393379613287;515.7807931629058;518.862501437119;532.8685258964143;6172.1050447685175;6187.4740647045;6195.9999999999945;6234.220722409699;6255.812325630232;6162.137300777884;6226.470749986245;6228.968855155729;6229.508196721303;6292.651246440535;5182.896342073166;5199.402068762084;5199.792008319662;5220.895582088356;5293.15309550472;4892.746323694739;5024.623153263502;5054.595632349422;5094.592432605401;5100.668456550312;4962.627802914778;4994.625403094764;4997.325361076245;5058.873528161807;5104.489551044884;4966.726830024347;4970.552650261494;5037.521435463644;5042.445331013586;5046.495350464961;4515.683902126852;4535.115652447774;4548.56788605082;4575.977120114411;4579.29021001742;6168.87662246757;6292.87414251717;6311.558190926637;6341.365863413622;6347.428731414195;5681.517071048925;5689.7724091036725;5703.144528320744;5746.224259724967;5791.73937172824;6574.276829548746;6605.249608853657;6637.800865974028;6640.0703901453835;6681.899771503431;4918.778654960535;4926.45808961014;4940.010589304792;4953.504649535053;4983.9252411213865;6910.44716422685;6962.477814163958;6970.423661071123;6997.265287144827;7057.717691292342;5030.189131912509;5034.697918124924;5038.2190760432195;5060.924086138711;5063.772130254148;5074.67014644047;5128.666636668605;5134.460881607452;5149.9742501287255;5157.819476318311;5648.181013752983;5674.801381951612;5682.602217844794;5694.9430505695245;5736.1682556029145;6287.9056814147825;6374.362563743682;6509.349065093547;6617.047970848611;6722.982930277374;1735.4186347573705;1736.1145815634118;1743.6660954369918;1760.9522334211124;1762.6915289824485;1056.4031322302874;1203.5667159822458;1204.837346958167;1238.5355491690616;1238.550895452323;5651.858703532363;5797.507211887037;5800.20193739942;5800.622959507618;5801.361850196475;6759.695813688298;6779.288174741581;6807.353301436356;6821.863562728718;6846.315368463114;5745.798897038667;5754.1944127822135;5763.6541807491685;5769.249997500298;5779.768809247583;3486.9476957845654;3562.4834399011997;3565.073080998959;3568.7323450741296;3579.409397449444;6362.554621176566;6387.329330420332;6474.417302442803;6506.804795856132;6519.3154718754795;6788.93211067883;6798.66006699663;6817.420519255819;6889.138857642794;6911;6404.743810247631;6455.7740479083;6511.446527045159;6588.242352129375;6606.801795946226;6622.403983641303;6637.933620663732;6645.136132302963;6646.767363142267;6746;6609.389291229842;6618.569792963442;6664.633445160563;6729.798106056826;6736.562123461959;6264.436200741955;6334.651594162181;6346.428821406095;6468.482521398183;6476.6114033158165;4891.486393928586;4956.950430495649;4986.177280748636;4992.650514463952;5009.899802004013;6611.272759996493;6620.337966203534;6651.600903945779;6743.865122697421;6804.142260250607;6384.840378990562;6404.110787317659;6459.773907913201;6470.417662410406;6490.286068532363;6396.42432181106;6437.678116094083;6493.532457666057;6541.271475259056;6562.09411529608;6326.703025879776;6451.161349024596;6468.61188328683;6495.123158373748;6499.350064993463;5960.403959604178;6355.904661430175;6487.3022377244515;6490.961446168591;6505.642189679613;6195.194624698941;6250.312465628868;6269.18500594938;6284.905726414016;6327.651979141007 16:09:41 INFO - PID 24428 | |5;yui.html;881.836860180867;887.7336798960313;906.9773255668607;908.8500397434424;917.3807679816123;18947.71578426323;19793.208271669115;19892;19899.50251243718;19927.501812454695;15623.609409764762;15728.685426291497;16411.507654770347;17077.40229091982;17167;966.2318456826839;976.5410257179115;979.9706008819746;980.0640388429033;980.6126580000895;961.8028304197658;974.4348277998744;985.571276494727;991.2268430624125;991.7768502087009;1057.133150816331;1065.206421216195;1065.2915810985724;1067.2422579968231;1067.423591260722;1009.6218661526999;1021.3922715984015;1026.6406757634843;1027.6660085472251;1037.3153718545723;751.3087959077661;752.9623518824037;753.8906858505541;753.9132999704995;756.130449982519;20472.488187795425;20560.074796634188;20605.79394206069;20640.896795515928;20698.275560355465;21089.050992705364;21679.783202167935;22025.889870550705;22040.669389959163;22047.559048818934;16755.83244167555;17750.46748597544;17799.288028478844;17892.91053544737;17963.281468741236;3688.041109311598;3704.573973992998;3712.628737126293;3714.739968202227;3717.423799311111;133326.33336833253;134064.32967835196;135500.999999999;135851.32074339665;136865;15418.614534636723;16129.274182661808;16134.35462581507;16179.595510112342;16222.83777162237;28587.28531786722;28902.42195156127;29993.70006299909;30135.246618834706;30418.087457376314;7992.240737129963;8187.71343002992;8190.058143313476;8220.8355832883;8222.301104406193;3828.7511311764647;3846.576876543578;3853.4412510186426;3915.197384536163;3962.623550762673;5116.283720279164;5173.3533308336455;5186.170212766015;5232.2413250078525;5234.42421333653;918.1277786103228;930.265090578449;952.661805059194;955.4362925873768;963.4075043848123;693.2409012131708;697.0380874393406;708.6881772020295;715.0846915947595;718.7304760714732;11556.94221528887;11787;11793.410329483491;12092.939535302443;12117.757644847054;682.7234969837224;687.0793137196181;689.7034275261585;699.4439420660588;701.0815831261065;4930.98914722481;5062.367204099488;5067.6452648315;5110.156824124052;5167.534921857051;123618;123619;124891;125648;129742;101249;102458.48770756248;107862.46068769606;107889;108459;92390.53804730934;92656;92963.53518232501;93524.53237733904;94749.52625236829;9220.124088211609;9264.351495395258;9300.72097837066;9362.76593085151;9438.811223775623;43125.353119703854;43375;43523.9119022027;44820.32769508525;45260;56626.7168664154;60192.097118542384;60598;60770;61853;15430.614234644223;15516.68966620684;15631;15795.921020394824;15841.445549406177;15919.68160636804;16198.514044578682;16323.18384080815;16329.591760206089;16483.91758041202;14638.487652932095;14808.111513309235;14822.481213157487;14950.92524537414;14956.102633842005;15167.772483412979;15179;15208.923955380153;15347.462838800588;15419.845801541842;16046.67906641884;16056.759148612546;16164.272607732444;16270.105144217181;16299.266533006281;10510.684679459628;10521.105706014769;10536.262461627612;10557.313774604627;10948.890511094787;11135.387553684624;11194.776104478027;11245.662630121109;11317.264377815416;11565.016973557005 16:09:41 INFO - PID 24428 | __end_tp_report 16:09:41 INFO - PID 24428 | __start_cc_report 16:09:41 INFO - PID 24428 | _x_x_mozilla_cycle_collect,358 16:09:41 INFO - PID 24428 | __end_cc_report 16:09:41 INFO - PID 24428 | __startTimestamp1505344181476__endTimestamp 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | ------- Summary: start ------- 16:09:41 INFO - PID 24428 | Number of tests: 6 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | [#0] dojo.html Cycles:165 Average:6192.96 Median:6500.28 stddev:838.46 (12.9%) stddev-sans-first:806.06 16:09:41 INFO - PID 24428 | Values: 3138.3 3150.2 3155.3 3160.9 3164.9 6194.4 6252.8 6263.3 6371.6 6386.0 6255.9 6274.1 6322.4 6418.6 6426.3 1311.5 5834.0 5883.1 5911.9 5933.4 5692.7 5775.1 5845.2 5861.0 5863.5 5793.5 5798.2 5826.9 5849.2 5864.8 5743.4 5768.2 5850.4 5852.2 5853.1 5756.1 5766.0 5812.5 5834.7 5870.1 6104.4 6227.1 6269.8 6333.5 6345.1 6565.4 6641.2 6645.3 6652.4 6710.9 6774.9 6780.0 6796.1 6820.0 6821.6 6156.9 6257.4 6266.5 6300.0 6314.1 6881.1 7012.8 7013.3 7034.3 7034.4 6629.3 6631.1 6702.0 6722.6 6723.5 6715.7 6750.7 6805.9 6811.0 6820.1 6699.7 6701.0 6756.1 6759.9 6802.1 6738.3 6751.1 6823.4 6850.9 6851.6 5217.2 5314.7 5316.7 5400.6 5404.6 4593.1 4621.9 4698.6 4707.9 4731.6 5800.2 5803.3 5863.7 5888.3 5893.9 6705.5 6790.4 6820.1 6874.6 6884.9 5732.1 5734.5 5773.7 5788.3 5790.7 5713.2 5798.7 5804.4 5810.4 5824.8 6495.1 6498.8 6500.3 6597.7 6612.3 6591.2 6667.8 6697.6 6842.9 6858.1 6527.7 6558.1 6570.3 6598.8 6602.5 6598.4 6695.1 6764.4 6774.6 6780.8 6687.4 6770.4 6773.1 6811.8 6923.5 6498.6 6518.6 6532.6 6603.2 6644.6 6532.7 6575.7 6593.7 6604.2 6641.4 6460.6 6469.8 6475.6 6575.1 6588.4 6546.5 6641.3 6644.3 6669.3 6704.7 6451.6 6505.8 6514.2 6516.2 6538.5 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | [#1] ext.html Cycles:180 Average:37373.57 Median:3528.99 stddev:53075.30 (1504.0%) stddev-sans-first:53153.10 16:09:41 INFO - PID 24428 | Values: 789.8 803.5 827.4 831.5 839.2 40437.6 41316.6 41361.2 41426.6 41479.4 41434.0 42433.8 42440.4 42441.8 42448.6 7706.3 7860.0 7860.1 7864.2 7868.3 7764.4 7883.4 7894.3 7903.4 7907.7 7878.0 7903.3 7918.6 7922.4 7924.6 7875.2 7901.1 7911.3 7911.4 7916.6 7761.0 7784.2 7789.5 7814.6 7825.6 37548.4 37734.4 37795.8 37795.8 37834.4 3503.8 3528.3 3529.7 3536.8 3539.1 30739.7 30848.4 31146.8 31147.5 31291.2 5001.6 5130.9 5170.1 5185.9 5195.1 182729.0 182889.0 182923.0 183750.0 184817.0 45972.5 45987.1 46099.3 46148.1 46286.0 48448.3 48464.8 48686.8 48688.5 48721.5 15284.0 15648.3 15658.8 15661.0 15674.9 12925.5 12928.2 12930.9 12957.7 12963.1 12743.8 12963.9 12975.7 13001.0 13107.6 5217.9 5223.8 5283.0 5321.1 5324.8 995.3 998.6 1008.9 1009.4 1012.8 22368.0 22518.7 22686.3 22921.0 22954.0 1076.3 1103.2 1113.4 1116.1 1117.3 11687.6 11781.7 12119.8 12138.8 12148.1 221839.0 224085.0 224509.0 224547.0 224903.0 50219.0 56096.0 59257.1 59680.7 59856.1 1467.4 1482.0 1490.5 1506.8 1509.8 27304.6 27506.6 27694.9 27928.3 28021.0 37783.2 38057.4 38065.2 38151.4 38183.6 204086.0 205449.0 205453.0 205485.0 205695.0 34672.5 35309.8 35433.8 35672.3 35703.5 41676.4 43153.0 43530.1 44040.6 44165.3 30695.2 31175.4 31213.7 31246.4 31321.4 30927.8 30978.5 31079.5 31294.4 31432.7 34743.5 34749.8 34791.1 34817.3 34819.7 30608.7 30650.5 30679.2 31256.4 31391.2 22236.4 22573.0 22800.1 22805.0 22940.2 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | [#2] jquery.html Cycles:180 Average:33247.11 Median:23105.71 stddev:73206.51 (316.8%) stddev-sans-first:73370.74 16:09:41 INFO - PID 24428 | Values: 561.6 562.6 567.4 576.9 592.9 21854.0 21997.9 22766.0 22791.7 22891.1 23495.8 25449.4 25469.0 25501.5 25508.4 6372.7 6506.6 6518.8 6520.5 6520.6 5905.9 6458.7 6486.3 6489.4 6502.2 6446.3 6457.5 6462.4 6464.4 6467.8 6408.3 6454.9 6455.0 6458.6 6468.0 5986.0 5994.3 6003.3 6005.3 6007.6 28843.4 28998.4 29415.1 29439.6 29630.0 1970.9 2014.2 2016.1 2017.6 2018.2 18944.3 19173.4 20192.7 20209.8 20238.0 2385.2 2390.3 2450.1 2464.2 2466.0 148328.0 155247.0 155575.2 156261.0 156374.0 14618.6 15025.4 15198.7 15198.8 15360.0 23348.0 23407.3 23418.9 23424.4 23446.3 9853.8 9999.2 10068.0 10151.5 10331.1 9266.4 9382.4 9424.4 9454.9 9471.9 8961.9 9115.6 9312.5 9412.2 9555.3 2677.2 2700.7 2713.3 2757.2 2820.7 957.8 984.4 986.7 987.9 1003.7 19990.6 20048.6 20061.6 20117.7 20282.7 1040.5 1043.4 1050.1 1053.1 1053.2 11079.6 11089.2 11129.8 11145.3 11267.4 417069.0 420716.0 427254.0 428997.0 431839.0 111315.4 111650.4 112020.4 112975.0 113685.4 1048.0 1095.6 1135.6 1178.1 1194.3 20551.3 20680.3 20786.5 20937.7 20987.7 52720.7 54102.0 54165.0 54609.5 55890.4 66577.0 67112.0 67451.0 67831.0 68354.0 14394.1 14707.8 14771.8 14811.6 14811.8 23069.9 23081.8 23129.7 23138.0 23445.9 18389.0 18585.3 18657.7 18691.3 18799.5 15736.7 16583.3 16603.8 16735.2 17134.1 18521.5 18580.8 18901.9 18992.6 19082.2 14387.7 14486.6 14506.9 14513.0 14529.9 18224.1 18348.3 18356.6 18538.1 18581.5 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | [#3] mootools.html Cycles:180 Average:9121.95 Median:14427.57 stddev:6632.78 (46.0%) stddev-sans-first:6625.35 16:09:41 INFO - PID 24428 | Values: 1298.8 1359.4 1363.7 1370.0 1382.1 14467.4 14862.6 14875.8 14877.6 15029.2 14651.3 14708.1 14739.4 14750.2 14751.0 2602.7 2630.1 2672.2 2704.6 2705.2 2614.8 2645.0 2646.4 2657.1 2662.2 2484.6 2617.4 2691.0 2709.1 2714.9 2511.3 2529.3 2542.6 2553.6 2556.1 2183.3 2252.3 2636.8 2642.5 2654.8 14500.6 14925.4 14955.8 14956.4 14959.3 7652.2 7859.0 7937.2 7942.3 7961.2 5299.2 5451.2 5473.7 5483.8 5528.3 1813.3 1893.0 1893.6 1941.0 1951.6 26225.0 26536.2 26557.9 26607.5 26620.7 6547.6 6583.6 6592.3 6743.6 6889.7 17046.8 17087.3 17101.0 17111.2 17126.4 7442.9 7635.5 7648.7 7669.0 7679.2 6938.6 6980.2 7065.5 7085.2 7087.4 5128.8 5154.6 5176.6 5199.7 5394.8 1439.6 1441.9 1455.2 1458.6 1474.5 852.3 875.1 877.1 897.7 902.6 7323.3 7338.4 7348.2 7354.8 7386.4 663.6 678.3 682.4 703.6 721.9 4766.5 4994.5 5084.7 5100.4 5140.3 20824.7 21174.2 21388.4 21809.1 21873.8 20255.9 21583.4 21623.0 21708.7 21771.1 9959.9 10471.4 10484.5 10508.2 10510.3 7921.9 7947.3 8057.2 8088.8 8099.8 17524.0 17713.3 18204.5 18329.5 18657.2 18329.6 18917.3 19086.8 19216.0 19235.6 10627.9 10829.9 10840.5 10857.4 10870.7 13474.9 13968.0 14043.3 14045.9 14056.0 10971.5 11080.4 11147.9 11196.8 11329.8 10861.8 11144.9 11254.1 11372.4 11413.5 9271.0 9545.0 9616.2 9620.4 9629.8 6905.9 7023.6 7038.3 7052.2 7112.8 6883.5 6907.7 7092.2 7096.0 7183.5 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | [#4] prototype.html Cycles:180 Average:5519.87 Median:6195.60 stddev:1539.27 (24.8%) stddev-sans-first:1496.70 16:09:41 INFO - PID 24428 | Values: 496.2 501.0 515.8 518.9 532.9 6172.1 6187.5 6196.0 6234.2 6255.8 6162.1 6226.5 6229.0 6229.5 6292.7 5182.9 5199.4 5199.8 5220.9 5293.2 4892.7 5024.6 5054.6 5094.6 5100.7 4962.6 4994.6 4997.3 5058.9 5104.5 4966.7 4970.6 5037.5 5042.4 5046.5 4515.7 4535.1 4548.6 4576.0 4579.3 6168.9 6292.9 6311.6 6341.4 6347.4 5681.5 5689.8 5703.1 5746.2 5791.7 6574.3 6605.2 6637.8 6640.1 6681.9 4918.8 4926.5 4940.0 4953.5 4983.9 6910.4 6962.5 6970.4 6997.3 7057.7 5030.2 5034.7 5038.2 5060.9 5063.8 5074.7 5128.7 5134.5 5150.0 5157.8 5648.2 5674.8 5682.6 5694.9 5736.2 6287.9 6374.4 6509.3 6617.0 6723.0 1735.4 1736.1 1743.7 1761.0 1762.7 1056.4 1203.6 1204.8 1238.5 1238.6 5651.9 5797.5 5800.2 5800.6 5801.4 6759.7 6779.3 6807.4 6821.9 6846.3 5745.8 5754.2 5763.7 5769.2 5779.8 3486.9 3562.5 3565.1 3568.7 3579.4 6362.6 6387.3 6474.4 6506.8 6519.3 6788.9 6798.7 6817.4 6889.1 6911.0 6404.7 6455.8 6511.4 6588.2 6606.8 6622.4 6637.9 6645.1 6646.8 6746.0 6609.4 6618.6 6664.6 6729.8 6736.6 6264.4 6334.7 6346.4 6468.5 6476.6 4891.5 4957.0 4986.2 4992.7 5009.9 6611.3 6620.3 6651.6 6743.9 6804.1 6384.8 6404.1 6459.8 6470.4 6490.3 6396.4 6437.7 6493.5 6541.3 6562.1 6326.7 6451.2 6468.6 6495.1 6499.4 5960.4 6355.9 6487.3 6491.0 6505.6 6195.2 6250.3 6269.2 6284.9 6327.7 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | [#5] yui.html Cycles:180 Average:23903.41 Median:21384.42 stddev:34934.64 (163.4%) stddev-sans-first:34989.87 16:09:41 INFO - PID 24428 | Values: 881.8 887.7 907.0 908.9 917.4 18947.7 19793.2 19892.0 19899.5 19927.5 15623.6 15728.7 16411.5 17077.4 17167.0 966.2 976.5 980.0 980.1 980.6 961.8 974.4 985.6 991.2 991.8 1057.1 1065.2 1065.3 1067.2 1067.4 1009.6 1021.4 1026.6 1027.7 1037.3 751.3 753.0 753.9 753.9 756.1 20472.5 20560.1 20605.8 20640.9 20698.3 21089.1 21679.8 22025.9 22040.7 22047.6 16755.8 17750.5 17799.3 17892.9 17963.3 3688.0 3704.6 3712.6 3714.7 3717.4 133326.3 134064.3 135501.0 135851.3 136865.0 15418.6 16129.3 16134.4 16179.6 16222.8 28587.3 28902.4 29993.7 30135.2 30418.1 7992.2 8187.7 8190.1 8220.8 8222.3 3828.8 3846.6 3853.4 3915.2 3962.6 5116.3 5173.4 5186.2 5232.2 5234.4 918.1 930.3 952.7 955.4 963.4 693.2 697.0 708.7 715.1 718.7 11556.9 11787.0 11793.4 12092.9 12117.8 682.7 687.1 689.7 699.4 701.1 4931.0 5062.4 5067.6 5110.2 5167.5 123618.0 123619.0 124891.0 125648.0 129742.0 101249.0 102458.5 107862.5 107889.0 108459.0 92390.5 92656.0 92963.5 93524.5 94749.5 9220.1 9264.4 9300.7 9362.8 9438.8 43125.4 43375.0 43523.9 44820.3 45260.0 56626.7 60192.1 60598.0 60770.0 61853.0 15430.6 15516.7 15631.0 15795.9 15841.4 15919.7 16198.5 16323.2 16329.6 16483.9 14638.5 14808.1 14822.5 14950.9 14956.1 15167.8 15179.0 15208.9 15347.5 15419.8 16046.7 16056.8 16164.3 16270.1 16299.3 10510.7 10521.1 10536.3 10557.3 10948.9 11135.4 11194.8 11245.7 11317.3 11565.0 16:09:41 INFO - PID 24428 | -------- Summary: end -------- 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | 16:09:41 INFO - PID 24428 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 16:09:41 INFO - PID 24428 | 16:09:42 INFO - TEST-INFO | 24428: exit 0 16:09:42 INFO - TEST-OK | dromaeo_css | took 1108920ms 16:09:42 INFO - TEST-START | kraken 16:09:42 INFO - Initialising browser for kraken test... 16:09:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58380/getInfo.html -profile /tmp/tmp8AiSEh/profile 16:09:42 INFO - TEST-INFO | started process 24803 (/builds/slave/test/build/application/firefox/firefox http://localhost:58380/getInfo.html) 16:09:47 INFO - TEST-INFO | 24803: exit 0 16:09:48 INFO - Browser initialized. 16:09:48 INFO - Running cycle 1/1 for kraken test... 16:09:48 INFO - Using env: {'DISPLAY': ':0', 16:09:48 INFO - 'HOME': '/home/cltbld', 16:09:48 INFO - 'JSGC_DISABLE_POISONING': '1', 16:09:48 INFO - 'LANG': 'en_US.UTF-8', 16:09:48 INFO - 'LANGUAGE': 'en_US:en', 16:09:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:09:48 INFO - 'LOGNAME': 'cltbld', 16:09:48 INFO - 'MAIL': '/var/mail/cltbld', 16:09:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:48 INFO - 'MOZ_CRASHREPORTER': '1', 16:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:09:48 INFO - 'MOZ_NO_REMOTE': '1', 16:09:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:48 INFO - 'NO_EM_RESTART': '1', 16:09:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:48 INFO - 'PWD': '/builds/slave/test', 16:09:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:48 INFO - 'RUST_BACKTRACE': 'full', 16:09:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:09:48 INFO - 'SHELL': '/bin/bash', 16:09:48 INFO - 'SHLVL': '1', 16:09:48 INFO - 'TERM': 'linux', 16:09:48 INFO - 'TMOUT': '86400', 16:09:48 INFO - 'USER': 'cltbld', 16:09:48 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408', 16:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:48 INFO - '_': '/tools/buildbot/bin/python'} 16:09:48 INFO - TEST-INFO | started process 25040 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8AiSEh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:49 INFO - PID 25040 | 16:09:49 INFO - PID 25040 | (/builds/slave/test/build/application/firefox/firefox:25089): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:09:49 INFO - PID 25040 | 16:09:59 INFO - PID 25040 | RSS: Main: 170721280 16:09:59 INFO - PID 25040 | 16:10:02 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58380/tests/kraken/driver.html?testName=audio-beat-detection) 16:10:03 INFO - PID 25040 | RSS: Main: 177053696 16:10:03 INFO - PID 25040 | 16:10:05 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58380/tests/kraken/driver.html?testName=audio-dft) 16:10:06 INFO - PID 25040 | RSS: Main: 176893952 16:10:06 INFO - PID 25040 | 16:10:09 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58380/tests/kraken/driver.html?testName=audio-fft) 16:10:09 INFO - PID 25040 | RSS: Main: 171700224 16:10:09 INFO - PID 25040 | 16:10:12 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58380/tests/kraken/driver.html?testName=audio-oscillator) 16:10:12 INFO - PID 25040 | RSS: Main: 172445696 16:10:12 INFO - PID 25040 | 16:10:14 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58380/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:15 INFO - PID 25040 | RSS: Main: 172773376 16:10:15 INFO - PID 25040 | 16:10:18 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58380/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:19 INFO - PID 25040 | RSS: Main: 175288320 16:10:19 INFO - PID 25040 | 16:10:22 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58380/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:23 INFO - PID 25040 | RSS: Main: 175349760 16:10:23 INFO - PID 25040 | 16:10:26 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58380/tests/kraken/driver.html?testName=json-parse-financial) 16:10:27 INFO - PID 25040 | RSS: Main: 175325184 16:10:27 INFO - PID 25040 | 16:10:28 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58380/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:29 INFO - PID 25040 | RSS: Main: 175116288 16:10:29 INFO - PID 25040 | 16:10:31 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:31 INFO - PID 25040 | RSS: Main: 176463872 16:10:31 INFO - PID 25040 | 16:10:33 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:34 INFO - PID 25040 | RSS: Main: 175730688 16:10:34 INFO - PID 25040 | 16:10:37 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:37 INFO - PID 25040 | RSS: Main: 176013312 16:10:37 INFO - PID 25040 | 16:10:40 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:41 INFO - PID 25040 | RSS: Main: 176168960 16:10:41 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | Cycle 1(1): loaded http://localhost:58380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58380/tests/kraken/driver.html?testName=ai-astar) 16:10:43 INFO - PID 25040 | RSS: Main: 177733632 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | __start_tp_report 16:10:43 INFO - PID 25040 | _x_x_mozilla_page_load 16:10:43 INFO - PID 25040 | _x_x_mozilla_page_load_details 16:10:43 INFO - PID 25040 | |i|pagename|runs| 16:10:43 INFO - PID 25040 | |0;ai-astar;109;107;107;108;108;108;108;109;107;107 16:10:43 INFO - PID 25040 | |1;audio-beat-detection;116;116;115;117;116;117;118;115;118;148 16:10:43 INFO - PID 25040 | |2;audio-dft;164;164;163;163;163;162;171;166;164;168 16:10:43 INFO - PID 25040 | |3;audio-fft;74;74;74;75;73;74;75;77;75;74 16:10:43 INFO - PID 25040 | |4;audio-oscillator;79;79;81;81;85;81;79;79;80;90 16:10:43 INFO - PID 25040 | |5;imaging-gaussian-blur;114;114;113;113;114;114;113;114;113;116 16:10:43 INFO - PID 25040 | |6;imaging-darkroom;146;147;147;148;148;146;147;147;148;147 16:10:43 INFO - PID 25040 | |7;imaging-desaturate;94;94;94;94;95;94;94;94;95;94 16:10:43 INFO - PID 25040 | |8;json-parse-financial;55;52;53;53;53;55;54;53;54;54 16:10:43 INFO - PID 25040 | |9;json-stringify-tinderbox;36;37;37;37;37;38;37;38;38;37 16:10:43 INFO - PID 25040 | |10;stanford-crypto-aes;74;75;73;88;74;75;76;73;74;72 16:10:43 INFO - PID 25040 | |11;stanford-crypto-ccm;115;111;110;110;116;113;118;114;119;117 16:10:43 INFO - PID 25040 | |12;stanford-crypto-pbkdf2;181;170;167;167;168;176;167;174;172;174 16:10:43 INFO - PID 25040 | |13;stanford-crypto-sha256-iterative;74;71;63;70;64;72;69;67;66;68 16:10:43 INFO - PID 25040 | __end_tp_report 16:10:43 INFO - PID 25040 | __start_cc_report 16:10:43 INFO - PID 25040 | _x_x_mozilla_cycle_collect,936 16:10:43 INFO - PID 25040 | __end_cc_report 16:10:43 INFO - PID 25040 | __startTimestamp1505344243469__endTimestamp 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | ------- Summary: start ------- 16:10:43 INFO - PID 25040 | Number of tests: 14 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#0] ai-astar Cycles:10 Average:107.80 Median:108.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 16:10:43 INFO - PID 25040 | Values: 109.0 107.0 107.0 108.0 108.0 108.0 108.0 109.0 107.0 107.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:117.00 stddev:10.04 (8.6%) stddev-sans-first:10.56 16:10:43 INFO - PID 25040 | Values: 116.0 116.0 115.0 117.0 116.0 117.0 118.0 115.0 118.0 148.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:2.78 (1.7%) stddev-sans-first:2.93 16:10:43 INFO - PID 25040 | Values: 164.0 164.0 163.0 163.0 163.0 162.0 171.0 166.0 164.0 168.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#3] audio-fft Cycles:10 Average:74.50 Median:74.50 stddev:1.08 (1.4%) stddev-sans-first:1.13 16:10:43 INFO - PID 25040 | Values: 74.0 74.0 74.0 75.0 73.0 74.0 75.0 77.0 75.0 74.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:81.00 stddev:3.53 (4.4%) stddev-sans-first:3.64 16:10:43 INFO - PID 25040 | Values: 79.0 79.0 81.0 81.0 85.0 81.0 79.0 79.0 80.0 90.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 16:10:43 INFO - PID 25040 | Values: 114.0 114.0 113.0 113.0 114.0 114.0 113.0 114.0 113.0 116.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 16:10:43 INFO - PID 25040 | Values: 146.0 147.0 147.0 148.0 148.0 146.0 147.0 147.0 148.0 147.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:10:43 INFO - PID 25040 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 95.0 94.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:0.88 16:10:43 INFO - PID 25040 | Values: 55.0 52.0 53.0 53.0 53.0 55.0 54.0 53.0 54.0 54.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#9] json-stringify-tinderbox Cycles:10 Average:37.20 Median:37.00 stddev:0.63 (1.7%) stddev-sans-first:0.50 16:10:43 INFO - PID 25040 | Values: 36.0 37.0 37.0 37.0 37.0 38.0 37.0 38.0 38.0 37.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:74.50 stddev:4.58 (6.1%) stddev-sans-first:4.82 16:10:43 INFO - PID 25040 | Values: 74.0 75.0 73.0 88.0 74.0 75.0 76.0 73.0 74.0 72.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#11] stanford-crypto-ccm Cycles:10 Average:114.30 Median:115.50 stddev:3.27 (2.8%) stddev-sans-first:3.46 16:10:43 INFO - PID 25040 | Values: 115.0 111.0 110.0 110.0 116.0 113.0 118.0 114.0 119.0 117.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:173.00 stddev:4.70 (2.7%) stddev-sans-first:3.54 16:10:43 INFO - PID 25040 | Values: 181.0 170.0 167.0 167.0 168.0 176.0 167.0 174.0 172.0 174.0 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.50 stddev:3.50 (5.0%) stddev-sans-first:3.07 16:10:43 INFO - PID 25040 | Values: 74.0 71.0 63.0 70.0 64.0 72.0 69.0 67.0 66.0 68.0 16:10:43 INFO - PID 25040 | -------- Summary: end -------- 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 16:10:43 INFO - PID 25040 | 16:10:43 INFO - PID 25040 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:10:44 INFO - TEST-INFO | 25040: exit 0 16:10:44 INFO - TEST-OK | kraken | took 61999ms 16:10:44 INFO - SUITE-END | took 1170s 16:10:44 INFO - Completed test suite (00:19:31) 16:10:44 INFO - javascript benchmark 16:10:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8416.126339732005, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3138.309571894183, 3150.2439414540518, 3155.274286914009, 3160.8577614007368, 3164.9208769780753, 6194.411530904568, 6252.812415627533, 6263.279722831876, 6371.649559274244, 6386.010168423896, 6255.906161407571, 6274.090256912757, 6322.399372059658, 6418.614883107017, 6426.325235850239, 1311.4505637298325, 5833.970830145843, 5883.058710606304, 5911.9113213301835, 5933.406659334075, 5692.743826527795, 5775.133729940521, 5845.2401187845735, 5860.970695146519, 5863.530917526609, 5793.478586927196, 5798.246227990356, 5826.854328641797, 5849.181114643953, 5864.824055278348, 5743.368229494752, 5768.163616275617, 5850.444207800252, 5852.180694702745, 5853.09277062056, 5756.079027355603, 5765.971170144165, 5812.505936995318, 5834.6790926499025, 5870.0607902735355, 6104.4200800923845, 6227.1282020517165, 6269.780557680505, 6333.524985626096, 6345.143405640272, 6565.376289252561, 6641.203055633355, 6645.269020407752, 6652.368025037614, 6710.93289067108, 6774.898376524356, 6780.0, 6796.116504854387, 6819.965900170517, 6821.556598821061, 6156.907646385308, 6257.436830685215, 6266.530010249249, 6299.968500157515, 6314.116023756677, 6881.10545629075, 7012.789616311519, 7013.26360732126, 7034.331738484836, 7034.40207582351, 6629.337066293332, 6631.071649969008, 6702.0, 6722.6302553359055, 6723.5293529452465, 6715.731370745116, 6750.7299708012115, 6805.93194068053, 6811.046453496514, 6820.113385259884, 6699.665016749143, 6701.0, 6756.0879281297375, 6759.932400676029, 6802.081718967975, 6738.292479289702, 6751.054852320679, 6823.38589526945, 6850.897236541456, 6851.623160726108, 5217.243499692523, 5314.707691077027, 5316.73416329182, 5400.621956463088, 5404.594655400782, 4593.127305811952, 4621.861344159681, 4698.600618947427, 4707.905841883212, 4731.645126615517, 5800.187973683687, 5803.303603567599, 5863.736131874076, 5888.263967004124, 5893.911591326134, 6705.530612857053, 6790.422814060761, 6820.079289295981, 6874.553154044971, 6884.862302753917, 5732.082866741302, 5734.5125664319, 5773.740181691834, 5788.334341550775, 5790.652560846363, 5713.22871412354, 5798.652080875161, 5804.390538993429, 5810.4189581041555, 5824.767009319665, 6495.123158373559, 6498.805035848932, 6500.284968653539, 6597.736090556324, 6612.338766123446, 6591.176102987222, 6667.799966001027, 6697.598144111369, 6842.897356539556, 6858.074159988537, 6527.67361631926, 6558.114654521769, 6570.310117437601, 6598.769043083469, 6602.471802255904, 6598.37315455031, 6695.06269122333, 6764.3573860483175, 6774.559653622498, 6780.830479238059, 6687.431568316553, 6770.356816102462, 6773.119494465688, 6811.761588344384, 6923.515353925177, 6498.642574658439, 6518.57629254097, 6532.5753826001155, 6603.20761508622, 6644.568103073285, 6532.738690452328, 6575.704093315717, 6593.703283352356, 6604.24051234095, 6641.402273795382, 6460.580062296123, 6469.805905822833, 6475.579087359307, 6575.112359831553, 6588.374104460068, 6546.541742078009, 6641.302663220292, 6644.269130395749, 6669.333066693486, 6704.6982885769285, 6451.580647257913, 6505.804825855232, 6514.1531600891585, 6516.152900122954, 6538.476921846336], "value": 6132.016992120233, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [789.7551758954721, 803.4937989066889, 827.4332082523472, 831.5052543736479, 839.2111415269642, 40437.59562404375, 41316.58683413169, 41361.17277654445, 41426.58573414265, 41479.377809332844, 41434.0, 42433.78783106088, 42440.36339454911, 42441.78779106108, 42448.57551424485, 7706.344960678349, 7860.0, 7860.056793184825, 7864.174261702525, 7868.252516010998, 7764.3788496920115, 7883.448158628898, 7894.289513943744, 7903.446758726892, 7907.683692652287, 7878.0, 7903.328217101524, 7918.604069796516, 7922.36621070313, 7924.603769811515, 7875.212478752136, 7901.051873775126, 7911.327537159348, 7911.367090632764, 7916.604169791487, 7761.0, 7784.182660820646, 7789.454738168331, 7814.609269536557, 7825.608719564056, 37548.43677344815, 37734.43398349027, 37795.81102094472, 37795.811020944995, 37834.43248351277, 3503.8248087595775, 3528.2943411317838, 3529.6823285904393, 3536.7701099428714, 3539.132912436444, 30739.692603073905, 30848.38303233923, 31146.844265778753, 31147.532787008215, 31291.217719556964, 5001.624878134155, 5130.897382052338, 5170.069387510247, 5185.922211166836, 5195.064888320101, 182729.0, 182889.0, 182922.99999999866, 183750.0, 184817.0, 45972.54027459749, 45987.08025839464, 46099.30851037237, 46148.07741342024, 46286.0, 48448.27327590089, 48464.7576762114, 48686.75656621694, 48688.5131148684, 48721.512784871695, 15284.0, 15648.295826687827, 15658.765118523232, 15661.0, 15674.9216253918, 12925.482980680856, 12928.159669621444, 12930.935345323212, 12957.676058098434, 12963.092583519063, 12743.808842867364, 12963.935180324039, 12975.675608109872, 13001.0, 13107.606771796862, 5217.921731174036, 5223.817166399158, 5282.973585132127, 5321.122014867581, 5324.8136315228785, 995.2883688162854, 998.6005597760809, 1008.8574091704288, 1009.4195837393528, 1012.8480727890875, 22368.0, 22518.66222006671, 22686.319410417713, 22921.0, 22954.0, 1076.34343050739, 1103.2056919018294, 1113.4098927568402, 1116.1126904111252, 1117.284937639911, 11687.649370518899, 11781.705457363634, 12119.757604847853, 12138.817917731241, 12148.088893333035, 221839.0, 224085.0, 224509.0, 224547.0, 224903.0, 50219.0, 56096.0, 59257.111143332884, 59680.70159649261, 59856.10215846766, 1467.449706360115, 1481.9703605928037, 1490.4708828365774, 1506.7664512000658, 1509.7886295918581, 27304.59043114315, 27506.587401189397, 27694.861525692242, 27928.30179245535, 28021.0, 37783.2443351137, 38057.42913856239, 38065.23869522649, 38151.42772858354, 38183.618163818006, 204086.0, 205449.0, 205453.0, 205485.0, 205695.0, 34672.47991280082, 35309.823450882584, 35433.82283088568, 35672.28655426929, 35703.464448032784, 41676.37485437781, 43153.0, 43530.12939741124, 44040.55959440365, 44165.33751993787, 30695.2326191847, 31175.376492470477, 31213.68786312108, 31246.37507249797, 31321.37357252888, 30927.845360773954, 30978.535321970638, 31079.533806993364, 31294.37411251808, 31432.68567314389, 34743.47884781781, 34749.82625086858, 34791.130221743646, 34817.30365392729, 34819.65180348164, 30608.693913060586, 30650.540241896837, 30679.2330191747, 31256.437178141456, 31391.215219618778, 22236.424915740856, 22573.0, 22800.087996479953, 22805.0, 22940.197093101662], "value": 17076.1372796033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.5591760468033, 562.6483447845097, 567.4212303450478, 576.8673205162816, 592.860677740731, 21854.0, 21997.890010549923, 22766.0, 22791.658125128135, 22891.084356625714, 23495.765042349572, 25449.363765905862, 25469.0, 25501.489970200586, 25508.362290942736, 6372.681365931708, 6506.642134682591, 6518.837029074263, 6520.543561950665, 6520.576162549442, 5905.881882362351, 6458.677066146697, 6486.286508484087, 6489.351064893496, 6502.2197336319705, 6446.323136070715, 6457.515686323521, 6462.353764623524, 6464.418202361787, 6467.83830404239, 6408.263049749292, 6454.903176452357, 6454.967725161391, 6458.579112595245, 6468.029795530708, 5986.042233242661, 5994.340622531518, 6003.339632640406, 6005.279366476007, 6007.579469437142, 28843.42313153746, 28998.42003159925, 29415.11754647364, 29439.558406623917, 29629.962951296602, 1970.9113089910988, 2014.224523558426, 2016.1431391658487, 2017.6368253714325, 2018.1624625780285, 18944.336948206885, 19173.424797256106, 20192.69710954322, 20209.79790202094, 20238.0, 2385.2128797496785, 2390.270967354956, 2450.0689737899484, 2464.162184857157, 2465.963010554843, 148328.0, 155247.0, 155575.22212388978, 156261.0, 156374.0, 14618.565010373573, 15025.422330655343, 15198.696026079637, 15198.848011519964, 15360.0, 23348.0, 23407.297781066594, 23418.882905585364, 23424.414389640053, 23446.296611101352, 9853.753656158653, 9999.200063994864, 10068.0, 10151.492425378701, 10331.070203681706, 9266.351355405057, 9382.437053776659, 9424.387414818015, 9454.85817712735, 9471.857922131174, 8961.910380896237, 9115.589798459085, 9312.463443531877, 9412.199963003084, 9555.331126821056, 2677.2102229842244, 2700.7434293742062, 2713.253855189799, 2757.2215920731014, 2820.7461328480535, 957.7797106665506, 984.3945973226557, 986.7187851462443, 987.8814542254831, 1003.7239759066315, 19990.60018799616, 20048.59902801936, 20061.598768024556, 20117.698234526495, 20282.69575956362, 1040.5109598488698, 1043.3896170740202, 1050.0811789684026, 1053.0732954999705, 1053.1785207538132, 11079.612213572485, 11089.207735495946, 11129.777404451866, 11145.331280123057, 11267.38029408374, 417069.0, 420716.0, 427254.0, 428997.0, 431839.0, 111315.44342278237, 111650.44174779237, 112020.43989780163, 112975.0, 113685.43157284326, 1047.994760026195, 1095.6384393150402, 1135.647949135754, 1178.092868491274, 1194.3073017649742, 20551.280705175246, 20680.276190333265, 20786.480337991066, 20937.685934710687, 20987.685184721937, 52720.73639631777, 54102.0, 54165.0, 54609.45390546044, 55890.44109559015, 66577.0, 67112.0, 67451.0, 67831.0, 68354.0, 14394.136351818925, 14707.779383309473, 14771.778423323443, 14811.555653330417, 14811.777823332442, 23069.88465057664, 23081.769182307962, 23129.653055204522, 23138.0, 23445.88277058604, 18389.0, 18585.34951276699, 18657.72013419827, 18691.25234990585, 18799.530011749815, 15736.685266294839, 16583.336666533203, 16603.750943736097, 16735.246913889147, 17134.143292835557, 18521.53696157607, 18580.81419185791, 18901.905490472458, 18992.620147596695, 19082.236710531422, 14387.712245754818, 14486.637834054232, 14506.927465362605, 14513.0, 14529.927350363181, 18224.088795560434, 18348.26606935761, 18356.632867342847, 18538.073096345397, 18581.535461613566], "value": 11954.531991364873, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.7986862036385, 1359.4086572341032, 1363.72725454909, 1369.965750856229, 1382.0878220374548, 14467.421303147861, 14862.554123376289, 14875.776863347044, 14877.553673389815, 15029.173395463245, 14651.267436628179, 14708.117512949231, 14739.410423583071, 14750.188739619316, 14751.0, 2602.7397260273915, 2630.1452028090853, 2672.2384120525653, 2704.634874291975, 2705.201965420206, 2614.7777438917715, 2645.0213421034246, 2646.4310173312733, 2657.0833062593374, 2662.2279538933685, 2484.627305904111, 2617.371830760613, 2690.9730902691017, 2709.133077415228, 2714.8506832124226, 2511.2842839790665, 2529.304441278645, 2542.6058960861096, 2553.6041913503436, 2556.0925871315785, 2183.323169817346, 2252.256755270757, 2636.775874050708, 2642.4979253941688, 2654.8008899332626, 14500.637484062878, 14925.402983880631, 14955.775663365059, 14956.40174393023, 14959.252037398195, 7652.196519365442, 7859.0, 7937.2459616336355, 7942.324902383304, 7961.1640777718085, 5299.205119232146, 5451.182322651594, 5473.726313684339, 5483.835484935458, 5528.281323427928, 1813.2837529175902, 1892.9716054259197, 1893.6307420053095, 1940.9514762130918, 1951.6487032334173, 26225.0, 26536.203913882808, 26557.867210663822, 26607.467850643072, 26620.73379266202, 6547.574407663582, 6583.63789991555, 6592.307807680125, 6743.5953842769595, 6889.655517224119, 17046.829531704523, 17087.316507339816, 17101.0, 17111.22999465027, 17126.40057597978, 7442.8883566746545, 7635.541867487968, 7648.73229436967, 7668.961655191688, 7679.232076792276, 6938.618375989369, 6980.162380514269, 7065.4700897432895, 7085.185203701537, 7087.397571206505, 5128.769205385766, 5154.639175257696, 5176.611754118456, 5199.740012999335, 5394.757235924393, 1439.647286414814, 1441.92069436182, 1455.163281113364, 1458.5624312706145, 1474.4544518528214, 852.3053711225332, 875.1336177184542, 877.0790669796688, 897.7351681254046, 902.6479672927433, 7323.290153634749, 7338.4496162788, 7348.154962179311, 7354.77935661931, 7386.372158366599, 663.5620490476263, 678.3420082519948, 682.3688088518101, 703.6235613946515, 721.9278072192843, 4766.523347665206, 4994.450610432849, 5084.669496482717, 5100.38795344561, 5140.254663073833, 20824.687629685566, 21174.15303387878, 21388.35834924955, 21809.127634894743, 21873.78126218749, 20255.898720506302, 21583.352499425044, 21623.0, 21708.674369884466, 21771.129154833947, 9959.900400995897, 10471.371717696962, 10484.528196231187, 10508.159347252202, 10510.316829406063, 7921.920780792118, 7947.3244774194845, 8057.234562716532, 8088.757337279891, 8099.757007289791, 17524.03617801033, 17713.29146834112, 18204.4538663835, 18329.541761455537, 18657.160427780513, 18329.633407332047, 18917.337893173673, 19086.809131908503, 19216.039198040322, 19235.615287693887, 10627.89372106269, 10829.891701082888, 10840.45797710127, 10857.40284284372, 10870.673879783619, 13474.865251347754, 13968.0, 14043.297835108, 14045.859541404456, 14056.0, 10971.506282217479, 11080.445977700922, 11147.888521114684, 11196.832047519456, 11329.830052549383, 10861.782764344827, 11144.888551114385, 11254.099672026383, 11372.374519401512, 11413.486393112164, 9271.0, 9545.045495450677, 9616.23070154372, 9620.37467564606, 9629.807403852024, 6905.930940690529, 7023.613701246481, 7038.2961703829205, 7052.153741550842, 7112.751053713096, 6883.518153729191, 6907.6891539881835, 7092.219855815959, 7096.0, 7183.497155199086], "value": 6442.488985592316, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.19138241111176, 501.0393379613287, 515.7807931629058, 518.862501437119, 532.8685258964143, 6172.1050447685175, 6187.4740647045, 6195.9999999999945, 6234.220722409699, 6255.812325630232, 6162.137300777884, 6226.470749986245, 6228.968855155729, 6229.508196721303, 6292.651246440535, 5182.896342073166, 5199.402068762084, 5199.792008319662, 5220.895582088356, 5293.15309550472, 4892.746323694739, 5024.623153263502, 5054.595632349422, 5094.592432605401, 5100.668456550312, 4962.627802914778, 4994.625403094764, 4997.325361076245, 5058.873528161807, 5104.489551044884, 4966.726830024347, 4970.552650261494, 5037.521435463644, 5042.445331013586, 5046.495350464961, 4515.683902126852, 4535.115652447774, 4548.56788605082, 4575.977120114411, 4579.29021001742, 6168.87662246757, 6292.87414251717, 6311.558190926637, 6341.365863413622, 6347.428731414195, 5681.517071048925, 5689.7724091036725, 5703.144528320744, 5746.224259724967, 5791.73937172824, 6574.276829548746, 6605.249608853657, 6637.800865974028, 6640.0703901453835, 6681.899771503431, 4918.778654960535, 4926.45808961014, 4940.010589304792, 4953.504649535053, 4983.9252411213865, 6910.44716422685, 6962.477814163958, 6970.423661071123, 6997.265287144827, 7057.717691292342, 5030.189131912509, 5034.697918124924, 5038.2190760432195, 5060.924086138711, 5063.772130254148, 5074.67014644047, 5128.666636668605, 5134.460881607452, 5149.9742501287255, 5157.819476318311, 5648.181013752983, 5674.801381951612, 5682.602217844794, 5694.9430505695245, 5736.1682556029145, 6287.9056814147825, 6374.362563743682, 6509.349065093547, 6617.047970848611, 6722.982930277374, 1735.4186347573705, 1736.1145815634118, 1743.6660954369918, 1760.9522334211124, 1762.6915289824485, 1056.4031322302874, 1203.5667159822458, 1204.837346958167, 1238.5355491690616, 1238.550895452323, 5651.858703532363, 5797.507211887037, 5800.20193739942, 5800.622959507618, 5801.361850196475, 6759.695813688298, 6779.288174741581, 6807.353301436356, 6821.863562728718, 6846.315368463114, 5745.798897038667, 5754.1944127822135, 5763.6541807491685, 5769.249997500298, 5779.768809247583, 3486.9476957845654, 3562.4834399011997, 3565.073080998959, 3568.7323450741296, 3579.409397449444, 6362.554621176566, 6387.329330420332, 6474.417302442803, 6506.804795856132, 6519.3154718754795, 6788.93211067883, 6798.66006699663, 6817.420519255819, 6889.138857642794, 6911.0, 6404.743810247631, 6455.7740479083, 6511.446527045159, 6588.242352129375, 6606.801795946226, 6622.403983641303, 6637.933620663732, 6645.136132302963, 6646.767363142267, 6746.0, 6609.389291229842, 6618.569792963442, 6664.633445160563, 6729.798106056826, 6736.562123461959, 6264.436200741955, 6334.651594162181, 6346.428821406095, 6468.482521398183, 6476.6114033158165, 4891.486393928586, 4956.950430495649, 4986.177280748636, 4992.650514463952, 5009.899802004013, 6611.272759996493, 6620.337966203534, 6651.600903945779, 6743.865122697421, 6804.142260250607, 6384.840378990562, 6404.110787317659, 6459.773907913201, 6470.417662410406, 6490.286068532363, 6396.42432181106, 6437.678116094083, 6493.532457666057, 6541.271475259056, 6562.09411529608, 6326.703025879776, 6451.161349024596, 6468.61188328683, 6495.123158373748, 6499.350064993463, 5960.403959604178, 6355.904661430175, 6487.3022377244515, 6490.961446168591, 6505.642189679613, 6195.194624698941, 6250.312465628868, 6269.18500594938, 6284.905726414016, 6327.651979141007], "value": 5063.152570080777, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [881.836860180867, 887.7336798960313, 906.9773255668607, 908.8500397434424, 917.3807679816123, 18947.71578426323, 19793.208271669115, 19892.0, 19899.50251243718, 19927.501812454695, 15623.609409764762, 15728.685426291497, 16411.507654770347, 17077.40229091982, 17167.0, 966.2318456826839, 976.5410257179115, 979.9706008819746, 980.0640388429033, 980.6126580000895, 961.8028304197658, 974.4348277998744, 985.571276494727, 991.2268430624125, 991.7768502087009, 1057.133150816331, 1065.206421216195, 1065.2915810985724, 1067.2422579968231, 1067.423591260722, 1009.6218661526999, 1021.3922715984015, 1026.6406757634843, 1027.6660085472251, 1037.3153718545723, 751.3087959077661, 752.9623518824037, 753.8906858505541, 753.9132999704995, 756.130449982519, 20472.488187795425, 20560.074796634188, 20605.79394206069, 20640.896795515928, 20698.275560355465, 21089.050992705364, 21679.783202167935, 22025.889870550705, 22040.669389959163, 22047.559048818934, 16755.83244167555, 17750.46748597544, 17799.288028478844, 17892.91053544737, 17963.281468741236, 3688.041109311598, 3704.573973992998, 3712.628737126293, 3714.739968202227, 3717.423799311111, 133326.33336833253, 134064.32967835196, 135500.999999999, 135851.32074339665, 136865.0, 15418.614534636723, 16129.274182661808, 16134.35462581507, 16179.595510112342, 16222.83777162237, 28587.28531786722, 28902.42195156127, 29993.70006299909, 30135.246618834706, 30418.087457376314, 7992.240737129963, 8187.71343002992, 8190.058143313476, 8220.8355832883, 8222.301104406193, 3828.7511311764647, 3846.576876543578, 3853.4412510186426, 3915.197384536163, 3962.623550762673, 5116.283720279164, 5173.3533308336455, 5186.170212766015, 5232.2413250078525, 5234.42421333653, 918.1277786103228, 930.265090578449, 952.661805059194, 955.4362925873768, 963.4075043848123, 693.2409012131708, 697.0380874393406, 708.6881772020295, 715.0846915947595, 718.7304760714732, 11556.94221528887, 11787.0, 11793.410329483491, 12092.939535302443, 12117.757644847054, 682.7234969837224, 687.0793137196181, 689.7034275261585, 699.4439420660588, 701.0815831261065, 4930.98914722481, 5062.367204099488, 5067.6452648315, 5110.156824124052, 5167.534921857051, 123618.0, 123619.0, 124891.0, 125648.0, 129742.0, 101249.0, 102458.48770756248, 107862.46068769606, 107889.0, 108459.0, 92390.53804730934, 92656.0, 92963.53518232501, 93524.53237733904, 94749.52625236829, 9220.124088211609, 9264.351495395258, 9300.72097837066, 9362.76593085151, 9438.811223775623, 43125.353119703854, 43375.0, 43523.9119022027, 44820.32769508525, 45260.0, 56626.7168664154, 60192.097118542384, 60598.0, 60770.0, 61853.0, 15430.614234644223, 15516.68966620684, 15631.0, 15795.921020394824, 15841.445549406177, 15919.68160636804, 16198.514044578682, 16323.18384080815, 16329.591760206089, 16483.91758041202, 14638.487652932095, 14808.111513309235, 14822.481213157487, 14950.92524537414, 14956.102633842005, 15167.772483412979, 15179.0, 15208.923955380153, 15347.462838800588, 15419.845801541842, 16046.67906641884, 16056.759148612546, 16164.272607732444, 16270.105144217181, 16299.266533006281, 10510.684679459628, 10521.105706014769, 10536.262461627612, 10557.313774604627, 10948.890511094787, 11135.387553684624, 11194.776104478027, 11245.662630121109, 11317.264377815416, 11565.016973557005], "value": 8702.562656577102, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1423.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 107.0, 107.0, 108.0, 108.0, 108.0, 108.0, 109.0, 107.0, 107.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 117.0, 116.0, 117.0, 118.0, 115.0, 118.0, 148.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 163.0, 163.0, 162.0, 171.0, 166.0, 164.0, 168.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 75.0, 73.0, 74.0, 75.0, 77.0, 75.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 81.0, 81.0, 85.0, 81.0, 79.0, 79.0, 80.0, 90.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 116.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 148.0, 148.0, 146.0, 147.0, 147.0, 148.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 53.0, 53.0, 55.0, 54.0, 53.0, 54.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 37.0, 37.0, 37.0, 38.0, 37.0, 38.0, 38.0, 37.0], "value": 37.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 88.0, 74.0, 75.0, 76.0, 73.0, 74.0, 72.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 111.0, 110.0, 110.0, 116.0, 113.0, 118.0, 114.0, 119.0, 117.0], "value": 114.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 170.0, 167.0, 167.0, 168.0, 176.0, 167.0, 174.0, 172.0, 174.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 63.0, 70.0, 64.0, 72.0, 69.0, 67.0, 66.0, 68.0], "value": 68.4, "unit": "score"}]}]} 16:10:44 INFO - Return code: 0 16:10:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:44 INFO - # TBPL SUCCESS # 16:10:44 INFO - Running post-action listener: _package_coverage_data 16:10:44 INFO - Running post-action listener: _resource_record_post_action 16:10:44 INFO - [mozharness: 2017-09-13 23:10:44.732057Z] Finished run-tests step (success) 16:10:44 INFO - Running post-run listener: _resource_record_post_run 16:10:44 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 346435584; Read time: 16; Write time: 227480 16:10:44 INFO - TinderboxPrint: CPU usage
13.1% 16:10:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:10:44 INFO - TinderboxPrint: I/O write bytes / time
346,435,584 / 227,480 16:10:44 INFO - TinderboxPrint: CPU idle
8,235.8 (86.9%) 16:10:44 INFO - TinderboxPrint: CPU user
1,195.2 (12.6%) 16:10:44 INFO - TinderboxPrint: Swap in / out
0 / 0 16:10:44 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13578240; Read time: 0; Write time: 2472 16:10:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:10:44 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 332857344; Read time: 0; Write time: 225008 16:10:45 INFO - Running post-run listener: _upload_blobber_files 16:10:45 INFO - Blob upload gear active. 16:10:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:45 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 16:10:45 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:10:45 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:10:45 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:10:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:10:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:46 INFO - (blobuploader) - INFO - Done attempting. 16:10:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:10:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:47 INFO - (blobuploader) - INFO - Done attempting. 16:10:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:10:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:10:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:48 INFO - (blobuploader) - INFO - Done attempting. 16:10:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:10:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:10:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:55 INFO - (blobuploader) - INFO - Done attempting. 16:10:55 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:55 INFO - Return code: 0 16:10:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:55 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56128c547c66edd2aa2e9e63b0eeb6bb3b24c8c00252d56733364e3bc8f7ab91c384602345d9d920ad72134d28097f639526155cdd97de9f9e7b80aaf9915fc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029eb703120d09ab2733a242cc21680bed9b32ea4e23b6e306905e50a83ec6433e5640d604a4416f00b4768f52fd3ce5cb8ae6ba53b4816c2bcac3f743f1d25a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7572cfef16d42913d28142f81014971ea15836db7a848770ace5d9828bd58d96085610b2d5d26e6e157a844c542fe9d4b4af86f253b0734992b8d2fe75676e1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/58b0662fda6d340c534bd4c45c9ef6d15919172bf435cc8d04689af042e43c461c2c37ff75f44e0d859776c3446700a5103ce2ce30689d1336273b50fe940c27"} 16:10:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:55 INFO - Contents: 16:10:55 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56128c547c66edd2aa2e9e63b0eeb6bb3b24c8c00252d56733364e3bc8f7ab91c384602345d9d920ad72134d28097f639526155cdd97de9f9e7b80aaf9915fc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029eb703120d09ab2733a242cc21680bed9b32ea4e23b6e306905e50a83ec6433e5640d604a4416f00b4768f52fd3ce5cb8ae6ba53b4816c2bcac3f743f1d25a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7572cfef16d42913d28142f81014971ea15836db7a848770ace5d9828bd58d96085610b2d5d26e6e157a844c542fe9d4b4af86f253b0734992b8d2fe75676e1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/58b0662fda6d340c534bd4c45c9ef6d15919172bf435cc8d04689af042e43c461c2c37ff75f44e0d859776c3446700a5103ce2ce30689d1336273b50fe940c27"} 16:10:55 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:55 INFO - Copying logs to upload dir... 16:10:55 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.122888 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-13 16:10:55.202716) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:10:55.209848) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408 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-Non-PGO/sha512/56128c547c66edd2aa2e9e63b0eeb6bb3b24c8c00252d56733364e3bc8f7ab91c384602345d9d920ad72134d28097f639526155cdd97de9f9e7b80aaf9915fc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029eb703120d09ab2733a242cc21680bed9b32ea4e23b6e306905e50a83ec6433e5640d604a4416f00b4768f52fd3ce5cb8ae6ba53b4816c2bcac3f743f1d25a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7572cfef16d42913d28142f81014971ea15836db7a848770ace5d9828bd58d96085610b2d5d26e6e157a844c542fe9d4b4af86f253b0734992b8d2fe75676e1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/58b0662fda6d340c534bd4c45c9ef6d15919172bf435cc8d04689af042e43c461c2c37ff75f44e0d859776c3446700a5103ce2ce30689d1336273b50fe940c27"} build_url:https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011449 build_url: 'https://queue.taskcluster.net/v1/task/JQ2c2hz0RGOZPHj6Jy9JKA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56128c547c66edd2aa2e9e63b0eeb6bb3b24c8c00252d56733364e3bc8f7ab91c384602345d9d920ad72134d28097f639526155cdd97de9f9e7b80aaf9915fc0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029eb703120d09ab2733a242cc21680bed9b32ea4e23b6e306905e50a83ec6433e5640d604a4416f00b4768f52fd3ce5cb8ae6ba53b4816c2bcac3f743f1d25a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e7572cfef16d42913d28142f81014971ea15836db7a848770ace5d9828bd58d96085610b2d5d26e6e157a844c542fe9d4b4af86f253b0734992b8d2fe75676e1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/58b0662fda6d340c534bd4c45c9ef6d15919172bf435cc8d04689af042e43c461c2c37ff75f44e0d859776c3446700a5103ce2ce30689d1336273b50fe940c27"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:10:55.241183) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:10:55.241596) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1505343013.716435-128736408 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:10:55.323488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:10:55.323814) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:10:55.324117) ========= ========= Total master_lag: 0.21 =========