builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1477533614.94 results: success (0) buildid: 20161026170734 builduid: e1aa0826a9c94846b483ff38d759946c revision: 861f21d94172c8da5bb3ad1ab17955022880234f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:14.942914) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:14.943511) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:14.943814) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:14.962145) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:14.962412) ========= 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=52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-26 19:00:15-- 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% 222M=0s 2016-10-26 19:00:15 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085759 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:15.062914) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:15.063230) ========= 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=52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016248 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:15.113365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 19:00:15.113763) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 861f21d94172c8da5bb3ad1ab17955022880234f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 861f21d94172c8da5bb3ad1ab17955022880234f --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=52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-26 19:00:15,859 truncating revision to first 12 chars 2016-10-26 19:00:15,859 Setting DEBUG logging. 2016-10-26 19:00:15,860 attempt 1/10 2016-10-26 19:00:15,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/861f21d94172?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 19:00:16,752 unpacking tar archive at: mozilla-aurora-861f21d94172/testing/mozharness/ program finished with exit code 0 elapsedTime=1.204862 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 19:00:16.332462) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:16.332817) ========= script_repo_revision: 861f21d94172c8da5bb3ad1ab17955022880234f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:16.333250) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:16.333551) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 19:00:16.343772) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-26 19:00:16.344174) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:00:17 INFO - MultiFileLogger online at 20161026 19:00:17 in /builds/slave/test 19:00:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:00:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:00:17 INFO - {'append_to_log': False, 19:00:17 INFO - 'base_work_dir': '/builds/slave/test', 19:00:17 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:00:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:00:17 INFO - 'branch': 'Mozilla-Aurora', 19:00:17 INFO - 'buildbot_json_path': 'buildprops.json', 19:00:17 INFO - 'config_files': ('talos/linux_config.py',), 19:00:17 INFO - 'default_actions': ('clobber', 19:00:17 INFO - 'read-buildbot-config', 19:00:17 INFO - 'download-and-extract', 19:00:17 INFO - 'populate-webroot', 19:00:17 INFO - 'create-virtualenv', 19:00:17 INFO - 'install', 19:00:17 INFO - 'run-tests'), 19:00:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:00:17 INFO - 'download_minidump_stackwalk': True, 19:00:17 INFO - 'download_symbols': 'ondemand', 19:00:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:00:17 INFO - 'tooltool.py': '/tools/tooltool.py', 19:00:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:00:17 INFO - '/tools/misc-python/virtualenv.py')}, 19:00:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:00:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:00:17 INFO - 'installer_path': 'installer.exe', 19:00:17 INFO - 'log_level': 'info', 19:00:17 INFO - 'log_name': 'talos', 19:00:17 INFO - 'log_to_console': True, 19:00:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:00:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:00:17 INFO - 'opt_config_files': (), 19:00:17 INFO - 'pip_index': False, 19:00:17 INFO - 'sps_profile': False, 19:00:17 INFO - 'sps_profile_interval': 0, 19:00:17 INFO - 'suite': 'dromaeojs', 19:00:17 INFO - 'system_bits': '32', 19:00:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:00:17 INFO - 'title': 'talos-linux64-ix-056', 19:00:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:00:17 INFO - 'use_talos_json': True, 19:00:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:00:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:00:17 INFO - 'work_dir': 'build'} 19:00:17 INFO - [mozharness: 2016-10-27 02:00:17.191635Z] Running clobber step. 19:00:17 INFO - Running pre-action listener: _resource_record_pre_action 19:00:17 INFO - Running main action method: clobber 19:00:17 INFO - rmtree: /builds/slave/test/build 19:00:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:00:17 INFO - Running post-action listener: _resource_record_post_action 19:00:17 INFO - [mozharness: 2016-10-27 02:00:17.650555Z] Finished clobber step (success) 19:00:17 INFO - [mozharness: 2016-10-27 02:00:17.650655Z] Running read-buildbot-config step. 19:00:17 INFO - Running pre-action listener: _resource_record_pre_action 19:00:17 INFO - Running main action method: read_buildbot_config 19:00:17 INFO - Using buildbot properties: 19:00:17 INFO - { 19:00:17 INFO - "project": "", 19:00:17 INFO - "product": "firefox", 19:00:17 INFO - "script_repo_revision": "production", 19:00:17 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:00:17 INFO - "repository": "", 19:00:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:00:17 INFO - "buildid": "20161026170734", 19:00:17 INFO - "pgo_build": "True", 19:00:17 INFO - "basedir": "/builds/slave/test", 19:00:17 INFO - "buildnumber": 592, 19:00:17 INFO - "slavename": "talos-linux64-ix-056", 19:00:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:00:17 INFO - "platform": "ubuntu64_hw", 19:00:17 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:00:17 INFO - "branch": "mozilla-aurora", 19:00:17 INFO - "repo_path": "releases/mozilla-aurora", 19:00:17 INFO - "revision": "861f21d94172c8da5bb3ad1ab17955022880234f", 19:00:17 INFO - "stage_platform": "linux64-pgo", 19:00:17 INFO - "builduid": "e1aa0826a9c94846b483ff38d759946c", 19:00:17 INFO - "slavebuilddir": "test" 19:00:17 INFO - } 19:00:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 19:00:17 INFO - Running post-action listener: _resource_record_post_action 19:00:17 INFO - [mozharness: 2016-10-27 02:00:17.653088Z] Finished read-buildbot-config step (success) 19:00:17 INFO - [mozharness: 2016-10-27 02:00:17.653178Z] Running download-and-extract step. 19:00:17 INFO - Running pre-action listener: _resource_record_pre_action 19:00:17 INFO - Running main action method: download_and_extract 19:00:17 INFO - mkdir: /builds/slave/test/build/tests 19:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:17 INFO - trying https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:00:17 INFO - Downloading https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:00:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:00:18 INFO - Downloaded 1361 bytes. 19:00:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:00:18 INFO - Using the following test package requirements: 19:00:18 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:00:18 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:00:18 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:00:18 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'jsshell-linux-x86_64.zip'], 19:00:18 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:00:18 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:00:18 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:00:18 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:00:18 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:00:18 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:00:18 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:00:18 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:00:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:00:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:00:18 INFO - Fetch https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:00:18 INFO - Expected file size: 21892785 19:00:23 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:00:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:00:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:00:23 INFO - Fetch https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:00:23 INFO - Expected file size: 21892785 19:00:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 19:00:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:00:28 INFO - Fetch https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 19:00:28 INFO - Expected file size: 14414081 19:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:29 INFO - trying https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:00:29 INFO - Downloading https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:00:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:00:30 INFO - Downloaded 63956965 bytes. 19:00:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:00:30 INFO - mkdir: /builds/slave/test/properties 19:00:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:30 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:30 INFO - Contents: 19:00:30 INFO - build_url:https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:00:31 INFO - Running post-action listener: _resource_record_post_action 19:00:31 INFO - Running post-action listener: set_extra_try_arguments 19:00:31 INFO - [mozharness: 2016-10-27 02:00:31.155662Z] Finished download-and-extract step (success) 19:00:31 INFO - [mozharness: 2016-10-27 02:00:31.155964Z] Running populate-webroot step. 19:00:31 INFO - Running pre-action listener: _resource_record_pre_action 19:00:31 INFO - Running main action method: populate_webroot 19:00:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:00:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:00:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:00:31 INFO - '--tppagecycles', 19:00:31 INFO - '7'], 19:00:31 INFO - 'tests': ['tsvgm']}}, 19:00:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['damp', 'tps']}, 19:00:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'tests': ['damp', 'tps']}, 19:00:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['dromaeo_dom']}, 19:00:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['basic_compositor_video']}, 19:00:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:00:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['a11yr', 19:00:31 INFO - 'ts_paint', 19:00:31 INFO - 'tpaint', 19:00:31 INFO - 'sessionrestore', 19:00:31 INFO - 'sessionrestore_no_auto_restore', 19:00:31 INFO - 'tabpaint']}, 19:00:31 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:31 INFO - 'ts_paint', 19:00:31 INFO - 'tpaint', 19:00:31 INFO - 'sessionrestore', 19:00:31 INFO - 'sessionrestore_no_auto_restore', 19:00:31 INFO - 'tabpaint']}, 19:00:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:00:31 INFO - 'ts_paint', 19:00:31 INFO - 'tpaint', 19:00:31 INFO - 'sessionrestore', 19:00:31 INFO - 'sessionrestore_no_auto_restore', 19:00:31 INFO - 'tabpaint']}, 19:00:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:00:31 INFO - 'ts_paint', 19:00:31 INFO - 'tpaint', 19:00:31 INFO - 'sessionrestore', 19:00:31 INFO - 'sessionrestore_no_auto_restore', 19:00:31 INFO - 'tabpaint']}, 19:00:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['a11yr', 19:00:31 INFO - 'ts_paint', 19:00:31 INFO - 'tpaint', 19:00:31 INFO - 'sessionrestore', 19:00:31 INFO - 'sessionrestore_no_auto_restore', 19:00:31 INFO - 'tabpaint']}, 19:00:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['a11yr', 19:00:31 INFO - 'ts_paint', 19:00:31 INFO - 'tpaint', 19:00:31 INFO - 'sessionrestore', 19:00:31 INFO - 'sessionrestore_no_auto_restore', 19:00:31 INFO - 'tabpaint']}, 19:00:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['tsvgx', 19:00:31 INFO - 'tsvgr_opacity', 19:00:31 INFO - 'tart', 19:00:31 INFO - 'tscrollx', 19:00:31 INFO - 'cart']}, 19:00:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:31 INFO - 'tsvgr_opacity', 19:00:31 INFO - 'tart', 19:00:31 INFO - 'tscrollx', 19:00:31 INFO - 'cart']}, 19:00:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'talos_options': ['--disable-e10s'], 19:00:31 INFO - 'tests': ['tp5o']}, 19:00:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'tests': ['tp5o']}, 19:00:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'talos_options': ['--disable-e10s', 19:00:31 INFO - '--xperf_path', 19:00:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:31 INFO - 'tests': ['tp5n']}, 19:00:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:00:31 INFO - 'talos_options': ['--xperf_path', 19:00:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:31 INFO - 'tests': ['tp5n']}}, 19:00:31 INFO - 'talos.zip': {'path': '', 19:00:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:00:31 INFO - Running post-action listener: _resource_record_post_action 19:00:31 INFO - [mozharness: 2016-10-27 02:00:31.179825Z] Finished populate-webroot step (success) 19:00:31 INFO - [mozharness: 2016-10-27 02:00:31.179918Z] Running create-virtualenv step. 19:00:31 INFO - Running pre-action listener: _resource_record_pre_action 19:00:31 INFO - Running main action method: create_virtualenv 19:00:31 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:00:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:00:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:31 INFO - Using real prefix '/usr' 19:00:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:00:31 INFO - Installing distribute.............................................................................................................................................................................................done. 19:00:33 INFO - Installing pip.................done. 19:00:33 INFO - Return code: 0 19:00:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:00:33 INFO - Using env: {'DISPLAY': ':0', 19:00:33 INFO - 'HOME': '/home/cltbld', 19:00:33 INFO - 'LANG': 'en_US.UTF-8', 19:00:33 INFO - 'LANGUAGE': 'en_US:en', 19:00:33 INFO - 'LOGNAME': 'cltbld', 19:00:33 INFO - 'MAIL': '/var/mail/cltbld', 19:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:33 INFO - 'MOZ_NO_REMOTE': '1', 19:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:33 INFO - 'NO_EM_RESTART': '1', 19:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:33 INFO - 'PWD': '/builds/slave/test', 19:00:33 INFO - 'SHELL': '/bin/bash', 19:00:33 INFO - 'SHLVL': '1', 19:00:33 INFO - 'TERM': 'linux', 19:00:33 INFO - 'TMOUT': '86400', 19:00:33 INFO - 'USER': 'cltbld', 19:00:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:33 INFO - '_': '/tools/buildbot/bin/python'} 19:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:00:33 INFO - Cleaning up... 19:00:33 INFO - Return code: 0 19:00:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:00:33 INFO - Using env: {'DISPLAY': ':0', 19:00:33 INFO - 'HOME': '/home/cltbld', 19:00:33 INFO - 'LANG': 'en_US.UTF-8', 19:00:33 INFO - 'LANGUAGE': 'en_US:en', 19:00:33 INFO - 'LOGNAME': 'cltbld', 19:00:33 INFO - 'MAIL': '/var/mail/cltbld', 19:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:33 INFO - 'MOZ_NO_REMOTE': '1', 19:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:33 INFO - 'NO_EM_RESTART': '1', 19:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:33 INFO - 'PWD': '/builds/slave/test', 19:00:33 INFO - 'SHELL': '/bin/bash', 19:00:33 INFO - 'SHLVL': '1', 19:00:33 INFO - 'TERM': 'linux', 19:00:33 INFO - 'TMOUT': '86400', 19:00:33 INFO - 'USER': 'cltbld', 19:00:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:33 INFO - '_': '/tools/buildbot/bin/python'} 19:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:34 INFO - Downloading/unpacking psutil>=3.1.1 19:00:34 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 19:00:34 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 19:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:34 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 19:00:34 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 19:00:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:00:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:36 INFO - Installing collected packages: psutil 19:00:36 INFO - Running setup.py install for psutil 19:00:36 INFO - building 'psutil._psutil_linux' extension 19:00:36 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 19:00:36 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 19:00:36 INFO - building 'psutil._psutil_posix' extension 19:00:36 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 19:00:36 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 19:00:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:36 INFO - Successfully installed psutil 19:00:36 INFO - Cleaning up... 19:00:36 INFO - Return code: 0 19:00:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:00:36 INFO - Using env: {'DISPLAY': ':0', 19:00:36 INFO - 'HOME': '/home/cltbld', 19:00:36 INFO - 'LANG': 'en_US.UTF-8', 19:00:36 INFO - 'LANGUAGE': 'en_US:en', 19:00:36 INFO - 'LOGNAME': 'cltbld', 19:00:36 INFO - 'MAIL': '/var/mail/cltbld', 19:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:36 INFO - 'MOZ_NO_REMOTE': '1', 19:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:36 INFO - 'NO_EM_RESTART': '1', 19:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:36 INFO - 'PWD': '/builds/slave/test', 19:00:36 INFO - 'SHELL': '/bin/bash', 19:00:36 INFO - 'SHLVL': '1', 19:00:36 INFO - 'TERM': 'linux', 19:00:36 INFO - 'TMOUT': '86400', 19:00:36 INFO - 'USER': 'cltbld', 19:00:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:36 INFO - '_': '/tools/buildbot/bin/python'} 19:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:00: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 19:00: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 19:00:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00: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 19:00: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 19:00:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:00:38 INFO - Installing collected packages: mozsystemmonitor 19:00:38 INFO - Running setup.py install for mozsystemmonitor 19:00:39 INFO - Successfully installed mozsystemmonitor 19:00:39 INFO - Cleaning up... 19:00:39 INFO - Return code: 0 19:00:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:00:39 INFO - Using env: {'DISPLAY': ':0', 19:00:39 INFO - 'HOME': '/home/cltbld', 19:00:39 INFO - 'LANG': 'en_US.UTF-8', 19:00:39 INFO - 'LANGUAGE': 'en_US:en', 19:00:39 INFO - 'LOGNAME': 'cltbld', 19:00:39 INFO - 'MAIL': '/var/mail/cltbld', 19:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:39 INFO - 'MOZ_NO_REMOTE': '1', 19:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:39 INFO - 'NO_EM_RESTART': '1', 19:00:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:39 INFO - 'PWD': '/builds/slave/test', 19:00:39 INFO - 'SHELL': '/bin/bash', 19:00:39 INFO - 'SHLVL': '1', 19:00:39 INFO - 'TERM': 'linux', 19:00:39 INFO - 'TMOUT': '86400', 19:00:39 INFO - 'USER': 'cltbld', 19:00:39 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:39 INFO - '_': '/tools/buildbot/bin/python'} 19:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:39 INFO - Downloading/unpacking jsonschema==2.5.1 19:00:39 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 19:00:39 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 19:00:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:39 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 19:00:39 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 19:00:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:00:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:00: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 19:00: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 19:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00: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 19:00: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 19:00:41 INFO - Downloading functools32-3.2.3-2.tar.gz 19:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:00:41 INFO - warning: no files found matching '*.txt' 19:00:41 INFO - no previously-included directories found matching 'build' 19:00:41 INFO - no previously-included directories found matching 'dist' 19:00:41 INFO - no previously-included directories found matching '.git*' 19:00:41 INFO - Installing collected packages: jsonschema, functools32 19:00:41 INFO - Running setup.py install for functools32 19:00:41 INFO - warning: no files found matching '*.txt' 19:00:41 INFO - no previously-included directories found matching 'build' 19:00:41 INFO - no previously-included directories found matching 'dist' 19:00:41 INFO - no previously-included directories found matching '.git*' 19:00:41 INFO - Successfully installed jsonschema functools32 19:00:41 INFO - Cleaning up... 19:00:41 INFO - Return code: 0 19:00:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:00:41 INFO - Using env: {'DISPLAY': ':0', 19:00:41 INFO - 'HOME': '/home/cltbld', 19:00:41 INFO - 'LANG': 'en_US.UTF-8', 19:00:41 INFO - 'LANGUAGE': 'en_US:en', 19:00:41 INFO - 'LOGNAME': 'cltbld', 19:00:41 INFO - 'MAIL': '/var/mail/cltbld', 19:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:41 INFO - 'MOZ_NO_REMOTE': '1', 19:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:41 INFO - 'NO_EM_RESTART': '1', 19:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:41 INFO - 'PWD': '/builds/slave/test', 19:00:41 INFO - 'SHELL': '/bin/bash', 19:00:41 INFO - 'SHLVL': '1', 19:00:41 INFO - 'TERM': 'linux', 19:00:41 INFO - 'TMOUT': '86400', 19:00:41 INFO - 'USER': 'cltbld', 19:00:41 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:41 INFO - '_': '/tools/buildbot/bin/python'} 19:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:00:41 INFO - Cleaning up... 19:00:41 INFO - Return code: 0 19:00:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:00:41 INFO - Using env: {'DISPLAY': ':0', 19:00:41 INFO - 'HOME': '/home/cltbld', 19:00:41 INFO - 'LANG': 'en_US.UTF-8', 19:00:41 INFO - 'LANGUAGE': 'en_US:en', 19:00:41 INFO - 'LOGNAME': 'cltbld', 19:00:41 INFO - 'MAIL': '/var/mail/cltbld', 19:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:41 INFO - 'MOZ_NO_REMOTE': '1', 19:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:41 INFO - 'NO_EM_RESTART': '1', 19:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:41 INFO - 'PWD': '/builds/slave/test', 19:00:41 INFO - 'SHELL': '/bin/bash', 19:00:41 INFO - 'SHLVL': '1', 19:00:41 INFO - 'TERM': 'linux', 19:00:41 INFO - 'TMOUT': '86400', 19:00:41 INFO - 'USER': 'cltbld', 19:00:41 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:41 INFO - '_': '/tools/buildbot/bin/python'} 19:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:42 INFO - Downloading/unpacking blobuploader==1.2.4 19:00:42 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 19:00:42 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 19:00:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:42 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 19:00:42 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 19:00:44 INFO - Downloading blobuploader-1.2.4.tar.gz 19:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:00:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:00: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 19:00: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 19:00:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00: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 19:00: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 19:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:00:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:00: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 19:00: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 19:00:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00: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 19:00: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 19:00:44 INFO - Downloading docopt-0.6.1.tar.gz 19:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:00:44 INFO - Installing collected packages: blobuploader, requests, docopt 19:00:44 INFO - Running setup.py install for blobuploader 19:00:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:00:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:00:44 INFO - Running setup.py install for requests 19:00:45 INFO - Running setup.py install for docopt 19:00:45 INFO - Successfully installed blobuploader requests docopt 19:00:45 INFO - Cleaning up... 19:00:45 INFO - Return code: 0 19:00:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:00:45 INFO - Using env: {'DISPLAY': ':0', 19:00:45 INFO - 'HOME': '/home/cltbld', 19:00:45 INFO - 'LANG': 'en_US.UTF-8', 19:00:45 INFO - 'LANGUAGE': 'en_US:en', 19:00:45 INFO - 'LOGNAME': 'cltbld', 19:00:45 INFO - 'MAIL': '/var/mail/cltbld', 19:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:45 INFO - 'MOZ_NO_REMOTE': '1', 19:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:45 INFO - 'NO_EM_RESTART': '1', 19:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:45 INFO - 'PWD': '/builds/slave/test', 19:00:45 INFO - 'SHELL': '/bin/bash', 19:00:45 INFO - 'SHLVL': '1', 19:00:45 INFO - 'TERM': 'linux', 19:00:45 INFO - 'TMOUT': '86400', 19:00:45 INFO - 'USER': 'cltbld', 19:00:45 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:45 INFO - '_': '/tools/buildbot/bin/python'} 19:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:45 INFO - Running setup.py (path:/tmp/pip-J9ScEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:45 INFO - Running setup.py (path:/tmp/pip-6fsqCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:45 INFO - Running setup.py (path:/tmp/pip-UJ3UE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:45 INFO - Running setup.py (path:/tmp/pip-sUyOWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:45 INFO - Running setup.py (path:/tmp/pip-wV9y5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:46 INFO - Running setup.py (path:/tmp/pip-s8CHLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:46 INFO - Running setup.py (path:/tmp/pip-ENtOh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:46 INFO - Running setup.py (path:/tmp/pip-ZoiFU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:46 INFO - Running setup.py (path:/tmp/pip-skniG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:46 INFO - Running setup.py (path:/tmp/pip-qJA2RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:46 INFO - Running setup.py (path:/tmp/pip-Uc5tg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:46 INFO - Running setup.py (path:/tmp/pip-U6_zpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:46 INFO - Running setup.py (path:/tmp/pip-oXGajZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:46 INFO - Running setup.py (path:/tmp/pip-Hxb_FQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:46 INFO - Running setup.py (path:/tmp/pip-IO14mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:46 INFO - Running setup.py (path:/tmp/pip-HPB6eC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:46 INFO - Running setup.py (path:/tmp/pip-Pj2ZRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:00:46 INFO - Running setup.py install for manifestparser 19:00:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Running setup.py install for mozcrash 19:00:47 INFO - Running setup.py install for mozdebug 19:00:47 INFO - Running setup.py install for mozdevice 19:00:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Running setup.py install for mozfile 19:00:47 INFO - Running setup.py install for mozhttpd 19:00:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Running setup.py install for mozinfo 19:00:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Running setup.py install for mozInstall 19:00:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Running setup.py install for mozleak 19:00:47 INFO - Running setup.py install for mozlog 19:00:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:00:47 INFO - Running setup.py install for moznetwork 19:00:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:00:48 INFO - Running setup.py install for mozprocess 19:00:48 INFO - Running setup.py install for mozprofile 19:00:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:00:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:00:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:00:48 INFO - Running setup.py install for mozrunner 19:00:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:00:48 INFO - Running setup.py install for mozscreenshot 19:00:48 INFO - Running setup.py install for moztest 19:00:48 INFO - Running setup.py install for mozversion 19:00:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:00:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:00:48 INFO - Cleaning up... 19:00:48 INFO - Return code: 0 19:00:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:00:48 INFO - Using env: {'DISPLAY': ':0', 19:00:48 INFO - 'HOME': '/home/cltbld', 19:00:48 INFO - 'LANG': 'en_US.UTF-8', 19:00:48 INFO - 'LANGUAGE': 'en_US:en', 19:00:48 INFO - 'LOGNAME': 'cltbld', 19:00:48 INFO - 'MAIL': '/var/mail/cltbld', 19:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:48 INFO - 'MOZ_NO_REMOTE': '1', 19:00:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:48 INFO - 'NO_EM_RESTART': '1', 19:00:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:48 INFO - 'PWD': '/builds/slave/test', 19:00:48 INFO - 'SHELL': '/bin/bash', 19:00:48 INFO - 'SHLVL': '1', 19:00:48 INFO - 'TERM': 'linux', 19:00:48 INFO - 'TMOUT': '86400', 19:00:48 INFO - 'USER': 'cltbld', 19:00:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:48 INFO - '_': '/tools/buildbot/bin/python'} 19:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:48 INFO - Running setup.py (path:/tmp/pip-tL_79i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00: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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:49 INFO - Running setup.py (path:/tmp/pip-8JaBwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:49 INFO - Running setup.py (path:/tmp/pip-2scDx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00: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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:49 INFO - Running setup.py (path:/tmp/pip-2cC1dQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:49 INFO - Running setup.py (path:/tmp/pip-1gDtJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00: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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:49 INFO - Running setup.py (path:/tmp/pip-xIhStL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00: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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:49 INFO - Running setup.py (path:/tmp/pip-dNBFlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:49 INFO - Running setup.py (path:/tmp/pip-DRZLWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:49 INFO - Running setup.py (path:/tmp/pip-05FTtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:49 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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:49 INFO - Running setup.py (path:/tmp/pip-oTRu4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:49 INFO - Running setup.py (path:/tmp/pip-v_jB8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:49 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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:49 INFO - Running setup.py (path:/tmp/pip-9FtF8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:49 INFO - Running setup.py (path:/tmp/pip-uaEsZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:49 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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:49 INFO - Running setup.py (path:/tmp/pip-X4gBFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 19:00:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:49 INFO - Running setup.py (path:/tmp/pip-voma_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00: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)) 19:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:50 INFO - Running setup.py (path:/tmp/pip-gruK0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 19:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:50 INFO - Running setup.py (path:/tmp/pip-_Im_ZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00: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)) 19:00: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:00: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)) 19:00: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:00:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00: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 19:00: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 19:00:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00: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 19:00: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 19:00:52 INFO - Downloading blessings-1.6.tar.gz 19:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:52 INFO - Installing collected packages: blessings 19:00:52 INFO - Running setup.py install for blessings 19:00:52 INFO - Successfully installed blessings 19:00:52 INFO - Cleaning up... 19:00:52 INFO - Return code: 0 19:00:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:52 INFO - Reading from file tmpfile_stdout 19:00:52 INFO - Current package versions: 19:00:52 INFO - argparse == 1.2.1 19:00:52 INFO - blessings == 1.6 19:00:52 INFO - blobuploader == 1.2.4 19:00:52 INFO - docopt == 0.6.1 19:00:52 INFO - functools32 == 3.2.3-2 19:00:52 INFO - jsonschema == 2.5.1 19:00:52 INFO - manifestparser == 1.1 19:00:52 INFO - mozInstall == 1.12 19:00:52 INFO - mozcrash == 0.17 19:00:52 INFO - mozdebug == 0.1 19:00:52 INFO - mozdevice == 0.48 19:00:52 INFO - mozfile == 1.2 19:00:52 INFO - mozhttpd == 0.7 19:00:52 INFO - mozinfo == 0.9 19:00:52 INFO - mozleak == 0.1 19:00:52 INFO - mozlog == 3.4 19:00:52 INFO - moznetwork == 0.27 19:00:52 INFO - mozprocess == 0.23 19:00:52 INFO - mozprofile == 0.28 19:00:52 INFO - mozrunner == 6.12 19:00:52 INFO - mozscreenshot == 0.1 19:00:52 INFO - mozsystemmonitor == 0.3 19:00:52 INFO - moztest == 0.7 19:00:52 INFO - mozversion == 1.4 19:00:52 INFO - psutil == 3.1.1 19:00:52 INFO - requests == 1.2.3 19:00:52 INFO - wsgiref == 0.1.2 19:00:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:00: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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:00:52 INFO - Using env: {'DISPLAY': ':0', 19:00:52 INFO - 'HOME': '/home/cltbld', 19:00:52 INFO - 'LANG': 'en_US.UTF-8', 19:00:52 INFO - 'LANGUAGE': 'en_US:en', 19:00:52 INFO - 'LOGNAME': 'cltbld', 19:00:52 INFO - 'MAIL': '/var/mail/cltbld', 19:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:52 INFO - 'MOZ_NO_REMOTE': '1', 19:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:52 INFO - 'NO_EM_RESTART': '1', 19:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:52 INFO - 'PWD': '/builds/slave/test', 19:00:52 INFO - 'SHELL': '/bin/bash', 19:00:52 INFO - 'SHLVL': '1', 19:00:52 INFO - 'TERM': 'linux', 19:00:52 INFO - 'TMOUT': '86400', 19:00:52 INFO - 'USER': 'cltbld', 19:00:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:52 INFO - '_': '/tools/buildbot/bin/python'} 19:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00: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)) 19:00: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)) 19:00: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)) 19:00: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)) 19:00: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)) 19:00: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)) 19:00: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)) 19:00: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)) 19:00: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)) 19:00:52 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)) 19:00:52 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)) 19:00:52 INFO - Cleaning up... 19:00:52 INFO - Return code: 0 19:00:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f827c9edf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac5ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, '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': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', '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 19:00:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:00:52 INFO - Using env: {'DISPLAY': ':0', 19:00:52 INFO - 'HOME': '/home/cltbld', 19:00:52 INFO - 'LANG': 'en_US.UTF-8', 19:00:52 INFO - 'LANGUAGE': 'en_US:en', 19:00:52 INFO - 'LOGNAME': 'cltbld', 19:00:52 INFO - 'MAIL': '/var/mail/cltbld', 19:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:52 INFO - 'MOZ_NO_REMOTE': '1', 19:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:52 INFO - 'NO_EM_RESTART': '1', 19:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:52 INFO - 'PWD': '/builds/slave/test', 19:00:52 INFO - 'SHELL': '/bin/bash', 19:00:52 INFO - 'SHLVL': '1', 19:00:52 INFO - 'TERM': 'linux', 19:00:52 INFO - 'TMOUT': '86400', 19:00:52 INFO - 'USER': 'cltbld', 19:00:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:52 INFO - '_': '/tools/buildbot/bin/python'} 19:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:00:53 INFO - Cleaning up... 19:00:53 INFO - Return code: 0 19:00:53 INFO - Running post-action listener: _resource_record_post_action 19:00:53 INFO - Running post-action listener: _start_resource_monitoring 19:00:53 INFO - Starting resource monitoring. 19:00:53 INFO - [mozharness: 2016-10-27 02:00:53.085537Z] Finished create-virtualenv step (success) 19:00:53 INFO - [mozharness: 2016-10-27 02:00:53.086250Z] Running install step. 19:00:53 INFO - Running pre-action listener: _resource_record_pre_action 19:00:53 INFO - Running main action method: install 19:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:53 INFO - Reading from file tmpfile_stdout 19:00:53 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:53 INFO - Reading from file tmpfile_stdout 19:00:53 INFO - Output received: 19:00:53 INFO - Usage: mozinstall [options] installer 19:00:53 INFO - Options: 19:00:53 INFO - -h, --help show this help message and exit 19:00:53 INFO - -d DEST, --destination=DEST 19:00:53 INFO - Directory to install application into. [default: 19:00:53 INFO - "/builds/slave/test"] 19:00:53 INFO - --app=APP Application being installed. [default: firefox] 19:00:53 INFO - mkdir: /builds/slave/test/build/application 19:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:01:09 INFO - Reading from file tmpfile_stdout 19:01:09 INFO - Output received: 19:01:09 INFO - /builds/slave/test/build/application/firefox/firefox 19:01:09 INFO - Running post-action listener: _resource_record_post_action 19:01:09 INFO - [mozharness: 2016-10-27 02:01:09.354048Z] Finished install step (success) 19:01:09 INFO - [mozharness: 2016-10-27 02:01:09.354388Z] Running run-tests step. 19:01:09 INFO - Running pre-action listener: _resource_record_pre_action 19:01:09 INFO - Running main action method: run_tests 19:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:01:09 INFO - Python 2.7.3 19:01:09 INFO - Return code: 0 19:01:09 INFO - grabbing minidump binary from tooltool 19:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 19:01:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/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 0x2ac2f60>, '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 0x2ac3d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ac41d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:01:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:01:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:01:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:01:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:01:09 INFO - Return code: 0 19:01:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:01:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:01:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:01:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:01:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:01:09 INFO - Using env: {'DISPLAY': ':0', 19:01:09 INFO - 'HOME': '/home/cltbld', 19:01:09 INFO - 'LANG': 'en_US.UTF-8', 19:01:09 INFO - 'LANGUAGE': 'en_US:en', 19:01:09 INFO - 'LOGNAME': 'cltbld', 19:01:09 INFO - 'MAIL': '/var/mail/cltbld', 19:01:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:01:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:09 INFO - 'MOZ_NO_REMOTE': '1', 19:01:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:01:09 INFO - 'NO_EM_RESTART': '1', 19:01:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:09 INFO - 'PWD': '/builds/slave/test', 19:01:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:01:09 INFO - 'SHELL': '/bin/bash', 19:01:09 INFO - 'SHLVL': '1', 19:01:09 INFO - 'TERM': 'linux', 19:01:09 INFO - 'TMOUT': '86400', 19:01:09 INFO - 'USER': 'cltbld', 19:01:09 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390', 19:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:09 INFO - '_': '/tools/buildbot/bin/python'} 19:01:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:01:09 INFO - mozversion application_buildid: 20161026170734 19:01:09 INFO - mozversion application_changeset: 861f21d94172c8da5bb3ad1ab17955022880234f 19:01:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:01:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:01:09 INFO - mozversion application_name: Firefox 19:01:09 INFO - mozversion application_remotingname: firefox-dev 19:01:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:01:09 INFO - mozversion application_vendor: Mozilla 19:01:09 INFO - mozversion application_version: 51.0a2 19:01:09 INFO - mozversion platform_buildid: 20161026170734 19:01:09 INFO - mozversion platform_changeset: 861f21d94172c8da5bb3ad1ab17955022880234f 19:01:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:01:09 INFO - mozversion platform_version: 51.0a2 19:01:09 INFO - using testdate: 1477533669 19:01:09 INFO - actual date: 1477533669 19:01:09 INFO - starting webserver on 'localhost:53342' 19:01:09 INFO - SUITE-START | Running 2 tests 19:01:09 INFO - TEST-START | dromaeo_css 19:01:09 INFO - operating with platform_type : linux_ 19:01:09 INFO - Initialising browser for dromaeo_css test... 19:01:09 INFO - TEST-INFO | started process 9444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZQntLb/profile http://localhost:53342/getInfo.html) 19:01:13 INFO - PROCESS | 9444 | __metrics Screen width/height:1600/1200 19:01:13 INFO - PROCESS | 9444 | colorDepth:24 19:01:13 INFO - PROCESS | 9444 | Browser inner width/height: 1024/697 19:01:13 INFO - PROCESS | 9444 | __metrics 19:01:14 INFO - PROCESS | 9444 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:01:14 INFO - PROCESS | 9444 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:01:14 INFO - PROCESS | 9444 | console.error: PushService: 19:01:14 INFO - PROCESS | 9444 | stateChangeProcessEnqueue: Error transitioning state 19:01:14 INFO - PROCESS | 9444 | UnknownError 19:01:15 INFO - TEST-INFO | 9444: exit 0 19:01:15 INFO - Browser initialized. 19:01:15 INFO - Running cycle 1/1 for dromaeo_css test... 19:01:15 INFO - TEST-INFO | started process 9535 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZQntLb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:27 INFO - PROCESS | 9535 | RSS: Main: 144474112 19:01:27 INFO - PROCESS | 9535 | 19:02:18 INFO - PROCESS | 9535 | 1477533738438 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:04:27 INFO - PROCESS | 9535 | Cycle 1(1): loaded http://localhost:53342/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53342/tests/dromaeo/cssquery-ext.html) 19:04:28 INFO - PROCESS | 9535 | RSS: Main: 252682240 19:04:28 INFO - PROCESS | 9535 | 19:07:31 INFO - PROCESS | 9535 | Cycle 1(1): loaded http://localhost:53342/tests/dromaeo/cssquery-ext.html (next: http://localhost:53342/tests/dromaeo/cssquery-jquery.html) 19:07:31 INFO - PROCESS | 9535 | RSS: Main: 235065344 19:07:31 INFO - PROCESS | 9535 | 19:10:33 INFO - PROCESS | 9535 | Cycle 1(1): loaded http://localhost:53342/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53342/tests/dromaeo/cssquery-mootools.html) 19:10:34 INFO - PROCESS | 9535 | RSS: Main: 174678016 19:10:34 INFO - PROCESS | 9535 | 19:13:36 INFO - PROCESS | 9535 | Cycle 1(1): loaded http://localhost:53342/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53342/tests/dromaeo/cssquery-prototype.html) 19:13:36 INFO - PROCESS | 9535 | RSS: Main: 175357952 19:13:36 INFO - PROCESS | 9535 | 19:16:39 INFO - PROCESS | 9535 | Cycle 1(1): loaded http://localhost:53342/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53342/tests/dromaeo/cssquery-yui.html) 19:16:39 INFO - PROCESS | 9535 | RSS: Main: 178266112 19:16:39 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | Cycle 1(1): loaded http://localhost:53342/tests/dromaeo/cssquery-yui.html (next: http://localhost:53342/tests/dromaeo/cssquery-dojo.html) 19:19:42 INFO - PROCESS | 9535 | RSS: Main: 255139840 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | __start_tp_report 19:19:42 INFO - PROCESS | 9535 | _x_x_mozilla_page_load 19:19:42 INFO - PROCESS | 9535 | _x_x_mozilla_page_load_details 19:19:42 INFO - PROCESS | 9535 | |i|pagename|runs| 19:19:42 INFO - PROCESS | 9535 | |0;dojo.html;3162.3991441626094;3180.7614428917823;3219.146926064593;3245.9188520286975;3267.607887053554;5646.830595082149;5684.54523638109;5702.116171993337;5713.71431428428;5745.856353591157;5674.631148975313;5688.146777983305;5724.685142317171;5738.139279108136;5788.42115788421;5130.71781052042;5167.844964651066;5179.766910489018;5190.766415511311;5228.79084836606;5065.265536497206;5262.92105618416;5276.076686579852;5295.0468915595175;5310.283111779918;5070.2141168119;5127.897442051175;5154.819581314655;5161.225816127592;5227.63406561539;5237.266782650432;5252.395974462947;5302.257683924254;5321.866953326198;5360.9463905360835;5006.524380183877;5163.741812909339;5257.842264732064;5290.9735451322495;5314.893702125936;5506.834794956158;5542.473465020857;5552.7223638817895;5565.9443405565835;5605.047141985831;5991.850203744898;6039.456448919618;6106.938930610681;6121.602095863755;6131.386861313833;6005.129256257818;6112.235970503687;6146.784862529835;6158.322584515699;6177.166082578839;5582.86042848932;5645.435456454363;5656.971715141481;5660.066089095294;5680.488756011979;6223.533235007392;6226.782062627877;6232.8130156095385;6233.812985610439;6237.96881015592;5779.768809247667;5964.821055368346;5977.6114552553945;5989.580729348991;5990.520758339322;5893.823185304448;6042.244719410074;6118.632882027093;6124.938750612526;6180.8763824723255;6165.229346331708;6168.599041062316;6171.352008039181;6172.351903050205;6193.163922870355;6124.5100391968535;6141.140240366352;6171.166892469549;6183.258009038854;6187.659678717714;4576.496585375606;4605.608523275492;4615.838445654453;4640.907181856344;4667.579917807414;4118.155778065489;4122.773247471418;4124.566920473307;4136.607022332873;4149.875503734893;5765.1640512125505;5766.4233576642;5771.278590176228;5771.365149833515;5775.48020678141;6000.429959153874;6049.637021778707;6052.939470605325;6080.635161890301;6112.694365281718;5792.913106303409;5798.565107616945;5803.912941305884;5830.242068531149;5847;5314.1497360422145;5317.521423071942;5322.787088516493;5325.3609566851455;5338.172583249525;6070.7875224366935;6131.662758548323;6195.783147589813;6295.087212354183;6349;6127.693615319127;6218.22272215973;6225.221847269091;6240.095186198067;6252.562320637694;6355.87288254223;6372.617642941438;6389.041643753289;6392.0731493934045;6398.200224971879;5823.388544202967;5882.970585147047;5919.91120133207;6068.120122582289;6077.24034495688;5987.820365389045;6037.78867739627;6041.426064523863;6071.392860713893;6093.086037094471;5836.708164591669;5904.350521442552;5906.320773111147;5913.793017244548;5936.732847021981;5837;5878.882422351615;5897.64614123154;5900.793472228452;5903.350631430453;5789.507891829073;5822.7670893163795;5843.065109582616;5906.114082887601;5948.226730525177;5959.28488581373;5959.791407300723;5969.074793406855;5973.880522389615;5974.40255974399;5720.713964301852;5724.942750572441;5768.50967667753;5807.65154090739;5818.912716309174 19:19:42 INFO - PROCESS | 9535 | |1;ext.html;588.535057304729;616.6454288783949;634.6541135081383;640.9455196308312;670.3028849996005;47349.526504734946;49040.26439603404;49053;49062.50937490624;53287.46712532874;50525.494745052536;50834;51080.48919510813;51115.74442127794;51128.23307650388;8414.36892233082;8612.827743445127;9447.385919915201;9698.466584337859;9701.417914925127;11057.668269951873;11339.149563782708;11356.48895799687;11761.764764704702;11773;11254.662360129209;11264.4367781611;11292.378919159442;11307.208495405324;11314.094872410229;11354.261972971814;11377.431128443586;11439.828402573927;11446.084313255004;11458.312501249951;11282.661520154408;11311.151663625262;11337;11340.829887551694;11353.091752659768;46529.76735116337;46943.53056469426;46956.7652161737;46972;46990.53009469896;3909.885682580479;3919.2945269851416;4002.579729128429;4336.6530677545725;4545.886352841206;35912.64087359119;36188.819055904816;36291.63708362909;37693.24613507742;37760.811195944116;4407.735535867826;4439.64482841372;4460.821567137311;4469.44131983502;4744.739039352834;376340;378743;379072;379087.10456447816;379165;40086.79956600198;41105;67644;67746.322536774;68160.3183968164;42609.147817044104;42985.785071074446;43004;43018.35472467916;43060.56939430628;15902.522924312289;15908.443204487787;15918.124503152207;16054.919725401298;16443.260053297632;13913;14220.075695079786;14748.188849613374;15380.307886144927;15411.61470963235;9770.951145244371;9808.509574521246;9808.558614862348;9852.064053914866;9891.505424728735;3661.707063434919;3663.0036630036693;3721.1999420124803;3725.4970578971493;3741.960631455863;1047.2512153810062;1074.8441475985937;1086.4079076903106;1162.7790719763218;1188.6731148934111;34791.4781278281;34960.30079398398;35371.11572210664;35398.292034159174;35481.46777798335;1132.6771870017005;1166.6849950513315;1169.5964892112208;1177.2995067934564;1235.2958813476412;17898.373556925446;18264.45206643803;18546.165422556016;18594;18644.254229830927;564871;570096;570453;571654;572495;65884.34115658878;66328.33671663319;66447;66537.66731166313;66555.33444665492;1309.8821106100497;1356.9185848849102;1400.544822932551;1437.8993470456967;1475.852414758537;33396;35570.822145889106;35812.64187358093;36321.636783631824;36325.45511817378;56376.7181164108;56395;56793;56841.43158568361;56842.715786420806;386743;389131;389598;389768;390658;31547;31633.05100846978;32329.838350808095;32764.508532371558;34281.14297142492;53281;53617.19574206312;53648;53816.730916345165;54227.72886135544;44286.335704965095;44955.77522112369;45278.773606131756;46422.767886160356;46618;44609.77695111504;46214.53785462103;46532.53467465282;47006.05987880155;47351.52648473471;44084.33873491836;44665.10669786521;44889.77555112334;45052.549474504835;46219.07561848677;38777.61222387853;40117.1976560473;40251.39622905717;40296;40658.59341406548;29528.409431811673;29565;29759.10722678323;30091.398172036;30104 19:19:42 INFO - PROCESS | 9535 | |2;jquery.html;399.770132174;440.9713368631039;441.77469490559815;445.11200155689403;447.34687356459557;17568.824311756878;19330.323438679632;20869.165233390686;21587.568248635016;21667.45831354217;13635.863641363583;16791.91604041978;19095;19716.704249436236;19794;5543.473370029841;5723.685197314146;5724.398938111501;5729.169270455782;5741.253637027181;5624.775008999655;5861.3552509223955;6146.170267013963;6191.349908259635;6232.9376706232815;5967.85080372992;6228.252609686844;6243.438090571848;6257.43683068526;6273.278572964099;6136.23297087864;6226.782062627786;6230.127782110508;6233.28317243516;6239.750409983595;5860.736266868002;6040.154378387029;6071.150038994544;6077.301110372319;6119.326874043852;15220.01069930451;15451.381944722198;15451.381944722198;21242.256521021845;24487.38781530458;2464.630305454178;2474.591692370757;2475.628655701659;2502.036715864387;2543.326018605071;10086.848697269546;10617.150627949746;10634.096101831354;10923.126149907988;11012.834807477895;1953.4823271832975;2022.888741119238;2062.5256191075987;2072.9067191976396;2120.342693764938;75291.6235418825;87312;87813.56093219557;90550;90822.54588727014;10724.463776811128;11208.66374008781;11298.265612735146;11513.827292590619;11560.479778409823;16059;16528.00831950087;16887.15564221784;16929.322827087024;17445.302187912595;8689.087645797226;8725.345599079967;8745.91254087451;8792.296616270683;9408.012158723372;8948.955255223682;8958.059403762642;8971.955140224258;9190;9203.585838637277;5125.410577783603;5248.212768084742;5266.815661451831;5276;5395.136778115482;2130.318298144579;2168.4253672776726;2202.152171414017;2205.5258119504383;2216.866987980694;924.523870206844;1002.6641075239876;1027.0345874877592;1033.5762337441613;1085.1156307609274;18823.152958116916;19197.616047678966;19726.901365493082;19974.70037949432;20099.39701808948;1023.4831410137981;1031.0823367203195;1043.8277684182176;1043.874735031801;1050.9737256568494;10613.787724245472;10976.176786741027;10988.725281867857;11109.944450277697;11137.053350465301;474295;478963;502072;502218;502923;84582.5770871154;93627.53186234026;96099;97618.33343331939;107591;506.59978616892244;538.5368583018601;540.5378401466768;541.0558575286072;543.3941155611544;11795.587154449897;12000.57997970067;12164.330961797186;12376.195547289397;12833.614991550268;29118.417631646826;38499.807500963434;40013.39979900246;40748.59251407448;41270.793646031576;56415.71792141177;57249;57677;58342;58635.70682146562;5817.680027598353;8563.95718021406;8804.735857924272;8929.374943754136;9142.085791420805;12541.372931353213;13858.237796920863;13869.375878085308;13928.373223204777;14109.717805643624;12094.516219351128;12172.2696638202;12229.816552751538;12230.266184028987;12431.813522797345;12213.633590992284;12224.205426647239;12304.384780761106;12528.624141275777;12610.621681349574;12241.632751017483;12306.384680765746;12391.814122788346;12425.502979880703;12445;8882.60028298741;9067.818643627223;9101.590428430603;9279;9315.115064068903;11559.133065019989;11867.287962722265;11901.880981190423;11923.224990375598;12564.869161775485 19:19:42 INFO - PROCESS | 9535 | |3;mootools.html;1500.4373359990004;1548.2490991868942;1644.2518654012426;1790.5971156489784;1807.8824876383026;8794.736157915264;9438.386504877179;9445.480498572575;9445.763855903606;9457.621695132195;8163.387745919065;8408.117147699495;8792.69225577105;9142.177204051633;9143.45139291643;2520.8109391795597;2539.606361014046;2540.5808041673104;2550.591905295153;2554.1571281477068;2481.6525686403916;2572.8327658702124;2593.5590949538623;2594.2347007632698;2596.3379338268714;2533.961990570143;2545.7072436669837;2548.1463709657287;2550.5664037113643;2558.1046633678156;2240.1375470443913;2241.652543855697;2268.8298377621745;2323.7560056194193;2327.1156960355124;2500.962485562718;2501.8498890066653;2512.170983575434;2516.7609077137645;2535.239428171541;9397.530123493798;9452.676625272466;9465.479398633073;9468.005859384733;9469.053094690546;5475.151351540518;5587.0502014657195;5599.60802743804;5631.267935168402;5663.886722265532;3528.558930133733;3598.8200589970397;3684.6868016218655;3703.6296370363016;3706.425504046877;1164.5516476156656;1221.6090850927706;1234.5432190089634;1239.2874097394035;1239.727260002798;13026.41381137851;13093.279869607168;13102.80345794814;13108.541201057917;13120.671983200496;5079.669821461667;5121.74391280442;5245.6852588844795;5265.631405801557;5332.280142180756;10984.286021408583;11017.83473247902;11087.944560277147;11089.33463992147;11109.555617775199;5728.856778580569;5756.33802112754;5756.625819321815;5759.568032397503;5782.479576838105;4649.232876575395;4711.222648263067;5270.578353731692;5328.200769884548;5331.760070796823;4304.80628371724;4364.214441400515;4391.802368893407;4404.7357158570585;4429.734215947054;1383.1424516799648;1398.398977901103;1402.698419839719;1432.3697573067816;1465.8387577366482;987.160913223754;998.7353351361895;1004.1113614451264;1005.5073014223125;1014.2028009428882;6238;6309.179806625109;6344.460720838781;6379.64911929839;6478.060681201199;515.3300709078259;520.0638850069859;528.3659608469775;528.582419888284;531.4207513809965;4257.297545904953;4271.466066741657;4376.190404775127;4378.058717375779;4385.101054283864;11157.609483668033;11507.194496385173;11552.94223528877;11585.478653460614;11598.536058557733;10801.135909127252;11290.7177320566;11367.886321136848;11493.367864767351;11824.70438239051;8710.390272680977;8794.648214071365;8813.515256660818;8898.555072246361;8901.154390332907;6093.847653808691;6262.467690246198;6344.206974128234;6405.647689377129;6411.455026322815;10695.732606684895;10868.891311086787;11028.117750580095;11036.613718519813;11140.16448766362;10891.945540272249;10982.670519884416;11112.444377781241;11152.21934464612;11181.273217240854;7434.3309102181065;7707.075150981694;7919.366450684047;8219;8371;8945;9117.589708463003;9126.954365228132;9132.589033493376;9163;7681.385489160742;7814.53112813233;7837.412194085352;7918.445708800328;8190.180981902001;8014.799630009064;8069.193080691884;8082.555459449787;8163.7142700005215;8351.540665263468;7394.482386233127;7446.92553074484;7482.289182527651;7539.886901696588;7543.43424243173;4960.131976903956;5214.348206474191;5216.530512253916;5290.920636190537;5342.6260161788305;5510.972445137909;5558.694271814928;5638.379778224474;5679.17651940458;5838.883222335615 19:19:42 INFO - PROCESS | 9535 | |4;prototype.html;363.8035460851141;368.7510930122167;369.4532092503096;370.54052974311855;378.12086897962246;5521.585881058922;5528.640638358504;5535.667859928402;5560.193771903072;5601.887962240763;5247.396549396811;5431.022415965124;5474.698891560963;5532.889342213153;5541.418151094137;4326.891827704301;4345.065810850667;4350.956490435086;4352.934705979413;4384.671149663788;4353.651707863363;4357.346398040303;4358;4372.3822185791305;4404.955950440487;4218.873433796991;4288.270993931023;4300.462442194726;4335.333769731608;4344.761038142901;4276.123394704078;4277.401163837066;4301.225779359714;4301.78491075445;4326.156399502099;3856.980715096435;3857.3442514772555;3857.9035524112123;3866.1107945172766;3876.069743261597;5394.32570928634;5422.75597598109;5463.7814487420455;5467.58993075517;5480.342358916916;4845.933894543195;5020.297158397827;5026.9946010797985;5040.143175660147;5047.192449208109;5745.971270143665;5747.735498190434;5750.412314458468;5752.714227900199;5757.568182386338;4246.299360605496;4290.163418133465;4313.374560688714;4325.13497300538;4338.674599405026;5697.430256974311;5836.270466191683;5930.339504938581;5947.702614869239;5976.223090998143;4311.353297005474;4437.29003359461;4448.888777780581;4451.308502768992;4462.977685111554;4518.615917647036;4542.591166795004;4555.316702494586;4557.043020965568;4558.609624064626;4692.718436893797;4846.660733748674;5062.898742025139;5093.541581257705;5126.282320475137;5752.482276595127;5794.768209271666;5820.679862607512;5822.272215973004;5897;1561.1023661394743;1575.9527214183593;1581.7469204927156;1585.445094217015;1586.2386054693811;997.546116516983;1011.3729487717662;1020.9693709188737;1022.7340891368298;1025.4718819807806;4946.071032297407;4947.356843610308;4951;4980.302757613937;5009.874753131201;5166.992436474927;5169.0357664087405;5212.931349073431;5256.185291279858;5267.707682698314;4670.556297151765;4893.804247830047;4966.255061740768;4969.850904472799;4977.113274300897;2785.56823692328;2796.5245908195657;2798.4263226038615;2800.8739606717745;2805.228562145426;5306.92039619406;5318.25544423781;5351.357837059578;5367.624266301322;5401.243825864382;5074.5686616637995;5175.9641044038735;5267.55142335853;5292.015494189679;5315.149576067811;5306.814261500829;5326.573874090063;5338.332708411449;5351.331083614548;5372.247885296062;4950.801967921387;5200.921986170286;5209.4009188943755;5222.709692893604;5233.555147812479;5139.7664560505955;5155.458676838878;5160.48395160481;5182.481751824938;5226.8954620908125;5324.254604355316;5908.911366329595;5917.408259174048;6019.789307374221;6066.757329706938;4409.140217657526;4441.244988351923;4448.199324121689;4460.710053846489;4474.955250447454;5784.768609255751;6004.459598636144;6026;6048.969755151196;6050.45545900871;5782.421757824352;5812.970935145467;5830.5044071254415;5830.7959221427045;5841.153032810304;5784.7107644618445;5796.478316951326;5796.855078623068;5829.941700583109;5862.413758624103;5603.495685388335;5780.94219057804;5816.970915145397;5821.76712931478;5843.415658434123;5772.509336706259;5819.5053420459735;5830.271216097988;5831.891940531285;5884.35272120075;5553.194786755978;5699.660579763835;5725.883452726678;5740.913886291625;5747.655140691572 19:19:42 INFO - PROCESS | 9535 | |5;yui.html;802.113664400837;809.3929552835374;817.619806789843;822.6010384963296;917.5183028909821;15295.770563441543;16530.504084877444;16553.503394898144;16556.420525281632;16606.335746570137;16254.51236462908;16460.506184814476;16474.505764827074;16482.34070637176;16495;727.7707522130512;747.4058123791572;843.6709683223547;846.4709556527171;873.2795443758893;871.2289623683055;874.204473928725;876.5967654878764;876.6318146378504;877.8024944387525;917.4816228830723;918.6371383303591;918.8024574716435;919.0304229038362;919.0809190809191;752.6650640464995;790.7192946503994;863.5380071661631;898;910.0990019880337;676.5940435738567;676.6988690032937;676.8781619308522;677.40388458157;677.4072686399401;15964.361425542964;16007.679846403124;16023.35906563736;16028.95811772231;16052;14994.325255363536;15183.772243416357;15261.38954441821;15348.07911525312;15728.685426291524;12976.221426714334;13132.0807543472;14201.289935503184;14237.85762142386;14382.137071775622;4633.559811817906;4649.814007439699;4653.208954477713;4661.696989695659;4835.903281934342;83280;83808.58095709543;83987.5800620999;84289;85040.87397815043;11720.191406442667;12184.329861857683;12377.51469823627;12489.250644961332;12726.300053497147;21186.15255389798;21973.67039494377;22576.548469030855;22589.887050564972;22729.659055114185;9217.95391023054;9465.66870159541;9618.086281803218;9672.903270967341;9816.312858100007;5099.36257967754;5116.948830511647;5211.218317252443;5230.973845130826;5467.972660136674;4024.1750441159493;4149.502059752788;4188.790560471964;4189.958100419018;4191.811368488365;699.7061234281614;700.7547358424613;703.2896774258036;711.1679335177854;722.0757430488983;631.9873602528015;644.5649186798892;649.2890285137767;687.4225650453643;695.6869408765974;9391.483468409166;9830.655927042519;10195.643152489627;10680.732981675521;10968.561257549609;745.0909889934271;750.2160242546552;761.6877080397121;791.5092642561649;803.7709252862905;4973.5523802857915;5024.196128619404;5041.445441001487;5057.494250574987;5146.87132821675;76701;96441;134565;137002;139365.3031734835;110699;116458;117334.41332793453;117400;120651.39674301572;56060.71969640207;91195.5440222808;93980.53009734908;94335;102825.48587257016;9845.45849978258;9952.850707239253;10193.490325483845;10720.839187412352;10951.726206844893;41069.38395924004;46289;49684.503154967984;55116;56102.158467623834;52062.73968630133;52912.47087529075;62944.055839161534;79865.80201296869;82502;13569.185848848705;13717.382717777924;14001.92999035039;14026;14234.430622774973;14615.269236538343;14877.925610372311;15142.167180805161;15344.923275383551;15715.135667538396;14452.638684032983;14834.703305934037;14840.703185936436;15355.462558810388;16180.271887764844;13999.58001259923;14167.64580885486;14209.928950355183;14445.277736113363;14722.631934201303;13911.791323130363;13971.58085257444;14047.578572642837;14439.639009024859;14465.27673616294;10907.727306817393;11006.174536909604;11016.834747478633;11364.772704545698;11982.580609678967;10555.366677999345;11103.333799972028;11299.548018079186;11314.83027754568;11341.26281791681 19:19:42 INFO - PROCESS | 9535 | __end_tp_report 19:19:42 INFO - PROCESS | 9535 | __start_cc_report 19:19:42 INFO - PROCESS | 9535 | _x_x_mozilla_cycle_collect,883 19:19:42 INFO - PROCESS | 9535 | __end_cc_report 19:19:42 INFO - PROCESS | 9535 | __startTimestamp1477534782770__endTimestamp 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | ------- Summary: start ------- 19:19:42 INFO - PROCESS | 9535 | Number of tests: 6 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | [#0] dojo.html Cycles:165 Average:5654.19 Median:5830.24 stddev:651.23 (11.2%) stddev-sans-first:623.20 19:19:42 INFO - PROCESS | 9535 | Values: 3162.4 3180.8 3219.1 3245.9 3267.6 5646.8 5684.5 5702.1 5713.7 5745.9 5674.6 5688.1 5724.7 5738.1 5788.4 5130.7 5167.8 5179.8 5190.8 5228.8 5065.3 5262.9 5276.1 5295.0 5310.3 5070.2 5127.9 5154.8 5161.2 5227.6 5237.3 5252.4 5302.3 5321.9 5360.9 5006.5 5163.7 5257.8 5291.0 5314.9 5506.8 5542.5 5552.7 5565.9 5605.0 5991.9 6039.5 6106.9 6121.6 6131.4 6005.1 6112.2 6146.8 6158.3 6177.2 5582.9 5645.4 5657.0 5660.1 5680.5 6223.5 6226.8 6232.8 6233.8 6238.0 5779.8 5964.8 5977.6 5989.6 5990.5 5893.8 6042.2 6118.6 6124.9 6180.9 6165.2 6168.6 6171.4 6172.4 6193.2 6124.5 6141.1 6171.2 6183.3 6187.7 4576.5 4605.6 4615.8 4640.9 4667.6 4118.2 4122.8 4124.6 4136.6 4149.9 5765.2 5766.4 5771.3 5771.4 5775.5 6000.4 6049.6 6052.9 6080.6 6112.7 5792.9 5798.6 5803.9 5830.2 5847.0 5314.1 5317.5 5322.8 5325.4 5338.2 6070.8 6131.7 6195.8 6295.1 6349.0 6127.7 6218.2 6225.2 6240.1 6252.6 6355.9 6372.6 6389.0 6392.1 6398.2 5823.4 5883.0 5919.9 6068.1 6077.2 5987.8 6037.8 6041.4 6071.4 6093.1 5836.7 5904.4 5906.3 5913.8 5936.7 5837.0 5878.9 5897.6 5900.8 5903.4 5789.5 5822.8 5843.1 5906.1 5948.2 5959.3 5959.8 5969.1 5973.9 5974.4 5720.7 5724.9 5768.5 5807.7 5818.9 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | [#1] ext.html Cycles:180 Average:62180.68 Median:197283.94 stddev:120352.93 (61.0%) stddev-sans-first:120601.70 19:19:42 INFO - PROCESS | 9535 | Values: 588.5 616.6 634.7 640.9 670.3 47349.5 49040.3 49053.0 49062.5 53287.5 50525.5 50834.0 51080.5 51115.7 51128.2 8414.4 8612.8 9447.4 9698.5 9701.4 11057.7 11339.1 11356.5 11761.8 11773.0 11254.7 11264.4 11292.4 11307.2 11314.1 11354.3 11377.4 11439.8 11446.1 11458.3 11282.7 11311.2 11337.0 11340.8 11353.1 46529.8 46943.5 46956.8 46972.0 46990.5 3909.9 3919.3 4002.6 4336.7 4545.9 35912.6 36188.8 36291.6 37693.2 37760.8 4407.7 4439.6 4460.8 4469.4 4744.7 376340.0 378743.0 379072.0 379087.1 379165.0 40086.8 41105.0 67644.0 67746.3 68160.3 42609.1 42985.8 43004.0 43018.4 43060.6 15902.5 15908.4 15918.1 16054.9 16443.3 13913.0 14220.1 14748.2 15380.3 15411.6 9771.0 9808.5 9808.6 9852.1 9891.5 3661.7 3663.0 3721.2 3725.5 3742.0 1047.3 1074.8 1086.4 1162.8 1188.7 34791.5 34960.3 35371.1 35398.3 35481.5 1132.7 1166.7 1169.6 1177.3 1235.3 17898.4 18264.5 18546.2 18594.0 18644.3 564871.0 570096.0 570453.0 571654.0 572495.0 65884.3 66328.3 66447.0 66537.7 66555.3 1309.9 1356.9 1400.5 1437.9 1475.9 33396.0 35570.8 35812.6 36321.6 36325.5 56376.7 56395.0 56793.0 56841.4 56842.7 386743.0 389131.0 389598.0 389768.0 390658.0 31547.0 31633.1 32329.8 32764.5 34281.1 53281.0 53617.2 53648.0 53816.7 54227.7 44286.3 44955.8 45278.8 46422.8 46618.0 44609.8 46214.5 46532.5 47006.1 47351.5 44084.3 44665.1 44889.8 45052.5 46219.1 38777.6 40117.2 40251.4 40296.0 40658.6 29528.4 29565.0 29759.1 30091.4 30104.0 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | [#2] jquery.html Cycles:180 Average:29189.88 Median:2469.61 stddev:81322.72 (3292.9%) stddev-sans-first:81522.12 19:19:42 INFO - PROCESS | 9535 | Values: 399.8 441.0 441.8 445.1 447.3 17568.8 19330.3 20869.2 21587.6 21667.5 13635.9 16791.9 19095.0 19716.7 19794.0 5543.5 5723.7 5724.4 5729.2 5741.3 5624.8 5861.4 6146.2 6191.3 6232.9 5967.9 6228.3 6243.4 6257.4 6273.3 6136.2 6226.8 6230.1 6233.3 6239.8 5860.7 6040.2 6071.2 6077.3 6119.3 15220.0 15451.4 15451.4 21242.3 24487.4 2464.6 2474.6 2475.6 2502.0 2543.3 10086.8 10617.2 10634.1 10923.1 11012.8 1953.5 2022.9 2062.5 2072.9 2120.3 75291.6 87312.0 87813.6 90550.0 90822.5 10724.5 11208.7 11298.3 11513.8 11560.5 16059.0 16528.0 16887.2 16929.3 17445.3 8689.1 8725.3 8745.9 8792.3 9408.0 8949.0 8958.1 8972.0 9190.0 9203.6 5125.4 5248.2 5266.8 5276.0 5395.1 2130.3 2168.4 2202.2 2205.5 2216.9 924.5 1002.7 1027.0 1033.6 1085.1 18823.2 19197.6 19726.9 19974.7 20099.4 1023.5 1031.1 1043.8 1043.9 1051.0 10613.8 10976.2 10988.7 11109.9 11137.1 474295.0 478963.0 502072.0 502218.0 502923.0 84582.6 93627.5 96099.0 97618.3 107591.0 506.6 538.5 540.5 541.1 543.4 11795.6 12000.6 12164.3 12376.2 12833.6 29118.4 38499.8 40013.4 40748.6 41270.8 56415.7 57249.0 57677.0 58342.0 58635.7 5817.7 8564.0 8804.7 8929.4 9142.1 12541.4 13858.2 13869.4 13928.4 14109.7 12094.5 12172.3 12229.8 12230.3 12431.8 12213.6 12224.2 12304.4 12528.6 12610.6 12241.6 12306.4 12391.8 12425.5 12445.0 8882.6 9067.8 9101.6 9279.0 9315.1 11559.1 11867.3 11901.9 11923.2 12564.9 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | [#3] mootools.html Cycles:180 Average:6155.65 Median:5019.90 stddev:3504.22 (69.8%) stddev-sans-first:3496.58 19:19:42 INFO - PROCESS | 9535 | Values: 1500.4 1548.2 1644.3 1790.6 1807.9 8794.7 9438.4 9445.5 9445.8 9457.6 8163.4 8408.1 8792.7 9142.2 9143.5 2520.8 2539.6 2540.6 2550.6 2554.2 2481.7 2572.8 2593.6 2594.2 2596.3 2534.0 2545.7 2548.1 2550.6 2558.1 2240.1 2241.7 2268.8 2323.8 2327.1 2501.0 2501.8 2512.2 2516.8 2535.2 9397.5 9452.7 9465.5 9468.0 9469.1 5475.2 5587.1 5599.6 5631.3 5663.9 3528.6 3598.8 3684.7 3703.6 3706.4 1164.6 1221.6 1234.5 1239.3 1239.7 13026.4 13093.3 13102.8 13108.5 13120.7 5079.7 5121.7 5245.7 5265.6 5332.3 10984.3 11017.8 11087.9 11089.3 11109.6 5728.9 5756.3 5756.6 5759.6 5782.5 4649.2 4711.2 5270.6 5328.2 5331.8 4304.8 4364.2 4391.8 4404.7 4429.7 1383.1 1398.4 1402.7 1432.4 1465.8 987.2 998.7 1004.1 1005.5 1014.2 6238.0 6309.2 6344.5 6379.6 6478.1 515.3 520.1 528.4 528.6 531.4 4257.3 4271.5 4376.2 4378.1 4385.1 11157.6 11507.2 11552.9 11585.5 11598.5 10801.1 11290.7 11367.9 11493.4 11824.7 8710.4 8794.6 8813.5 8898.6 8901.2 6093.8 6262.5 6344.2 6405.6 6411.5 10695.7 10868.9 11028.1 11036.6 11140.2 10891.9 10982.7 11112.4 11152.2 11181.3 7434.3 7707.1 7919.4 8219.0 8371.0 8945.0 9117.6 9127.0 9132.6 9163.0 7681.4 7814.5 7837.4 7918.4 8190.2 8014.8 8069.2 8082.6 8163.7 8351.5 7394.5 7446.9 7482.3 7539.9 7543.4 4960.1 5214.3 5216.5 5290.9 5342.6 5511.0 5558.7 5638.4 5679.2 5838.9 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | [#4] prototype.html Cycles:180 Average:4738.62 Median:5172.50 stddev:1336.36 (25.8%) stddev-sans-first:1299.14 19:19:42 INFO - PROCESS | 9535 | Values: 363.8 368.8 369.5 370.5 378.1 5521.6 5528.6 5535.7 5560.2 5601.9 5247.4 5431.0 5474.7 5532.9 5541.4 4326.9 4345.1 4351.0 4352.9 4384.7 4353.7 4357.3 4358.0 4372.4 4405.0 4218.9 4288.3 4300.5 4335.3 4344.8 4276.1 4277.4 4301.2 4301.8 4326.2 3857.0 3857.3 3857.9 3866.1 3876.1 5394.3 5422.8 5463.8 5467.6 5480.3 4845.9 5020.3 5027.0 5040.1 5047.2 5746.0 5747.7 5750.4 5752.7 5757.6 4246.3 4290.2 4313.4 4325.1 4338.7 5697.4 5836.3 5930.3 5947.7 5976.2 4311.4 4437.3 4448.9 4451.3 4463.0 4518.6 4542.6 4555.3 4557.0 4558.6 4692.7 4846.7 5062.9 5093.5 5126.3 5752.5 5794.8 5820.7 5822.3 5897.0 1561.1 1576.0 1581.7 1585.4 1586.2 997.5 1011.4 1021.0 1022.7 1025.5 4946.1 4947.4 4951.0 4980.3 5009.9 5167.0 5169.0 5212.9 5256.2 5267.7 4670.6 4893.8 4966.3 4969.9 4977.1 2785.6 2796.5 2798.4 2800.9 2805.2 5306.9 5318.3 5351.4 5367.6 5401.2 5074.6 5176.0 5267.6 5292.0 5315.1 5306.8 5326.6 5338.3 5351.3 5372.2 4950.8 5200.9 5209.4 5222.7 5233.6 5139.8 5155.5 5160.5 5182.5 5226.9 5324.3 5908.9 5917.4 6019.8 6066.8 4409.1 4441.2 4448.2 4460.7 4475.0 5784.8 6004.5 6026.0 6049.0 6050.5 5782.4 5813.0 5830.5 5830.8 5841.2 5784.7 5796.5 5796.9 5829.9 5862.4 5603.5 5780.9 5817.0 5821.8 5843.4 5772.5 5819.5 5830.3 5831.9 5884.4 5553.2 5699.7 5725.9 5740.9 5747.7 19:19:42 INFO - PROCESS | 9535 | 19:19:42 INFO - PROCESS | 9535 | [#5] yui.html Cycles:180 Average:21791.56 Median:25240.41 stddev:31890.43 (126.3%) stddev-sans-first:31940.95 19:19:42 INFO - PROCESS | 9535 | Values: 802.1 809.4 817.6 822.6 917.5 15295.8 16530.5 16553.5 16556.4 16606.3 16254.5 16460.5 16474.5 16482.3 16495.0 727.8 747.4 843.7 846.5 873.3 871.2 874.2 876.6 876.6 877.8 917.5 918.6 918.8 919.0 919.1 752.7 790.7 863.5 898.0 910.1 676.6 676.7 676.9 677.4 677.4 15964.4 16007.7 16023.4 16029.0 16052.0 14994.3 15183.8 15261.4 15348.1 15728.7 12976.2 13132.1 14201.3 14237.9 14382.1 4633.6 4649.8 4653.2 4661.7 4835.9 83280.0 83808.6 83987.6 84289.0 85040.9 11720.2 12184.3 12377.5 12489.3 12726.3 21186.2 21973.7 22576.5 22589.9 22729.7 9218.0 9465.7 9618.1 9672.9 9816.3 5099.4 5116.9 5211.2 5231.0 5468.0 4024.2 4149.5 4188.8 4190.0 4191.8 699.7 700.8 703.3 711.2 722.1 632.0 644.6 649.3 687.4 695.7 9391.5 9830.7 10195.6 10680.7 10968.6 745.1 750.2 761.7 791.5 803.8 4973.6 5024.2 5041.4 5057.5 5146.9 76701.0 96441.0 134565.0 137002.0 139365.3 110699.0 116458.0 117334.4 117400.0 120651.4 56060.7 91195.5 93980.5 94335.0 102825.5 9845.5 9952.9 10193.5 10720.8 10951.7 41069.4 46289.0 49684.5 55116.0 56102.2 52062.7 52912.5 62944.1 79865.8 82502.0 13569.2 13717.4 14001.9 14026.0 14234.4 14615.3 14877.9 15142.2 15344.9 15715.1 14452.6 14834.7 14840.7 15355.5 16180.3 13999.6 14167.6 14209.9 14445.3 14722.6 13911.8 13971.6 14047.6 14439.6 14465.3 10907.7 11006.2 11016.8 11364.8 11982.6 10555.4 11103.3 11299.5 11314.8 11341.3 19:19:42 INFO - PROCESS | 9535 | -------- Summary: end -------- 19:19:42 INFO - PROCESS | 9535 | 19:19:43 INFO - TEST-INFO | 9535: exit 0 19:19:43 INFO - TEST-OK | dromaeo_css | took 1113892ms 19:19:43 INFO - TEST-START | kraken 19:19:43 INFO - operating with platform_type : linux_ 19:19:43 INFO - Initialising browser for kraken test... 19:19:43 INFO - TEST-INFO | started process 9854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcNKLCP/profile http://localhost:53342/getInfo.html) 19:19:47 INFO - PROCESS | 9854 | __metrics Screen width/height:1600/1200 19:19:47 INFO - PROCESS | 9854 | colorDepth:24 19:19:47 INFO - PROCESS | 9854 | Browser inner width/height: 1024/697 19:19:47 INFO - PROCESS | 9854 | __metrics 19:19:48 INFO - PROCESS | 9854 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:19:48 INFO - PROCESS | 9854 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:19:48 INFO - PROCESS | 9854 | console.error: PushService: 19:19:48 INFO - PROCESS | 9854 | stateChangeProcessEnqueue: Error transitioning state 19:19:48 INFO - PROCESS | 9854 | UnknownError 19:19:49 INFO - TEST-INFO | 9854: exit 0 19:19:49 INFO - Browser initialized. 19:19:49 INFO - Running cycle 1/1 for kraken test... 19:19:49 INFO - TEST-INFO | started process 9944 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcNKLCP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:20:00 INFO - PROCESS | 9944 | RSS: Main: 147816448 19:20:00 INFO - PROCESS | 9944 | 19:20:03 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53342/tests/kraken/driver.html?testName=audio-beat-detection) 19:20:04 INFO - PROCESS | 9944 | RSS: Main: 189915136 19:20:04 INFO - PROCESS | 9944 | 19:20:06 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53342/tests/kraken/driver.html?testName=audio-dft) 19:20:07 INFO - PROCESS | 9944 | RSS: Main: 179286016 19:20:07 INFO - PROCESS | 9944 | 19:20:10 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53342/tests/kraken/driver.html?testName=audio-fft) 19:20:10 INFO - PROCESS | 9944 | RSS: Main: 270303232 19:20:10 INFO - PROCESS | 9944 | 19:20:12 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53342/tests/kraken/driver.html?testName=audio-oscillator) 19:20:13 INFO - PROCESS | 9944 | RSS: Main: 176918528 19:20:13 INFO - PROCESS | 9944 | 19:20:15 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53342/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:20:16 INFO - PROCESS | 9944 | RSS: Main: 178024448 19:20:16 INFO - PROCESS | 9944 | 19:20:19 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53342/tests/kraken/driver.html?testName=imaging-darkroom) 19:20:19 INFO - PROCESS | 9944 | RSS: Main: 214163456 19:20:19 INFO - PROCESS | 9944 | 19:20:23 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53342/tests/kraken/driver.html?testName=imaging-desaturate) 19:20:23 INFO - PROCESS | 9944 | RSS: Main: 213028864 19:20:23 INFO - PROCESS | 9944 | 19:20:26 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53342/tests/kraken/driver.html?testName=json-parse-financial) 19:20:27 INFO - PROCESS | 9944 | RSS: Main: 212852736 19:20:27 INFO - PROCESS | 9944 | 19:20:28 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53342/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:20:29 INFO - PROCESS | 9944 | RSS: Main: 174747648 19:20:29 INFO - PROCESS | 9944 | 19:20:30 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:20:31 INFO - PROCESS | 9944 | RSS: Main: 182374400 19:20:31 INFO - PROCESS | 9944 | 19:20:33 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:20:33 INFO - PROCESS | 9944 | RSS: Main: 188903424 19:20:33 INFO - PROCESS | 9944 | 19:20:36 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:20:37 INFO - PROCESS | 9944 | RSS: Main: 197120000 19:20:37 INFO - PROCESS | 9944 | 19:20:39 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:20:40 INFO - PROCESS | 9944 | RSS: Main: 193847296 19:20:40 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | Cycle 1(1): loaded http://localhost:53342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53342/tests/kraken/driver.html?testName=ai-astar) 19:20:42 INFO - PROCESS | 9944 | RSS: Main: 244531200 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | __start_tp_report 19:20:42 INFO - PROCESS | 9944 | _x_x_mozilla_page_load 19:20:42 INFO - PROCESS | 9944 | _x_x_mozilla_page_load_details 19:20:42 INFO - PROCESS | 9944 | |i|pagename|runs| 19:20:42 INFO - PROCESS | 9944 | |0;ai-astar;107;111;112;109;110;111;109;108;109;108 19:20:42 INFO - PROCESS | 9944 | |1;audio-beat-detection;146;120;116;154;117;119;117;117;162;129 19:20:42 INFO - PROCESS | 9944 | |2;audio-dft;162;160;160;162;161;162;160;161;160;161 19:20:42 INFO - PROCESS | 9944 | |3;audio-fft;74;76;75;113;76;77;76;76;96;75 19:20:42 INFO - PROCESS | 9944 | |4;audio-oscillator;82;81;120;82;81;81;103;81;82;103 19:20:42 INFO - PROCESS | 9944 | |5;imaging-gaussian-blur;117;117;118;117;117;118;117;118;117;117 19:20:42 INFO - PROCESS | 9944 | |6;imaging-darkroom;145;147;146;145;146;147;146;146;145;147 19:20:42 INFO - PROCESS | 9944 | |7;imaging-desaturate;95;95;95;96;94;96;94;95;95;95 19:20:42 INFO - PROCESS | 9944 | |8;json-parse-financial;50;50;50;50;50;49;52;50;51;50 19:20:42 INFO - PROCESS | 9944 | |9;json-stringify-tinderbox;59;59;60;59;60;59;59;59;59;59 19:20:42 INFO - PROCESS | 9944 | |10;stanford-crypto-aes;73;74;74;75;74;72;73;73;74;76 19:20:42 INFO - PROCESS | 9944 | |11;stanford-crypto-ccm;124;123;120;121;120;124;122;119;166;122 19:20:42 INFO - PROCESS | 9944 | |12;stanford-crypto-pbkdf2;155;154;156;155;153;158;156;155;159;156 19:20:42 INFO - PROCESS | 9944 | |13;stanford-crypto-sha256-iterative;63;63;63;64;61;65;67;66;61;60 19:20:42 INFO - PROCESS | 9944 | __end_tp_report 19:20:42 INFO - PROCESS | 9944 | __start_cc_report 19:20:42 INFO - PROCESS | 9944 | _x_x_mozilla_cycle_collect,1612 19:20:42 INFO - PROCESS | 9944 | __end_cc_report 19:20:42 INFO - PROCESS | 9944 | __startTimestamp1477534842281__endTimestamp 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | ------- Summary: start ------- 19:20:42 INFO - PROCESS | 9944 | Number of tests: 14 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#0] ai-astar Cycles:10 Average:109.40 Median:109.50 stddev:1.58 (1.4%) stddev-sans-first:1.41 19:20:42 INFO - PROCESS | 9944 | Values: 107.0 111.0 112.0 109.0 110.0 111.0 109.0 108.0 109.0 108.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:124.50 stddev:17.58 (14.1%) stddev-sans-first:17.62 19:20:42 INFO - PROCESS | 9944 | Values: 146.0 120.0 116.0 154.0 117.0 119.0 117.0 117.0 162.0 129.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.83 19:20:42 INFO - PROCESS | 9944 | Values: 162.0 160.0 160.0 162.0 161.0 162.0 160.0 161.0 160.0 161.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:12.84 (16.9%) stddev-sans-first:13.34 19:20:42 INFO - PROCESS | 9944 | Values: 74.0 76.0 75.0 113.0 76.0 77.0 76.0 76.0 96.0 75.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:81.00 stddev:13.95 (17.2%) stddev-sans-first:14.53 19:20:42 INFO - PROCESS | 9944 | Values: 82.0 81.0 120.0 82.0 81.0 81.0 103.0 81.0 82.0 103.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:20:42 INFO - PROCESS | 9944 | Values: 117.0 117.0 118.0 117.0 117.0 118.0 117.0 118.0 117.0 117.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.78 19:20:42 INFO - PROCESS | 9944 | Values: 145.0 147.0 146.0 145.0 146.0 147.0 146.0 146.0 145.0 147.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 19:20:42 INFO - PROCESS | 9944 | Values: 95.0 95.0 95.0 96.0 94.0 96.0 94.0 95.0 95.0 95.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.00 stddev:0.79 (1.6%) stddev-sans-first:0.83 19:20:42 INFO - PROCESS | 9944 | Values: 50.0 50.0 50.0 50.0 50.0 49.0 52.0 50.0 51.0 50.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 19:20:42 INFO - PROCESS | 9944 | Values: 59.0 59.0 60.0 59.0 60.0 59.0 59.0 59.0 59.0 59.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:74.00 stddev:1.14 (1.5%) stddev-sans-first:1.17 19:20:42 INFO - PROCESS | 9944 | Values: 73.0 74.0 74.0 75.0 74.0 72.0 73.0 73.0 74.0 76.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:122.50 stddev:14.12 (11.5%) stddev-sans-first:14.96 19:20:42 INFO - PROCESS | 9944 | Values: 124.0 123.0 120.0 121.0 120.0 124.0 122.0 119.0 166.0 122.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:156.00 stddev:1.77 (1.1%) stddev-sans-first:1.86 19:20:42 INFO - PROCESS | 9944 | Values: 155.0 154.0 156.0 155.0 153.0 158.0 156.0 155.0 159.0 156.0 19:20:42 INFO - PROCESS | 9944 | 19:20:42 INFO - PROCESS | 9944 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:2.26 (3.6%) stddev-sans-first:2.40 19:20:42 INFO - PROCESS | 9944 | Values: 63.0 63.0 63.0 64.0 61.0 65.0 67.0 66.0 61.0 60.0 19:20:42 INFO - PROCESS | 9944 | -------- Summary: end -------- 19:20:42 INFO - PROCESS | 9944 | 19:20:43 INFO - TEST-INFO | 9944: exit 0 19:20:43 INFO - TEST-OK | kraken | took 59865ms 19:20:43 INFO - SUITE-END | took 1173s 19:20:43 INFO - Completed test suite (00:19:33) 19:20:43 INFO - javascript benchmark 19:20:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3162.3991441626094, 3180.7614428917823, 3219.146926064593, 3245.9188520286975, 3267.607887053554, 5646.830595082149, 5684.54523638109, 5702.116171993337, 5713.71431428428, 5745.856353591157, 5674.631148975313, 5688.146777983305, 5724.685142317171, 5738.139279108136, 5788.42115788421, 5130.71781052042, 5167.844964651066, 5179.766910489018, 5190.766415511311, 5228.79084836606, 5065.265536497206, 5262.92105618416, 5276.076686579852, 5295.0468915595175, 5310.283111779918, 5070.2141168119, 5127.897442051175, 5154.819581314655, 5161.225816127592, 5227.63406561539, 5237.266782650432, 5252.395974462947, 5302.257683924254, 5321.866953326198, 5360.9463905360835, 5006.524380183877, 5163.741812909339, 5257.842264732064, 5290.9735451322495, 5314.893702125936, 5506.834794956158, 5542.473465020857, 5552.7223638817895, 5565.9443405565835, 5605.047141985831, 5991.850203744898, 6039.456448919618, 6106.938930610681, 6121.602095863755, 6131.386861313833, 6005.129256257818, 6112.235970503687, 6146.784862529835, 6158.322584515699, 6177.166082578839, 5582.86042848932, 5645.435456454363, 5656.971715141481, 5660.066089095294, 5680.488756011979, 6223.533235007392, 6226.782062627877, 6232.8130156095385, 6233.812985610439, 6237.96881015592, 5779.768809247667, 5964.821055368346, 5977.6114552553945, 5989.580729348991, 5990.520758339322, 5893.823185304448, 6042.244719410074, 6118.632882027093, 6124.938750612526, 6180.8763824723255, 6165.229346331708, 6168.599041062316, 6171.352008039181, 6172.351903050205, 6193.163922870355, 6124.5100391968535, 6141.140240366352, 6171.166892469549, 6183.258009038854, 6187.659678717714, 4576.496585375606, 4605.608523275492, 4615.838445654453, 4640.907181856344, 4667.579917807414, 4118.155778065489, 4122.773247471418, 4124.566920473307, 4136.607022332873, 4149.875503734893, 5765.1640512125505, 5766.4233576642, 5771.278590176228, 5771.365149833515, 5775.48020678141, 6000.429959153874, 6049.637021778707, 6052.939470605325, 6080.635161890301, 6112.694365281718, 5792.913106303409, 5798.565107616945, 5803.912941305884, 5830.242068531149, 5847.0, 5314.1497360422145, 5317.521423071942, 5322.787088516493, 5325.3609566851455, 5338.172583249525, 6070.7875224366935, 6131.662758548323, 6195.783147589813, 6295.087212354183, 6349.0, 6127.693615319127, 6218.22272215973, 6225.221847269091, 6240.095186198067, 6252.562320637694, 6355.87288254223, 6372.617642941438, 6389.041643753289, 6392.0731493934045, 6398.200224971879, 5823.388544202967, 5882.970585147047, 5919.91120133207, 6068.120122582289, 6077.24034495688, 5987.820365389045, 6037.78867739627, 6041.426064523863, 6071.392860713893, 6093.086037094471, 5836.708164591669, 5904.350521442552, 5906.320773111147, 5913.793017244548, 5936.732847021981, 5837.0, 5878.882422351615, 5897.64614123154, 5900.793472228452, 5903.350631430453, 5789.507891829073, 5822.7670893163795, 5843.065109582616, 5906.114082887601, 5948.226730525177, 5959.28488581373, 5959.791407300723, 5969.074793406855, 5973.880522389615, 5974.40255974399, 5720.713964301852, 5724.942750572441, 5768.50967667753, 5807.65154090739, 5818.912716309174], "value": 5609.002829052375, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.535057304729, 616.6454288783949, 634.6541135081383, 640.9455196308312, 670.3028849996005, 47349.526504734946, 49040.26439603404, 49053.0, 49062.50937490624, 53287.46712532874, 50525.494745052536, 50834.0, 51080.48919510813, 51115.74442127794, 51128.23307650388, 8414.36892233082, 8612.827743445127, 9447.385919915201, 9698.466584337859, 9701.417914925127, 11057.668269951873, 11339.149563782708, 11356.48895799687, 11761.764764704702, 11773.0, 11254.662360129209, 11264.4367781611, 11292.378919159442, 11307.208495405324, 11314.094872410229, 11354.261972971814, 11377.431128443586, 11439.828402573927, 11446.084313255004, 11458.312501249951, 11282.661520154408, 11311.151663625262, 11337.0, 11340.829887551694, 11353.091752659768, 46529.76735116337, 46943.53056469426, 46956.7652161737, 46972.0, 46990.53009469896, 3909.885682580479, 3919.2945269851416, 4002.579729128429, 4336.6530677545725, 4545.886352841206, 35912.64087359119, 36188.819055904816, 36291.63708362909, 37693.24613507742, 37760.811195944116, 4407.735535867826, 4439.64482841372, 4460.821567137311, 4469.44131983502, 4744.739039352834, 376340.0, 378743.0, 379072.0, 379087.10456447816, 379165.0, 40086.79956600198, 41105.0, 67644.0, 67746.322536774, 68160.3183968164, 42609.147817044104, 42985.785071074446, 43004.0, 43018.35472467916, 43060.56939430628, 15902.522924312289, 15908.443204487787, 15918.124503152207, 16054.919725401298, 16443.260053297632, 13913.0, 14220.075695079786, 14748.188849613374, 15380.307886144927, 15411.61470963235, 9770.951145244371, 9808.509574521246, 9808.558614862348, 9852.064053914866, 9891.505424728735, 3661.707063434919, 3663.0036630036693, 3721.1999420124803, 3725.4970578971493, 3741.960631455863, 1047.2512153810062, 1074.8441475985937, 1086.4079076903106, 1162.7790719763218, 1188.6731148934111, 34791.4781278281, 34960.30079398398, 35371.11572210664, 35398.292034159174, 35481.46777798335, 1132.6771870017005, 1166.6849950513315, 1169.5964892112208, 1177.2995067934564, 1235.2958813476412, 17898.373556925446, 18264.45206643803, 18546.165422556016, 18594.0, 18644.254229830927, 564871.0, 570096.0, 570453.0, 571654.0, 572495.0, 65884.34115658878, 66328.33671663319, 66447.0, 66537.66731166313, 66555.33444665492, 1309.8821106100497, 1356.9185848849102, 1400.544822932551, 1437.8993470456967, 1475.852414758537, 33396.0, 35570.822145889106, 35812.64187358093, 36321.636783631824, 36325.45511817378, 56376.7181164108, 56395.0, 56793.0, 56841.43158568361, 56842.715786420806, 386743.0, 389131.0, 389598.0, 389768.0, 390658.0, 31547.0, 31633.05100846978, 32329.838350808095, 32764.508532371558, 34281.14297142492, 53281.0, 53617.19574206312, 53648.0, 53816.730916345165, 54227.72886135544, 44286.335704965095, 44955.77522112369, 45278.773606131756, 46422.767886160356, 46618.0, 44609.77695111504, 46214.53785462103, 46532.53467465282, 47006.05987880155, 47351.52648473471, 44084.33873491836, 44665.10669786521, 44889.77555112334, 45052.549474504835, 46219.07561848677, 38777.61222387853, 40117.1976560473, 40251.39622905717, 40296.0, 40658.59341406548, 29528.409431811673, 29565.0, 29759.10722678323, 30091.398172036, 30104.0], "value": 21050.276317540465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.770132174, 440.9713368631039, 441.77469490559815, 445.11200155689403, 447.34687356459557, 17568.824311756878, 19330.323438679632, 20869.165233390686, 21587.568248635016, 21667.45831354217, 13635.863641363583, 16791.91604041978, 19095.0, 19716.704249436236, 19794.0, 5543.473370029841, 5723.685197314146, 5724.398938111501, 5729.169270455782, 5741.253637027181, 5624.775008999655, 5861.3552509223955, 6146.170267013963, 6191.349908259635, 6232.9376706232815, 5967.85080372992, 6228.252609686844, 6243.438090571848, 6257.43683068526, 6273.278572964099, 6136.23297087864, 6226.782062627786, 6230.127782110508, 6233.28317243516, 6239.750409983595, 5860.736266868002, 6040.154378387029, 6071.150038994544, 6077.301110372319, 6119.326874043852, 15220.01069930451, 15451.381944722198, 15451.381944722198, 21242.256521021845, 24487.38781530458, 2464.630305454178, 2474.591692370757, 2475.628655701659, 2502.036715864387, 2543.326018605071, 10086.848697269546, 10617.150627949746, 10634.096101831354, 10923.126149907988, 11012.834807477895, 1953.4823271832975, 2022.888741119238, 2062.5256191075987, 2072.9067191976396, 2120.342693764938, 75291.6235418825, 87312.0, 87813.56093219557, 90550.0, 90822.54588727014, 10724.463776811128, 11208.66374008781, 11298.265612735146, 11513.827292590619, 11560.479778409823, 16059.0, 16528.00831950087, 16887.15564221784, 16929.322827087024, 17445.302187912595, 8689.087645797226, 8725.345599079967, 8745.91254087451, 8792.296616270683, 9408.012158723372, 8948.955255223682, 8958.059403762642, 8971.955140224258, 9190.0, 9203.585838637277, 5125.410577783603, 5248.212768084742, 5266.815661451831, 5276.0, 5395.136778115482, 2130.318298144579, 2168.4253672776726, 2202.152171414017, 2205.5258119504383, 2216.866987980694, 924.523870206844, 1002.6641075239876, 1027.0345874877592, 1033.5762337441613, 1085.1156307609274, 18823.152958116916, 19197.616047678966, 19726.901365493082, 19974.70037949432, 20099.39701808948, 1023.4831410137981, 1031.0823367203195, 1043.8277684182176, 1043.874735031801, 1050.9737256568494, 10613.787724245472, 10976.176786741027, 10988.725281867857, 11109.944450277697, 11137.053350465301, 474295.0, 478963.0, 502072.0, 502218.0, 502923.0, 84582.5770871154, 93627.53186234026, 96099.0, 97618.33343331939, 107591.0, 506.59978616892244, 538.5368583018601, 540.5378401466768, 541.0558575286072, 543.3941155611544, 11795.587154449897, 12000.57997970067, 12164.330961797186, 12376.195547289397, 12833.614991550268, 29118.417631646826, 38499.807500963434, 40013.39979900246, 40748.59251407448, 41270.793646031576, 56415.71792141177, 57249.0, 57677.0, 58342.0, 58635.70682146562, 5817.680027598353, 8563.95718021406, 8804.735857924272, 8929.374943754136, 9142.085791420805, 12541.372931353213, 13858.237796920863, 13869.375878085308, 13928.373223204777, 14109.717805643624, 12094.516219351128, 12172.2696638202, 12229.816552751538, 12230.266184028987, 12431.813522797345, 12213.633590992284, 12224.205426647239, 12304.384780761106, 12528.624141275777, 12610.621681349574, 12241.632751017483, 12306.384680765746, 12391.814122788346, 12425.502979880703, 12445.0, 8882.60028298741, 9067.818643627223, 9101.590428430603, 9279.0, 9315.115064068903, 11559.133065019989, 11867.287962722265, 11901.880981190423, 11923.224990375598, 12564.869161775485], "value": 9273.06725804943, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.4373359990004, 1548.2490991868942, 1644.2518654012426, 1790.5971156489784, 1807.8824876383026, 8794.736157915264, 9438.386504877179, 9445.480498572575, 9445.763855903606, 9457.621695132195, 8163.387745919065, 8408.117147699495, 8792.69225577105, 9142.177204051633, 9143.45139291643, 2520.8109391795597, 2539.606361014046, 2540.5808041673104, 2550.591905295153, 2554.1571281477068, 2481.6525686403916, 2572.8327658702124, 2593.5590949538623, 2594.2347007632698, 2596.3379338268714, 2533.961990570143, 2545.7072436669837, 2548.1463709657287, 2550.5664037113643, 2558.1046633678156, 2240.1375470443913, 2241.652543855697, 2268.8298377621745, 2323.7560056194193, 2327.1156960355124, 2500.962485562718, 2501.8498890066653, 2512.170983575434, 2516.7609077137645, 2535.239428171541, 9397.530123493798, 9452.676625272466, 9465.479398633073, 9468.005859384733, 9469.053094690546, 5475.151351540518, 5587.0502014657195, 5599.60802743804, 5631.267935168402, 5663.886722265532, 3528.558930133733, 3598.8200589970397, 3684.6868016218655, 3703.6296370363016, 3706.425504046877, 1164.5516476156656, 1221.6090850927706, 1234.5432190089634, 1239.2874097394035, 1239.727260002798, 13026.41381137851, 13093.279869607168, 13102.80345794814, 13108.541201057917, 13120.671983200496, 5079.669821461667, 5121.74391280442, 5245.6852588844795, 5265.631405801557, 5332.280142180756, 10984.286021408583, 11017.83473247902, 11087.944560277147, 11089.33463992147, 11109.555617775199, 5728.856778580569, 5756.33802112754, 5756.625819321815, 5759.568032397503, 5782.479576838105, 4649.232876575395, 4711.222648263067, 5270.578353731692, 5328.200769884548, 5331.760070796823, 4304.80628371724, 4364.214441400515, 4391.802368893407, 4404.7357158570585, 4429.734215947054, 1383.1424516799648, 1398.398977901103, 1402.698419839719, 1432.3697573067816, 1465.8387577366482, 987.160913223754, 998.7353351361895, 1004.1113614451264, 1005.5073014223125, 1014.2028009428882, 6238.0, 6309.179806625109, 6344.460720838781, 6379.64911929839, 6478.060681201199, 515.3300709078259, 520.0638850069859, 528.3659608469775, 528.582419888284, 531.4207513809965, 4257.297545904953, 4271.466066741657, 4376.190404775127, 4378.058717375779, 4385.101054283864, 11157.609483668033, 11507.194496385173, 11552.94223528877, 11585.478653460614, 11598.536058557733, 10801.135909127252, 11290.7177320566, 11367.886321136848, 11493.367864767351, 11824.70438239051, 8710.390272680977, 8794.648214071365, 8813.515256660818, 8898.555072246361, 8901.154390332907, 6093.847653808691, 6262.467690246198, 6344.206974128234, 6405.647689377129, 6411.455026322815, 10695.732606684895, 10868.891311086787, 11028.117750580095, 11036.613718519813, 11140.16448766362, 10891.945540272249, 10982.670519884416, 11112.444377781241, 11152.21934464612, 11181.273217240854, 7434.3309102181065, 7707.075150981694, 7919.366450684047, 8219.0, 8371.0, 8945.0, 9117.589708463003, 9126.954365228132, 9132.589033493376, 9163.0, 7681.385489160742, 7814.53112813233, 7837.412194085352, 7918.445708800328, 8190.180981902001, 8014.799630009064, 8069.193080691884, 8082.555459449787, 8163.7142700005215, 8351.540665263468, 7394.482386233127, 7446.92553074484, 7482.289182527651, 7539.886901696588, 7543.43424243173, 4960.131976903956, 5214.348206474191, 5216.530512253916, 5290.920636190537, 5342.6260161788305, 5510.972445137909, 5558.694271814928, 5638.379778224474, 5679.17651940458, 5838.883222335615], "value": 4862.628862410497, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.8035460851141, 368.7510930122167, 369.4532092503096, 370.54052974311855, 378.12086897962246, 5521.585881058922, 5528.640638358504, 5535.667859928402, 5560.193771903072, 5601.887962240763, 5247.396549396811, 5431.022415965124, 5474.698891560963, 5532.889342213153, 5541.418151094137, 4326.891827704301, 4345.065810850667, 4350.956490435086, 4352.934705979413, 4384.671149663788, 4353.651707863363, 4357.346398040303, 4358.0, 4372.3822185791305, 4404.955950440487, 4218.873433796991, 4288.270993931023, 4300.462442194726, 4335.333769731608, 4344.761038142901, 4276.123394704078, 4277.401163837066, 4301.225779359714, 4301.78491075445, 4326.156399502099, 3856.980715096435, 3857.3442514772555, 3857.9035524112123, 3866.1107945172766, 3876.069743261597, 5394.32570928634, 5422.75597598109, 5463.7814487420455, 5467.58993075517, 5480.342358916916, 4845.933894543195, 5020.297158397827, 5026.9946010797985, 5040.143175660147, 5047.192449208109, 5745.971270143665, 5747.735498190434, 5750.412314458468, 5752.714227900199, 5757.568182386338, 4246.299360605496, 4290.163418133465, 4313.374560688714, 4325.13497300538, 4338.674599405026, 5697.430256974311, 5836.270466191683, 5930.339504938581, 5947.702614869239, 5976.223090998143, 4311.353297005474, 4437.29003359461, 4448.888777780581, 4451.308502768992, 4462.977685111554, 4518.615917647036, 4542.591166795004, 4555.316702494586, 4557.043020965568, 4558.609624064626, 4692.718436893797, 4846.660733748674, 5062.898742025139, 5093.541581257705, 5126.282320475137, 5752.482276595127, 5794.768209271666, 5820.679862607512, 5822.272215973004, 5897.0, 1561.1023661394743, 1575.9527214183593, 1581.7469204927156, 1585.445094217015, 1586.2386054693811, 997.546116516983, 1011.3729487717662, 1020.9693709188737, 1022.7340891368298, 1025.4718819807806, 4946.071032297407, 4947.356843610308, 4951.0, 4980.302757613937, 5009.874753131201, 5166.992436474927, 5169.0357664087405, 5212.931349073431, 5256.185291279858, 5267.707682698314, 4670.556297151765, 4893.804247830047, 4966.255061740768, 4969.850904472799, 4977.113274300897, 2785.56823692328, 2796.5245908195657, 2798.4263226038615, 2800.8739606717745, 2805.228562145426, 5306.92039619406, 5318.25544423781, 5351.357837059578, 5367.624266301322, 5401.243825864382, 5074.5686616637995, 5175.9641044038735, 5267.55142335853, 5292.015494189679, 5315.149576067811, 5306.814261500829, 5326.573874090063, 5338.332708411449, 5351.331083614548, 5372.247885296062, 4950.801967921387, 5200.921986170286, 5209.4009188943755, 5222.709692893604, 5233.555147812479, 5139.7664560505955, 5155.458676838878, 5160.48395160481, 5182.481751824938, 5226.8954620908125, 5324.254604355316, 5908.911366329595, 5917.408259174048, 6019.789307374221, 6066.757329706938, 4409.140217657526, 4441.244988351923, 4448.199324121689, 4460.710053846489, 4474.955250447454, 5784.768609255751, 6004.459598636144, 6026.0, 6048.969755151196, 6050.45545900871, 5782.421757824352, 5812.970935145467, 5830.5044071254415, 5830.7959221427045, 5841.153032810304, 5784.7107644618445, 5796.478316951326, 5796.855078623068, 5829.941700583109, 5862.413758624103, 5603.495685388335, 5780.94219057804, 5816.970915145397, 5821.76712931478, 5843.415658434123, 5772.509336706259, 5819.5053420459735, 5830.271216097988, 5831.891940531285, 5884.35272120075, 5553.194786755978, 5699.660579763835, 5725.883452726678, 5740.913886291625, 5747.655140691572], "value": 4326.880842468105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.113664400837, 809.3929552835374, 817.619806789843, 822.6010384963296, 917.5183028909821, 15295.770563441543, 16530.504084877444, 16553.503394898144, 16556.420525281632, 16606.335746570137, 16254.51236462908, 16460.506184814476, 16474.505764827074, 16482.34070637176, 16495.0, 727.7707522130512, 747.4058123791572, 843.6709683223547, 846.4709556527171, 873.2795443758893, 871.2289623683055, 874.204473928725, 876.5967654878764, 876.6318146378504, 877.8024944387525, 917.4816228830723, 918.6371383303591, 918.8024574716435, 919.0304229038362, 919.0809190809191, 752.6650640464995, 790.7192946503994, 863.5380071661631, 898.0, 910.0990019880337, 676.5940435738567, 676.6988690032937, 676.8781619308522, 677.40388458157, 677.4072686399401, 15964.361425542964, 16007.679846403124, 16023.35906563736, 16028.95811772231, 16052.0, 14994.325255363536, 15183.772243416357, 15261.38954441821, 15348.07911525312, 15728.685426291524, 12976.221426714334, 13132.0807543472, 14201.289935503184, 14237.85762142386, 14382.137071775622, 4633.559811817906, 4649.814007439699, 4653.208954477713, 4661.696989695659, 4835.903281934342, 83280.0, 83808.58095709543, 83987.5800620999, 84289.0, 85040.87397815043, 11720.191406442667, 12184.329861857683, 12377.51469823627, 12489.250644961332, 12726.300053497147, 21186.15255389798, 21973.67039494377, 22576.548469030855, 22589.887050564972, 22729.659055114185, 9217.95391023054, 9465.66870159541, 9618.086281803218, 9672.903270967341, 9816.312858100007, 5099.36257967754, 5116.948830511647, 5211.218317252443, 5230.973845130826, 5467.972660136674, 4024.1750441159493, 4149.502059752788, 4188.790560471964, 4189.958100419018, 4191.811368488365, 699.7061234281614, 700.7547358424613, 703.2896774258036, 711.1679335177854, 722.0757430488983, 631.9873602528015, 644.5649186798892, 649.2890285137767, 687.4225650453643, 695.6869408765974, 9391.483468409166, 9830.655927042519, 10195.643152489627, 10680.732981675521, 10968.561257549609, 745.0909889934271, 750.2160242546552, 761.6877080397121, 791.5092642561649, 803.7709252862905, 4973.5523802857915, 5024.196128619404, 5041.445441001487, 5057.494250574987, 5146.87132821675, 76701.0, 96441.0, 134565.0, 137002.0, 139365.3031734835, 110699.0, 116458.0, 117334.41332793453, 117400.0, 120651.39674301572, 56060.71969640207, 91195.5440222808, 93980.53009734908, 94335.0, 102825.48587257016, 9845.45849978258, 9952.850707239253, 10193.490325483845, 10720.839187412352, 10951.726206844893, 41069.38395924004, 46289.0, 49684.503154967984, 55116.0, 56102.158467623834, 52062.73968630133, 52912.47087529075, 62944.055839161534, 79865.80201296869, 82502.0, 13569.185848848705, 13717.382717777924, 14001.92999035039, 14026.0, 14234.430622774973, 14615.269236538343, 14877.925610372311, 15142.167180805161, 15344.923275383551, 15715.135667538396, 14452.638684032983, 14834.703305934037, 14840.703185936436, 15355.462558810388, 16180.271887764844, 13999.58001259923, 14167.64580885486, 14209.928950355183, 14445.277736113363, 14722.631934201303, 13911.791323130363, 13971.58085257444, 14047.578572642837, 14439.639009024859, 14465.27673616294, 10907.727306817393, 11006.174536909604, 11016.834747478633, 11364.772704545698, 11982.580609678967, 10555.366677999345, 11103.333799972028, 11299.548018079186, 11314.83027754568, 11341.26281791681], "value": 8037.086255772465, "unit": "score"}], "name": "dromaeo_css", "value": 7549.632903547696, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 112.0, 109.0, 110.0, 111.0, 109.0, 108.0, 109.0, 108.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 116.0, 154.0, 117.0, 119.0, 117.0, 117.0, 162.0, 129.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 162.0, 161.0, 162.0, 160.0, 161.0, 160.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 113.0, 76.0, 77.0, 76.0, 76.0, 96.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 120.0, 82.0, 81.0, 81.0, 103.0, 81.0, 82.0, 103.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 94.0, 96.0, 94.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 50.0, 50.0, 49.0, 52.0, 50.0, 51.0, 50.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 75.0, 74.0, 72.0, 73.0, 73.0, 74.0, 76.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 120.0, 121.0, 120.0, 124.0, 122.0, 119.0, 166.0, 122.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 156.0, 155.0, 153.0, 158.0, 156.0, 155.0, 159.0, 156.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 64.0, 61.0, 65.0, 67.0, 66.0, 61.0, 60.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1457.6, "alertThreshold": 2.0}]} 19:20:43 INFO - Return code: 0 19:20:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:20:43 INFO - # TBPL SUCCESS # 19:20:43 INFO - Running post-action listener: _resource_record_post_action 19:20:43 INFO - [mozharness: 2016-10-27 02:20:43.609503Z] Finished run-tests step (success) 19:20:43 INFO - Running post-run listener: _resource_record_post_run 19:20:43 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 369258496; Read time: 0; Write time: 302060 19:20:43 INFO - TinderboxPrint: CPU usage
13.0% 19:20:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:20:43 INFO - TinderboxPrint: I/O write bytes / time
369,258,496 / 302,060 19:20:43 INFO - TinderboxPrint: CPU idle
8,257.8 (87.0%) 19:20:43 INFO - TinderboxPrint: CPU user
1,192.4 (12.6%) 19:20:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:20:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118722560; Read time: 0; Write time: 101696 19:20:43 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 250535936; Read time: 0; Write time: 200364 19:20:44 INFO - Running post-run listener: _upload_blobber_files 19:20:44 INFO - Blob upload gear active. 19:20:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:20:44 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 19:20:44 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:20:44 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:20:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:20:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:20:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:48 INFO - (blobuploader) - INFO - Done attempting. 19:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:20:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:48 INFO - (blobuploader) - INFO - Done attempting. 19:20:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:20:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:20:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:49 INFO - (blobuploader) - INFO - Done attempting. 19:20:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:49 INFO - Return code: 0 19:20:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebb97b4748ba225f5fc8e55c616c6cfbe8be382eeeb9815dcae5a3d0812c2607aa2b7a8c20629c0bd3cb1797109151e4caf141a558a85d16f7c6111a502d4a97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b4fde3b560a01f0ede06a9f00c61d6fcc333a286047a4fe832d25efa996c2e1c921b889c73d10d0730c027f69762c65e832dd1135138e6f4095a027bcba7256a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7701737a70b7d77efcf023d5e82b1ff4d429693ff683d18b1a8ca5d7af4e6aeef85424fc17abb68ee44a0e2f868dbe6883b3ad789c3f861be8b575dc1d7fb0ef"} 19:20:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:49 INFO - Contents: 19:20:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebb97b4748ba225f5fc8e55c616c6cfbe8be382eeeb9815dcae5a3d0812c2607aa2b7a8c20629c0bd3cb1797109151e4caf141a558a85d16f7c6111a502d4a97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b4fde3b560a01f0ede06a9f00c61d6fcc333a286047a4fe832d25efa996c2e1c921b889c73d10d0730c027f69762c65e832dd1135138e6f4095a027bcba7256a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7701737a70b7d77efcf023d5e82b1ff4d429693ff683d18b1a8ca5d7af4e6aeef85424fc17abb68ee44a0e2f868dbe6883b3ad789c3f861be8b575dc1d7fb0ef"} 19:20:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:49 INFO - Copying logs to upload dir... 19:20:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.683256 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-10-26 19:20:49.032552) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 19:20:49.035921) ========= 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=52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebb97b4748ba225f5fc8e55c616c6cfbe8be382eeeb9815dcae5a3d0812c2607aa2b7a8c20629c0bd3cb1797109151e4caf141a558a85d16f7c6111a502d4a97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b4fde3b560a01f0ede06a9f00c61d6fcc333a286047a4fe832d25efa996c2e1c921b889c73d10d0730c027f69762c65e832dd1135138e6f4095a027bcba7256a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7701737a70b7d77efcf023d5e82b1ff4d429693ff683d18b1a8ca5d7af4e6aeef85424fc17abb68ee44a0e2f868dbe6883b3ad789c3f861be8b575dc1d7fb0ef"} build_url:https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/a1rThwkLSPularONyIkiNQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ebb97b4748ba225f5fc8e55c616c6cfbe8be382eeeb9815dcae5a3d0812c2607aa2b7a8c20629c0bd3cb1797109151e4caf141a558a85d16f7c6111a502d4a97", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b4fde3b560a01f0ede06a9f00c61d6fcc333a286047a4fe832d25efa996c2e1c921b889c73d10d0730c027f69762c65e832dd1135138e6f4095a027bcba7256a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7701737a70b7d77efcf023d5e82b1ff4d429693ff683d18b1a8ca5d7af4e6aeef85424fc17abb68ee44a0e2f868dbe6883b3ad789c3f861be8b575dc1d7fb0ef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 19:20:49.066485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 19:20:49.067256) ========= 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=52faae308a556c440401f8e5000001e8-1477531973.944944-2048281390 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 19:20:49.150222) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 19:20:49.150932) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 19:20:49.151658) ========= ========= Total master_lag: 0.17 =========