builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-053 starttime: 1483953717.5 results: success (0) buildid: 20170108234508 builduid: 3dde137a6d7e4844ab13acc3af2e39be revision: 6dc2ca24bd0bcb88992ae72e4fb8285ea8e24333 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.504554) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.505131) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.505555) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.520945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.521284) ========= 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=920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-09 01:21:58-- 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% 79.1M=0s 2017-01-09 01:21:58 (79.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122787 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.657740) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.658048) ========= 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=920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018032 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.708079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:57.708431) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6dc2ca24bd0bcb88992ae72e4fb8285ea8e24333 --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-beta --rev 6dc2ca24bd0bcb88992ae72e4fb8285ea8e24333 --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=920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-09 01:21:58,442 truncating revision to first 12 chars 2017-01-09 01:21:58,442 Setting DEBUG logging. 2017-01-09 01:21:58,442 attempt 1/10 2017-01-09 01:21:58,442 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6dc2ca24bd0b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 01:21:58,737 unpacking tar archive at: mozilla-beta-6dc2ca24bd0b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.723438 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:58.538600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:58.539025) ========= script_repo_revision: 6dc2ca24bd0bcb88992ae72e4fb8285ea8e24333 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:58.539411) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:58.539668) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 01:21:58.565343) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-01-09 01:21:58.565625) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:21:59 INFO - MultiFileLogger online at 20170109 01:21:59 in /builds/slave/test 01:21:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:21:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:21:59 INFO - {'append_to_log': False, 01:21:59 INFO - 'base_work_dir': '/builds/slave/test', 01:21:59 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:21:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:21:59 INFO - 'branch': 'Mozilla-Beta', 01:21:59 INFO - 'buildbot_json_path': 'buildprops.json', 01:21:59 INFO - 'config_files': ('talos/linux_config.py',), 01:21:59 INFO - 'default_actions': ('clobber', 01:21:59 INFO - 'read-buildbot-config', 01:21:59 INFO - 'download-and-extract', 01:21:59 INFO - 'populate-webroot', 01:21:59 INFO - 'create-virtualenv', 01:21:59 INFO - 'install', 01:21:59 INFO - 'run-tests'), 01:21:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:21:59 INFO - 'download_minidump_stackwalk': True, 01:21:59 INFO - 'download_symbols': 'ondemand', 01:21:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:21:59 INFO - 'tooltool.py': '/tools/tooltool.py', 01:21:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:21:59 INFO - '/tools/misc-python/virtualenv.py')}, 01:21:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:21:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:21:59 INFO - 'installer_path': 'installer.exe', 01:21:59 INFO - 'log_level': 'info', 01:21:59 INFO - 'log_name': 'talos', 01:21:59 INFO - 'log_to_console': True, 01:21:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:21:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:21:59 INFO - 'opt_config_files': (), 01:21:59 INFO - 'pip_index': False, 01:21:59 INFO - 'sps_profile': False, 01:21:59 INFO - 'sps_profile_interval': 0, 01:21:59 INFO - 'suite': 'dromaeojs', 01:21:59 INFO - 'system_bits': '32', 01:21:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:21:59 INFO - 'title': 'talos-linux64-ix-053', 01:21:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:21:59 INFO - 'use_talos_json': True, 01:21:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:21:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:21:59 INFO - 'work_dir': 'build'} 01:21:59 INFO - [mozharness: 2017-01-09 09:21:59.394657Z] Running clobber step. 01:21:59 INFO - Running pre-action listener: _resource_record_pre_action 01:21:59 INFO - Running main action method: clobber 01:21:59 INFO - rmtree: /builds/slave/test/build 01:21:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:22:00 INFO - Running post-action listener: _resource_record_post_action 01:22:00 INFO - [mozharness: 2017-01-09 09:22:00.369938Z] Finished clobber step (success) 01:22:00 INFO - [mozharness: 2017-01-09 09:22:00.370037Z] Running read-buildbot-config step. 01:22:00 INFO - Running pre-action listener: _resource_record_pre_action 01:22:00 INFO - Running main action method: read_buildbot_config 01:22:00 INFO - Using buildbot properties: 01:22:00 INFO - { 01:22:00 INFO - "project": "", 01:22:00 INFO - "product": "firefox", 01:22:00 INFO - "script_repo_revision": "production", 01:22:00 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 01:22:00 INFO - "repository": "", 01:22:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 01:22:00 INFO - "buildid": "20170108234508", 01:22:00 INFO - "pgo_build": "True", 01:22:00 INFO - "basedir": "/builds/slave/test", 01:22:00 INFO - "buildnumber": 82, 01:22:00 INFO - "slavename": "talos-linux64-ix-053", 01:22:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:22:00 INFO - "platform": "ubuntu64_hw", 01:22:00 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 01:22:00 INFO - "branch": "mozilla-beta", 01:22:00 INFO - "repo_path": "releases/mozilla-beta", 01:22:00 INFO - "revision": "6dc2ca24bd0bcb88992ae72e4fb8285ea8e24333", 01:22:00 INFO - "stage_platform": "linux64-pgo", 01:22:00 INFO - "builduid": "3dde137a6d7e4844ab13acc3af2e39be", 01:22:00 INFO - "slavebuilddir": "test" 01:22:00 INFO - } 01:22:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 01:22:00 INFO - Running post-action listener: _resource_record_post_action 01:22:00 INFO - [mozharness: 2017-01-09 09:22:00.372471Z] Finished read-buildbot-config step (success) 01:22:00 INFO - [mozharness: 2017-01-09 09:22:00.372561Z] Running download-and-extract step. 01:22:00 INFO - Running pre-action listener: _resource_record_pre_action 01:22:00 INFO - Running main action method: download_and_extract 01:22:00 INFO - mkdir: /builds/slave/test/build/tests 01:22:00 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')]} 01:22:00 INFO - trying https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 01:22:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 01:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:22:00 INFO - Downloaded 1327 bytes. 01:22:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 01:22:00 INFO - Using the following test package requirements: 01:22:00 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 01:22:00 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 01:22:00 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 01:22:00 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'jsshell-linux-x86_64.zip'], 01:22:00 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 01:22:00 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 01:22:00 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 01:22:00 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 01:22:00 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 01:22:00 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 01:22:00 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 01:22:00 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:22:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 01:22:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:22:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 01:22:01 INFO - Expected file size: 21969736 01:22:05 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:22:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 01:22:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:22:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 01:22:06 INFO - Expected file size: 21969736 01:22:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 01:22:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:22:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 01:22:11 INFO - Expected file size: 14414089 01:22:12 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')]} 01:22:12 INFO - trying https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 01:22:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 01:22:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:22:14 INFO - Downloaded 56993937 bytes. 01:22:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 01:22:14 INFO - mkdir: /builds/slave/test/properties 01:22:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:22:14 INFO - Writing to file /builds/slave/test/properties/build_url 01:22:14 INFO - Contents: 01:22:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 01:22:15 INFO - Running post-action listener: _resource_record_post_action 01:22:15 INFO - Running post-action listener: set_extra_try_arguments 01:22:15 INFO - [mozharness: 2017-01-09 09:22:15.020181Z] Finished download-and-extract step (success) 01:22:15 INFO - [mozharness: 2017-01-09 09:22:15.020454Z] Running populate-webroot step. 01:22:15 INFO - Running pre-action listener: _resource_record_pre_action 01:22:15 INFO - Running main action method: populate_webroot 01:22:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:22:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:22:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:22:15 INFO - '--tppagecycles', 01:22:15 INFO - '7'], 01:22:15 INFO - 'tests': ['tsvgm']}}, 01:22:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:22:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:22:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:22:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:22:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:22:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:22:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['damp', 'tps']}, 01:22:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'tests': ['damp', 'tps']}, 01:22:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['dromaeo_dom']}, 01:22:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:22:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['basic_compositor_video']}, 01:22:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:22:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['a11yr', 01:22:15 INFO - 'ts_paint', 01:22:15 INFO - 'tpaint', 01:22:15 INFO - 'sessionrestore', 01:22:15 INFO - 'sessionrestore_no_auto_restore', 01:22:15 INFO - 'tabpaint']}, 01:22:15 INFO - 'other-e10s': {'tests': ['a11yr', 01:22:15 INFO - 'ts_paint', 01:22:15 INFO - 'tpaint', 01:22:15 INFO - 'sessionrestore', 01:22:15 INFO - 'sessionrestore_no_auto_restore', 01:22:15 INFO - 'tabpaint']}, 01:22:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:22:15 INFO - 'ts_paint', 01:22:15 INFO - 'tpaint', 01:22:15 INFO - 'sessionrestore', 01:22:15 INFO - 'sessionrestore_no_auto_restore', 01:22:15 INFO - 'tabpaint']}, 01:22:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:22:15 INFO - 'ts_paint', 01:22:15 INFO - 'tpaint', 01:22:15 INFO - 'sessionrestore', 01:22:15 INFO - 'sessionrestore_no_auto_restore', 01:22:15 INFO - 'tabpaint']}, 01:22:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['a11yr', 01:22:15 INFO - 'ts_paint', 01:22:15 INFO - 'tpaint', 01:22:15 INFO - 'sessionrestore', 01:22:15 INFO - 'sessionrestore_no_auto_restore', 01:22:15 INFO - 'tabpaint']}, 01:22:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['a11yr', 01:22:15 INFO - 'ts_paint', 01:22:15 INFO - 'tpaint', 01:22:15 INFO - 'sessionrestore', 01:22:15 INFO - 'sessionrestore_no_auto_restore', 01:22:15 INFO - 'tabpaint']}, 01:22:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['tsvgx', 01:22:15 INFO - 'tsvgr_opacity', 01:22:15 INFO - 'tart', 01:22:15 INFO - 'tscrollx', 01:22:15 INFO - 'cart']}, 01:22:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:22:15 INFO - 'tsvgr_opacity', 01:22:15 INFO - 'tart', 01:22:15 INFO - 'tscrollx', 01:22:15 INFO - 'cart']}, 01:22:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'talos_options': ['--disable-e10s'], 01:22:15 INFO - 'tests': ['tp5o']}, 01:22:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'tests': ['tp5o']}, 01:22:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'talos_options': ['--disable-e10s', 01:22:15 INFO - '--xperf_path', 01:22:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:22:15 INFO - 'tests': ['tp5n']}, 01:22:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:22:15 INFO - 'talos_options': ['--xperf_path', 01:22:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:22:15 INFO - 'tests': ['tp5n']}}, 01:22:15 INFO - 'talos.zip': {'path': '', 01:22:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:22:15 INFO - Running post-action listener: _resource_record_post_action 01:22:15 INFO - [mozharness: 2017-01-09 09:22:15.036888Z] Finished populate-webroot step (success) 01:22:15 INFO - [mozharness: 2017-01-09 09:22:15.036982Z] Running create-virtualenv step. 01:22:15 INFO - Running pre-action listener: _resource_record_pre_action 01:22:15 INFO - Running main action method: create_virtualenv 01:22:15 INFO - Creating virtualenv /builds/slave/test/build/venv 01:22:15 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 01:22:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:22:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:22:15 INFO - Using real prefix '/usr' 01:22:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:22:15 INFO - Installing distribute.............................................................................................................................................................................................done. 01:22:17 INFO - Installing pip.................done. 01:22:17 INFO - Return code: 0 01:22:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:22: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')]} 01:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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')]} 01:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:17 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:17 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 01:22:17 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 01:22:17 INFO - Using env: {'DISPLAY': ':0', 01:22:17 INFO - 'HOME': '/home/cltbld', 01:22:17 INFO - 'LANG': 'en_US.UTF-8', 01:22:17 INFO - 'LANGUAGE': 'en_US:en', 01:22:17 INFO - 'LOGNAME': 'cltbld', 01:22:17 INFO - 'MAIL': '/var/mail/cltbld', 01:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:17 INFO - 'MOZ_NO_REMOTE': '1', 01:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:17 INFO - 'NO_EM_RESTART': '1', 01:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:17 INFO - 'PWD': '/builds/slave/test', 01:22:17 INFO - 'SHELL': '/bin/bash', 01:22:17 INFO - 'SHLVL': '1', 01:22:17 INFO - 'TERM': 'linux', 01:22:17 INFO - 'TMOUT': '86400', 01:22:17 INFO - 'USER': 'cltbld', 01:22:17 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:17 INFO - '_': '/tools/buildbot/bin/python'} 01:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:17 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 01:22:17 INFO - Cleaning up... 01:22:17 INFO - Return code: 0 01:22:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:22: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')]} 01:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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')]} 01:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:17 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:17 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 01:22:17 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 01:22:17 INFO - Using env: {'DISPLAY': ':0', 01:22:17 INFO - 'HOME': '/home/cltbld', 01:22:17 INFO - 'LANG': 'en_US.UTF-8', 01:22:17 INFO - 'LANGUAGE': 'en_US:en', 01:22:17 INFO - 'LOGNAME': 'cltbld', 01:22:17 INFO - 'MAIL': '/var/mail/cltbld', 01:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:17 INFO - 'MOZ_NO_REMOTE': '1', 01:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:17 INFO - 'NO_EM_RESTART': '1', 01:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:17 INFO - 'PWD': '/builds/slave/test', 01:22:17 INFO - 'SHELL': '/bin/bash', 01:22:17 INFO - 'SHLVL': '1', 01:22:17 INFO - 'TERM': 'linux', 01:22:17 INFO - 'TMOUT': '86400', 01:22:17 INFO - 'USER': 'cltbld', 01:22:17 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:17 INFO - '_': '/tools/buildbot/bin/python'} 01:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:18 INFO - Downloading/unpacking psutil>=3.1.1 01:22:18 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 01:22:18 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 01:22:18 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 01:22:18 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 01:22:18 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 01:22:18 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 01:22:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:22:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:22:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:22:20 INFO - Installing collected packages: psutil 01:22:20 INFO - Running setup.py install for psutil 01:22:20 INFO - building 'psutil._psutil_linux' extension 01:22:20 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 01:22:20 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 01:22:20 INFO - building 'psutil._psutil_posix' extension 01:22:20 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 01:22:20 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 01:22:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:22:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:22:20 INFO - Successfully installed psutil 01:22:20 INFO - Cleaning up... 01:22:20 INFO - Return code: 0 01:22:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:22:20 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')]} 01:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:20 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')]} 01:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:20 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:20 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 01:22:20 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 01:22:20 INFO - Using env: {'DISPLAY': ':0', 01:22:20 INFO - 'HOME': '/home/cltbld', 01:22:20 INFO - 'LANG': 'en_US.UTF-8', 01:22:20 INFO - 'LANGUAGE': 'en_US:en', 01:22:20 INFO - 'LOGNAME': 'cltbld', 01:22:20 INFO - 'MAIL': '/var/mail/cltbld', 01:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:20 INFO - 'MOZ_NO_REMOTE': '1', 01:22:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:20 INFO - 'NO_EM_RESTART': '1', 01:22:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:20 INFO - 'PWD': '/builds/slave/test', 01:22:20 INFO - 'SHELL': '/bin/bash', 01:22:20 INFO - 'SHLVL': '1', 01:22:20 INFO - 'TERM': 'linux', 01:22:20 INFO - 'TMOUT': '86400', 01:22:20 INFO - 'USER': 'cltbld', 01:22:20 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:20 INFO - '_': '/tools/buildbot/bin/python'} 01:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:22:21 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 01:22:21 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 01:22:21 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 01:22:21 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 01:22:21 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 01:22:21 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 01:22:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:22:23 INFO - Installing collected packages: mozsystemmonitor 01:22:23 INFO - Running setup.py install for mozsystemmonitor 01:22:23 INFO - Successfully installed mozsystemmonitor 01:22:23 INFO - Cleaning up... 01:22:23 INFO - Return code: 0 01:22:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:22:23 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')]} 01:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:23 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')]} 01:22:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:23 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:23 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 01:22:23 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 01:22:23 INFO - Using env: {'DISPLAY': ':0', 01:22:23 INFO - 'HOME': '/home/cltbld', 01:22:23 INFO - 'LANG': 'en_US.UTF-8', 01:22:23 INFO - 'LANGUAGE': 'en_US:en', 01:22:23 INFO - 'LOGNAME': 'cltbld', 01:22:23 INFO - 'MAIL': '/var/mail/cltbld', 01:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:23 INFO - 'MOZ_NO_REMOTE': '1', 01:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:23 INFO - 'NO_EM_RESTART': '1', 01:22:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:23 INFO - 'PWD': '/builds/slave/test', 01:22:23 INFO - 'SHELL': '/bin/bash', 01:22:23 INFO - 'SHLVL': '1', 01:22:23 INFO - 'TERM': 'linux', 01:22:23 INFO - 'TMOUT': '86400', 01:22:23 INFO - 'USER': 'cltbld', 01:22:23 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:23 INFO - '_': '/tools/buildbot/bin/python'} 01:22:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:23 INFO - Downloading/unpacking jsonschema==2.5.1 01:22:23 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 01:22:23 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 01:22:23 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 01:22:23 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 01:22:23 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 01:22:23 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 01:22:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:22:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:22:25 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 01:22:25 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 01:22:25 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 01:22:25 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 01:22:25 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 01:22:25 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 01:22:25 INFO - Downloading functools32-3.2.3-2.tar.gz 01:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:22:25 INFO - warning: no files found matching '*.txt' 01:22:25 INFO - no previously-included directories found matching 'build' 01:22:25 INFO - no previously-included directories found matching 'dist' 01:22:25 INFO - no previously-included directories found matching '.git*' 01:22:25 INFO - Installing collected packages: jsonschema, functools32 01:22:25 INFO - Running setup.py install for functools32 01:22:25 INFO - warning: no files found matching '*.txt' 01:22:25 INFO - no previously-included directories found matching 'build' 01:22:25 INFO - no previously-included directories found matching 'dist' 01:22:25 INFO - no previously-included directories found matching '.git*' 01:22:25 INFO - Successfully installed jsonschema functools32 01:22:25 INFO - Cleaning up... 01:22:26 INFO - Return code: 0 01:22:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:22:26 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')]} 01:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:26 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')]} 01:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:26 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:26 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 01:22:26 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 01:22:26 INFO - Using env: {'DISPLAY': ':0', 01:22:26 INFO - 'HOME': '/home/cltbld', 01:22:26 INFO - 'LANG': 'en_US.UTF-8', 01:22:26 INFO - 'LANGUAGE': 'en_US:en', 01:22:26 INFO - 'LOGNAME': 'cltbld', 01:22:26 INFO - 'MAIL': '/var/mail/cltbld', 01:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:26 INFO - 'MOZ_NO_REMOTE': '1', 01:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:26 INFO - 'NO_EM_RESTART': '1', 01:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:26 INFO - 'PWD': '/builds/slave/test', 01:22:26 INFO - 'SHELL': '/bin/bash', 01:22:26 INFO - 'SHLVL': '1', 01:22:26 INFO - 'TERM': 'linux', 01:22:26 INFO - 'TMOUT': '86400', 01:22:26 INFO - 'USER': 'cltbld', 01:22:26 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:26 INFO - '_': '/tools/buildbot/bin/python'} 01:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:22:26 INFO - Cleaning up... 01:22:26 INFO - Return code: 0 01:22:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:22:26 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')]} 01:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:26 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')]} 01:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:26 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:26 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 01:22:26 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 01:22:26 INFO - Using env: {'DISPLAY': ':0', 01:22:26 INFO - 'HOME': '/home/cltbld', 01:22:26 INFO - 'LANG': 'en_US.UTF-8', 01:22:26 INFO - 'LANGUAGE': 'en_US:en', 01:22:26 INFO - 'LOGNAME': 'cltbld', 01:22:26 INFO - 'MAIL': '/var/mail/cltbld', 01:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:26 INFO - 'MOZ_NO_REMOTE': '1', 01:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:26 INFO - 'NO_EM_RESTART': '1', 01:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:26 INFO - 'PWD': '/builds/slave/test', 01:22:26 INFO - 'SHELL': '/bin/bash', 01:22:26 INFO - 'SHLVL': '1', 01:22:26 INFO - 'TERM': 'linux', 01:22:26 INFO - 'TMOUT': '86400', 01:22:26 INFO - 'USER': 'cltbld', 01:22:26 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:26 INFO - '_': '/tools/buildbot/bin/python'} 01:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:26 INFO - Downloading/unpacking blobuploader==1.2.4 01:22:26 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 01:22:26 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 01:22:26 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 01:22:26 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 01:22:26 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 01:22:26 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 01:22:28 INFO - Downloading blobuploader-1.2.4.tar.gz 01:22:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:22:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:22:28 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 01:22:28 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 01:22:28 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 01:22:28 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 01:22:28 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 01:22:28 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 01:22:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:22:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:22:28 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 01:22:28 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 01:22:28 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 01:22:28 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 01:22:28 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 01:22:28 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 01:22:29 INFO - Downloading docopt-0.6.1.tar.gz 01:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:22:29 INFO - Installing collected packages: blobuploader, requests, docopt 01:22:29 INFO - Running setup.py install for blobuploader 01:22:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:22:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:22:29 INFO - Running setup.py install for requests 01:22:29 INFO - Running setup.py install for docopt 01:22:29 INFO - Successfully installed blobuploader requests docopt 01:22:29 INFO - Cleaning up... 01:22:29 INFO - Return code: 0 01:22:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22: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')]} 01:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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')]} 01:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:29 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:29 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 01:22:29 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 01:22:29 INFO - Using env: {'DISPLAY': ':0', 01:22:29 INFO - 'HOME': '/home/cltbld', 01:22:29 INFO - 'LANG': 'en_US.UTF-8', 01:22:29 INFO - 'LANGUAGE': 'en_US:en', 01:22:29 INFO - 'LOGNAME': 'cltbld', 01:22:29 INFO - 'MAIL': '/var/mail/cltbld', 01:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:29 INFO - 'MOZ_NO_REMOTE': '1', 01:22:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:29 INFO - 'NO_EM_RESTART': '1', 01:22:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:29 INFO - 'PWD': '/builds/slave/test', 01:22:29 INFO - 'SHELL': '/bin/bash', 01:22:29 INFO - 'SHLVL': '1', 01:22:29 INFO - 'TERM': 'linux', 01:22:29 INFO - 'TMOUT': '86400', 01:22:29 INFO - 'USER': 'cltbld', 01:22:29 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:29 INFO - '_': '/tools/buildbot/bin/python'} 01:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:30 INFO - Running setup.py (path:/tmp/pip-sI1cE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:30 INFO - Running setup.py (path:/tmp/pip-KQSeoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:30 INFO - Running setup.py (path:/tmp/pip-ghbRMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:30 INFO - Running setup.py (path:/tmp/pip-N_g76t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:30 INFO - Running setup.py (path:/tmp/pip-xpIUde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:30 INFO - Running setup.py (path:/tmp/pip-kiJvKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:30 INFO - Running setup.py (path:/tmp/pip-IRosyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:30 INFO - Running setup.py (path:/tmp/pip-lifNC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:30 INFO - Running setup.py (path:/tmp/pip-4QrAsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:30 INFO - Running setup.py (path:/tmp/pip-zECBrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:30 INFO - Running setup.py (path:/tmp/pip-vVyAvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:30 INFO - Running setup.py (path:/tmp/pip-0dxeTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:30 INFO - Running setup.py (path:/tmp/pip-f9rZu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:30 INFO - Running setup.py (path:/tmp/pip-H6l7zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:31 INFO - Running setup.py (path:/tmp/pip-76QGGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:31 INFO - Running setup.py (path:/tmp/pip-HXCskQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:31 INFO - Running setup.py (path:/tmp/pip-3T4XsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:22:31 INFO - Running setup.py install for manifestparser 01:22:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:22:31 INFO - Running setup.py install for mozcrash 01:22:31 INFO - Running setup.py install for mozdebug 01:22:31 INFO - Running setup.py install for mozdevice 01:22:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:22:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:22:31 INFO - Running setup.py install for mozfile 01:22:31 INFO - Running setup.py install for mozhttpd 01:22:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:22:31 INFO - Running setup.py install for mozinfo 01:22:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:22:31 INFO - Running setup.py install for mozInstall 01:22:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Running setup.py install for mozleak 01:22:32 INFO - Running setup.py install for mozlog 01:22:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Running setup.py install for moznetwork 01:22:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Running setup.py install for mozprocess 01:22:32 INFO - Running setup.py install for mozprofile 01:22:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Running setup.py install for mozrunner 01:22:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:22:32 INFO - Running setup.py install for mozscreenshot 01:22:32 INFO - Running setup.py install for moztest 01:22:32 INFO - Running setup.py install for mozversion 01:22:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:22:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:22:33 INFO - Cleaning up... 01:22:33 INFO - Return code: 0 01:22:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22: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')]} 01:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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')]} 01:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:33 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:33 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 01:22:33 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 01:22:33 INFO - Using env: {'DISPLAY': ':0', 01:22:33 INFO - 'HOME': '/home/cltbld', 01:22:33 INFO - 'LANG': 'en_US.UTF-8', 01:22:33 INFO - 'LANGUAGE': 'en_US:en', 01:22:33 INFO - 'LOGNAME': 'cltbld', 01:22:33 INFO - 'MAIL': '/var/mail/cltbld', 01:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:33 INFO - 'MOZ_NO_REMOTE': '1', 01:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:33 INFO - 'NO_EM_RESTART': '1', 01:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:33 INFO - 'PWD': '/builds/slave/test', 01:22:33 INFO - 'SHELL': '/bin/bash', 01:22:33 INFO - 'SHLVL': '1', 01:22:33 INFO - 'TERM': 'linux', 01:22:33 INFO - 'TMOUT': '86400', 01:22:33 INFO - 'USER': 'cltbld', 01:22:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:33 INFO - '_': '/tools/buildbot/bin/python'} 01:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:22:33 INFO - Running setup.py (path:/tmp/pip-HZKW9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:22:33 INFO - Running setup.py (path:/tmp/pip-9Nn0Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:22:33 INFO - Running setup.py (path:/tmp/pip-HpMjKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:22:33 INFO - Running setup.py (path:/tmp/pip-ezyCcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:22:33 INFO - Running setup.py (path:/tmp/pip-UXUbQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:22:33 INFO - Running setup.py (path:/tmp/pip-ErNYgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:22:33 INFO - Running setup.py (path:/tmp/pip-rmjKZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:22:33 INFO - Running setup.py (path:/tmp/pip-aUWs2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:22:33 INFO - Running setup.py (path:/tmp/pip-unfewH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:22:33 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)) 01:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:22:33 INFO - Running setup.py (path:/tmp/pip-whSyXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:22:34 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)) 01:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:22:34 INFO - Running setup.py (path:/tmp/pip-lrZgQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:22:34 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)) 01:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:22:34 INFO - Running setup.py (path:/tmp/pip-hLGjzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:22:34 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)) 01:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:22:34 INFO - Running setup.py (path:/tmp/pip-6VvkfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:22:34 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)) 01:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:22:34 INFO - Running setup.py (path:/tmp/pip-niHDa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:22:34 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)) 01:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:34 INFO - Running setup.py (path:/tmp/pip-vbwzxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:22:34 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)) 01:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:22:34 INFO - Running setup.py (path:/tmp/pip-aFrJOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:22:34 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)) 01:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:22:34 INFO - Running setup.py (path:/tmp/pip-YRuMFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:22:34 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)) 01:22:34 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)) 01:22:34 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)) 01:22:34 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)) 01:22:34 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)) 01:22:34 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)) 01:22:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:22: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 01:22: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 01:22: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 01:22: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 01:22: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 01:22: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 01:22:36 INFO - Downloading blessings-1.6.tar.gz 01:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:22:36 INFO - Installing collected packages: blessings 01:22:36 INFO - Running setup.py install for blessings 01:22:36 INFO - Successfully installed blessings 01:22:36 INFO - Cleaning up... 01:22:36 INFO - Return code: 0 01:22:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:22:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:36 INFO - Reading from file tmpfile_stdout 01:22:36 INFO - Current package versions: 01:22:36 INFO - argparse == 1.2.1 01:22:36 INFO - blessings == 1.6 01:22:36 INFO - blobuploader == 1.2.4 01:22:36 INFO - docopt == 0.6.1 01:22:36 INFO - functools32 == 3.2.3-2 01:22:36 INFO - jsonschema == 2.5.1 01:22:36 INFO - manifestparser == 1.1 01:22:36 INFO - mozInstall == 1.12 01:22:36 INFO - mozcrash == 0.17 01:22:36 INFO - mozdebug == 0.1 01:22:36 INFO - mozdevice == 0.48 01:22:36 INFO - mozfile == 1.2 01:22:36 INFO - mozhttpd == 0.7 01:22:36 INFO - mozinfo == 0.9 01:22:36 INFO - mozleak == 0.1 01:22:36 INFO - mozlog == 3.4 01:22:36 INFO - moznetwork == 0.27 01:22:36 INFO - mozprocess == 0.23 01:22:36 INFO - mozprofile == 0.28 01:22:36 INFO - mozrunner == 6.12 01:22:36 INFO - mozscreenshot == 0.1 01:22:36 INFO - mozsystemmonitor == 0.3 01:22:36 INFO - moztest == 0.7 01:22:36 INFO - mozversion == 1.4 01:22:36 INFO - psutil == 3.1.1 01:22:36 INFO - requests == 1.2.3 01:22:36 INFO - wsgiref == 0.1.2 01:22:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:22: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')]} 01:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22: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')]} 01:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:36 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:36 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 01:22:36 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 01:22:37 INFO - Using env: {'DISPLAY': ':0', 01:22:37 INFO - 'HOME': '/home/cltbld', 01:22:37 INFO - 'LANG': 'en_US.UTF-8', 01:22:37 INFO - 'LANGUAGE': 'en_US:en', 01:22:37 INFO - 'LOGNAME': 'cltbld', 01:22:37 INFO - 'MAIL': '/var/mail/cltbld', 01:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:37 INFO - 'MOZ_NO_REMOTE': '1', 01:22:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:37 INFO - 'NO_EM_RESTART': '1', 01:22:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:37 INFO - 'PWD': '/builds/slave/test', 01:22:37 INFO - 'SHELL': '/bin/bash', 01:22:37 INFO - 'SHLVL': '1', 01:22:37 INFO - 'TERM': 'linux', 01:22:37 INFO - 'TMOUT': '86400', 01:22:37 INFO - 'USER': 'cltbld', 01:22:37 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:37 INFO - '_': '/tools/buildbot/bin/python'} 01:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 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)) 01:22:37 INFO - Cleaning up... 01:22:37 INFO - Return code: 0 01:22:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:22:37 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')]} 01:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:37 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')]} 01:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:22:37 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 0x7ff51d4d5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2488ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, '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': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', '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 01:22:37 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 01:22:37 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 01:22:37 INFO - Using env: {'DISPLAY': ':0', 01:22:37 INFO - 'HOME': '/home/cltbld', 01:22:37 INFO - 'LANG': 'en_US.UTF-8', 01:22:37 INFO - 'LANGUAGE': 'en_US:en', 01:22:37 INFO - 'LOGNAME': 'cltbld', 01:22:37 INFO - 'MAIL': '/var/mail/cltbld', 01:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:37 INFO - 'MOZ_NO_REMOTE': '1', 01:22:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:37 INFO - 'NO_EM_RESTART': '1', 01:22:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:37 INFO - 'PWD': '/builds/slave/test', 01:22:37 INFO - 'SHELL': '/bin/bash', 01:22:37 INFO - 'SHLVL': '1', 01:22:37 INFO - 'TERM': 'linux', 01:22:37 INFO - 'TMOUT': '86400', 01:22:37 INFO - 'USER': 'cltbld', 01:22:37 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:37 INFO - '_': '/tools/buildbot/bin/python'} 01:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:22:37 INFO - Cleaning up... 01:22:37 INFO - Return code: 0 01:22:37 INFO - Running post-action listener: _resource_record_post_action 01:22:37 INFO - Running post-action listener: _start_resource_monitoring 01:22:37 INFO - Starting resource monitoring. 01:22:37 INFO - [mozharness: 2017-01-09 09:22:37.470750Z] Finished create-virtualenv step (success) 01:22:37 INFO - [mozharness: 2017-01-09 09:22:37.471200Z] Running install step. 01:22:37 INFO - Running pre-action listener: _resource_record_pre_action 01:22:37 INFO - Running main action method: install 01:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:22:37 INFO - Reading from file tmpfile_stdout 01:22:37 INFO - Detecting whether we're running mozinstall >=1.0... 01:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:22:37 INFO - Reading from file tmpfile_stdout 01:22:37 INFO - Output received: 01:22:37 INFO - Usage: mozinstall [options] installer 01:22:37 INFO - Options: 01:22:37 INFO - -h, --help show this help message and exit 01:22:37 INFO - -d DEST, --destination=DEST 01:22:37 INFO - Directory to install application into. [default: 01:22:37 INFO - "/builds/slave/test"] 01:22:37 INFO - --app=APP Application being installed. [default: firefox] 01:22:37 INFO - mkdir: /builds/slave/test/build/application 01:22:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:22:52 INFO - Reading from file tmpfile_stdout 01:22:52 INFO - Output received: 01:22:52 INFO - /builds/slave/test/build/application/firefox/firefox 01:22:52 INFO - Running post-action listener: _resource_record_post_action 01:22:52 INFO - [mozharness: 2017-01-09 09:22:52.030880Z] Finished install step (success) 01:22:52 INFO - [mozharness: 2017-01-09 09:22:52.031125Z] Running run-tests step. 01:22:52 INFO - Running pre-action listener: _resource_record_pre_action 01:22:52 INFO - Running main action method: run_tests 01:22:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:22:52 INFO - Python 2.7.3 01:22:52 INFO - Return code: 0 01:22:52 INFO - grabbing minidump binary from tooltool 01:22: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')]} 01:22:52 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 0x2485f60>, '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 0x2486d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24871d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:22:52 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 01:22:52 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 01:22:52 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 01:22:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:22:52 INFO - Return code: 0 01:22:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:22:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:22:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:22:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:22:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:22:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 01:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 01:22:52 INFO - Using env: {'DISPLAY': ':0', 01:22:52 INFO - 'HOME': '/home/cltbld', 01:22:52 INFO - 'LANG': 'en_US.UTF-8', 01:22:52 INFO - 'LANGUAGE': 'en_US:en', 01:22:52 INFO - 'LOGNAME': 'cltbld', 01:22:52 INFO - 'MAIL': '/var/mail/cltbld', 01:22:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:22:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:22:52 INFO - 'MOZ_NO_REMOTE': '1', 01:22:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:22:52 INFO - 'NO_EM_RESTART': '1', 01:22:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:22:52 INFO - 'PWD': '/builds/slave/test', 01:22:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:22:52 INFO - 'SHELL': '/bin/bash', 01:22:52 INFO - 'SHLVL': '1', 01:22:52 INFO - 'TERM': 'linux', 01:22:52 INFO - 'TMOUT': '86400', 01:22:52 INFO - 'USER': 'cltbld', 01:22:52 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717', 01:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:22:52 INFO - '_': '/tools/buildbot/bin/python'} 01:22:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 01:22:52 INFO - mozversion application_buildid: 20170108234508 01:22:52 INFO - mozversion application_changeset: 6dc2ca24bd0bcb88992ae72e4fb8285ea8e24333 01:22:52 INFO - mozversion application_display_name: Firefox 01:22:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:22:52 INFO - mozversion application_name: Firefox 01:22:52 INFO - mozversion application_remotingname: firefox 01:22:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:22:52 INFO - mozversion application_vendor: Mozilla 01:22:52 INFO - mozversion application_version: 51.0 01:22:52 INFO - mozversion platform_buildid: 20170108234508 01:22:52 INFO - mozversion platform_changeset: 6dc2ca24bd0bcb88992ae72e4fb8285ea8e24333 01:22:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:22:52 INFO - mozversion platform_version: 51.0 01:22:52 INFO - using testdate: 1483953772 01:22:52 INFO - actual date: 1483953772 01:22:52 INFO - starting webserver on 'localhost:42039' 01:22:52 INFO - SUITE-START | Running 2 tests 01:22:52 INFO - TEST-START | dromaeo_css 01:22:52 INFO - operating with platform_type : linux_ 01:22:52 INFO - Initialising browser for dromaeo_css test... 01:22:52 INFO - TEST-INFO | started process 24829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp40CUuV/profile http://localhost:42039/getInfo.html) 01:22:56 INFO - PROCESS | 24829 | __metrics Screen width/height:1600/1200 01:22:56 INFO - PROCESS | 24829 | colorDepth:24 01:22:56 INFO - PROCESS | 24829 | Browser inner width/height: 1024/697 01:22:56 INFO - PROCESS | 24829 | __metrics 01:22:57 INFO - PROCESS | 24829 | 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" 01:22:57 INFO - PROCESS | 24829 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:22:57 INFO - PROCESS | 24829 | console.error: PushService: 01:22:57 INFO - PROCESS | 24829 | stateChangeProcessEnqueue: Error transitioning state 01:22:57 INFO - PROCESS | 24829 | UnknownError 01:22:58 INFO - TEST-INFO | 24829: exit 0 01:22:58 INFO - Browser initialized. 01:22:58 INFO - Running cycle 1/1 for dromaeo_css test... 01:22:58 INFO - TEST-INFO | started process 24922 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp40CUuV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:23:10 INFO - PROCESS | 24922 | RSS: Main: 143646720 01:23:10 INFO - PROCESS | 24922 | 01:24:01 INFO - PROCESS | 24922 | 1483953841172 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] 01:26:10 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:42039/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42039/tests/dromaeo/cssquery-ext.html) 01:26:11 INFO - PROCESS | 24922 | RSS: Main: 238694400 01:26:11 INFO - PROCESS | 24922 | 01:29:13 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:42039/tests/dromaeo/cssquery-ext.html (next: http://localhost:42039/tests/dromaeo/cssquery-jquery.html) 01:29:14 INFO - PROCESS | 24922 | RSS: Main: 234651648 01:29:14 INFO - PROCESS | 24922 | 01:32:16 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:42039/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42039/tests/dromaeo/cssquery-mootools.html) 01:32:16 INFO - PROCESS | 24922 | RSS: Main: 175624192 01:32:16 INFO - PROCESS | 24922 | 01:35:18 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:42039/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42039/tests/dromaeo/cssquery-prototype.html) 01:35:19 INFO - PROCESS | 24922 | RSS: Main: 175480832 01:35:19 INFO - PROCESS | 24922 | 01:38:21 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:42039/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42039/tests/dromaeo/cssquery-yui.html) 01:38:22 INFO - PROCESS | 24922 | RSS: Main: 177299456 01:38:22 INFO - PROCESS | 24922 | 01:41:24 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:42039/tests/dromaeo/cssquery-yui.html (next: http://localhost:42039/tests/dromaeo/cssquery-dojo.html) 01:41:25 INFO - PROCESS | 24922 | RSS: Main: 263798784 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | __start_tp_report 01:41:25 INFO - PROCESS | 24922 | _x_x_mozilla_page_load 01:41:25 INFO - PROCESS | 24922 | _x_x_mozilla_page_load_details 01:41:25 INFO - PROCESS | 24922 | |i|pagename|runs| 01:41:25 INFO - PROCESS | 24922 | |0;dojo.html;3114.4705400081984;3154.700303471171;3166.3667266546695;3182.0453863840844;3196.3287709580986;5589.301337332833;5654.151877218419;5655.236543066685;5661.207430959664;5685.260916080909;5718.285214348207;5798.7100644967795;5801.274840644919;5802.303723553169;5810.215620891178;5218.425973142951;5268.841934741964;5284.365876094874;5299.60252981026;5323.760430780624;4974.950250497503;5328.973355133219;5351.839444816662;5352.116900711378;5354.196870469422;4931.408231012284;5085.669431486942;5141.76862041209;5205.817796377128;5220.451852555465;5110.156824124034;5145.536901678829;5149.716765577873;5216.347956505437;5221;5061.5950723942;5096.694198348111;5174.715390653512;5187.455317191716;5218.321618189649;5459.69971651559;5497.615166938316;5498.092814685572;5596.720163991784;5597.412271711452;6034.547408944347;6042.214512113397;6049.243844519435;6055.394460553909;6058.96970515149;6093.817185484442;6099.085137229406;6100.664463454508;6107.725152368154;6110.175126357929;5600.187972743929;5606.131049687305;5651.2935883014625;5656.547476201935;5693.459121383462;6274.2784579773;6279.560430769802;6281.528885333527;6284.497240220771;6288.496920246369;5999.880002399927;6007.909881351783;6008.639481631116;6010.2186715726675;6012.759489620454;5813.244278243801;5936.22829032223;5970.820875373745;6054.727537260834;6094.329623741385;6115.358579221981;6121.234845644294;6124.846878828065;6127.295361033538;6129.877402451926;6105.236845394325;6106.267247930276;6109.694515274307;6112.449879510866;6135.601185922901;4536.637069034469;4550.385697930803;4589.862304130881;4693.155232058194;4696.107739529479;4111.403846442249;4129.938050929239;4149.7095203335475;4179.1432756284885;4188.078622703;5812.505936995318;5817.243758311477;5818.738156782955;5833.4749872511675;5850.151727999501;5817.854553636108;5874.765009399662;6096.451319381277;6144.539159563051;6150.600210986271;5770.826875193751;5907.970460147758;5940.495057920125;5948.821535353946;5951.137085122719;5235.319408476874;5240.4759524047295;5253.579713622901;5255.185446255837;5258.815941442108;6021.187139736079;6354.046892966185;6408.198975128109;6426.4537514311805;6428.871422571522;5999.520038397005;6024.367441418588;6095.481884039907;6108.969455152695;6170.167027451236;6318.178636777373;6319.210098737658;6321.557490975588;6325.746970121328;6355.586886852339;5986.16193732869;6003;6057;6063.060225665029;6070.1198326241565;5875.206847075761;5908.025175846109;5922.585419020627;5982.850428739316;6060.575759696955;5864;5885.3820348862755;5886.558508111994;5887.234659494238;5917.792877249276;5825.650460972186;5853.590248682722;5853.824385268278;5857.853553661193;5859.326177489644;5690.687012214368;5807.186993820953;5823.7379317931645;5855.765769369348;5894.498967587803;6023.819285421444;6039.124326972652;6043.153958445733;6053.213082299273;6067.787627433018;5703.116017017369;5893.145493903446;5908.7045647718305;5912.260967379078;5918.585699001201 01:41:25 INFO - PROCESS | 24922 | |1;ext.html;599.2150283129101;621.7823761683411;639.3191251317344;647.9902801457976;660.1945626335869;34279;37496.62503374965;44103.999999999956;46112.538874611244;46275.53724462754;46271.305930410985;46671.533284667144;46705.532944670544;46768;46781.532184678144;8426.452280601772;9220.861687074683;9355.859662105075;9362.859557106649;9374.765630859214;8308.916910830905;8576.0995095515;8579.871301930445;10719.78560428791;11010.779784404307;11077.446127693624;11156.497957591928;11158.944205278962;11159.776804463905;11161.609343672973;10962.780744385107;11033.337999720003;11036.061934735557;11040.171987100959;11042.834357484644;10879.456027198608;10947.4526273686;10947.890521094767;10948.616798412017;10954.671359859218;44085;44566;44607;44661;44689.329660055126;3791.7156213284115;3799.696024318048;3839.78881161536;3925.568187499373;4188.497380314351;34444.82777586096;34445;34469.48295775565;34699.82650086759;34923.65076349255;4418.3372494125815;4476.955230447719;4482.484514280834;4591.1506371321075;4601.447826260836;352631;353995;354268;355642.99999999744;356411;60509.69745151246;60708.696456517435;60805.39194608086;60861;60893.69553152206;39719;40953.79523102365;41066.1786764263;42810.35784463236;64702.352976470575;19246.4226073218;19381.90309048446;19400.805991940182;19429.417117486497;19433.416997489814;15124.621884452758;15233.923830380778;15234.923825380802;15248.923755381375;15259.237038148049;18003.549911252325;18568;19494.132051244036;20370.898145509473;20951.266705665228;7139.714411423589;7139.92860071403;7200.567965922061;7232.529885557422;7318.670659820321;988.6342053440129;1041.6874937518714;1126.985712858434;1134.551852018448;1219.3903048475763;30820.24502114664;33121.66878331234;33177.66822331794;33228.83385583057;33253.66746332506;1155.156735583029;1176.7999440095034;1201.3272567362305;1229.5327775445453;1264.3804535777588;17321.826781732274;17878.195481203376;17890.731639025424;17954.730679039825;18046.819531804776;532339;537882;539400;539790;542385;60463.39536604666;61052.389476105556;61129.38870611326;61173.388266116766;61430.38569614336;1419.496078891999;1451.2308476507467;1457.6283047822737;1463.122126723957;1472.4772705689327;30393.39213215679;32272.83863580667;32414;32417.351652966336;32873;50497.49502504928;51259.74370128274;51290.48709512857;51382.74308628433;51473.48526514837;484252.8936776609;485008;485825;486215;488045;26988.32529186786;30094.976541938857;30200;31021.68978310188;31054.068377948697;40722.79638601788;42552.787236063625;43957.56042439662;45538.58460615401;52064.479355205964;40978.59021409867;42732.78633606812;43325.35011974886;43577.346339804295;44148.33777493404;42489.78755106204;43011;43502.782486087366;44122.77938610415;44597;42919.356209657504;43858.34212486879;43958.56041439545;44107.33838992482;44131.11737765291;36656.633433666386;37855.43216851805;38786.22427551377;38951.512701905114;39052.21895562016;28517.857410712815;28607.713922860505;28782.280442989093;28884.133475995754;29352.853235733684 01:41:25 INFO - PROCESS | 24922 | |2;jquery.html;412.46173743265047;424.42278501238314;427.3226935307537;431.89418592444855;443.56752166637534;18791.812081879176;19072.618547629037;20448.977551122458;20476;20555.280565180223;19586;19611.509712257164;19999.900000499976;20127.698084528707;20209;5800.564957628174;6049.213602231704;6049.78825741097;6058.454739073482;6059.39406059395;5921.934051870661;6005.2193214882;6008.399160083978;6030.698465076751;6097.634141951475;5986.191864098357;6031.6984150792505;6032.487238584726;6036.909446358308;6037.849053773647;5801.970990145043;5966.37353077927;6002.519798416138;6039.184710064129;6060.787872424444;5445.918311225335;5453.127499600045;5456.863578410532;5460.836174914759;5461.208124821878;24285.150019749224;24440.26679199627;24474.510509789703;24668.259952201464;24703.876480617662;2489.0914816092177;2639.366552027519;2715.4704832557463;2860.8426536540483;2900.216941425824;15426.46007389736;15437;15439.845601543953;15503.922480387639;15506.844931550653;2083.5832833433374;2118.5445129297123;2120.6394912864694;2120.650092734697;2275.954480910389;63151.68424157896;67400;69579.65210173967;72608;77523;11977.461014254206;12000.939995299968;12118.454669539891;12166.148369614219;12366.134370594153;17172;17182.570435739206;17204.569885752957;17215.56961075983;17222.483325500256;8436.072032076467;8846.292296616255;8956.283497320199;8958.776030599287;9140.085991400807;8859.335549833788;8912.108789121166;8924.598393072327;9055.501947392957;9105.40814847046;5238.633295669343;5275.446078161738;5285;5310.7610157543;5380.085385484477;2020.4241791089469;2031.7866624004562;2049.149602914774;2063.2366024570706;2131.8061885344255;485.2842057964474;977.5454413697522;1054.9630762923261;1060.2843080920502;1075.387029393254;12325.630231093082;13280.203187808762;13395.464181432633;13400.865991339962;13442.596722098353;513.5403813586819;515.2991930973873;516.630609114485;536.9033573956725;550.4797965922165;7045.7181712731945;7172.031775710317;7200.1359836818865;7254.056972593529;7280.526765760209;519778;526085;527342;533014;534984;78818;79620.60189699015;91540.54229728942;91963.54018229866;96507.51746241364;1055.8785739640039;1066.0565399621391;1105.4859490337033;1114.3202646385698;1114.4093630375914;18266;18575.628487430447;18713.719294210325;18953.526161846064;19117.13972871252;40496.595034050464;41640.375394369716;41838.16323673449;44989.77505112453;45406;52217.47782522126;60995.69502152461;61145.694271530134;62138;62833.37166628458;5819.185314055951;7422.703091876265;8446.915530844613;8771.728099425674;9126.17864392208;13190;13350.132241404277;13720.245386503839;13928.303584820516;14082.859171408154;12056;12131.575394861138;12155.331456769962;12160.087993400708;12422.937885310515;11894.821577676516;11993.460294286604;12117.515299387926;12171.2697238166;12456.190347627375;11941.52233910634;12481.687957801129;12508.624741257778;12742.936285318514;12781.8082728761;8937.261574636677;9165.495897725688;9171.44971301724;9213;9359.251259899327;12311.815322770344;12465.85916161953;12583.119181657195;12734;12934.288614126313 01:41:25 INFO - PROCESS | 24922 | |3;mootools.html;1481.555533339998;1532.0041972717731;1658.9502314930548;1805.837474627285;1806.3045727394958;8754;9468.43189408635;9479;9481.573329200186;9489.098535639121;7897.960510197456;8131.552764597945;8406.369522285822;8649.481031138135;9313.720588382343;2377.5363804058215;2389.6654468374354;2392.3301475586863;2393.9521209575796;2395.161693407311;2376.417777644471;2394.1022116706235;2395.796357309631;2399.124319623342;2401.5677178107935;2395.341281147681;2397.4725560376687;2398.0767404549288;2404.2066118181046;2423.9151629692965;2349.7532759060223;2356.0693526057285;2360.905563777447;2366.692329997098;2379.8334116611845;2323.9070437182663;2334.5447637710654;2337.4506990857135;2339.415146213447;2346.4720437901506;8869.334799890034;9307.813843723088;9376.109269619375;9399.62401503939;9434.05659434058;5351.331083614548;5523.143912693539;5525.475079867406;5527.917081243744;5537.058700021006;3422.5208470814105;3463.809490478023;3490.022793617791;3491.5810102787573;3521.6126226115107;1189.9473408370577;1195.713028873064;1201.531402752927;1209.4436559182698;1226.8466441694789;12784.360781960864;12799.424025918855;12837.1014029018;12852.742945141139;12858.164219325714;4705.200115980291;4734.95265047352;4741.019620019853;4887.315775791392;4905.3132561441425;10883.83674244887;10890;10892.40091794959;10902.345859248471;10937.343759374464;5593.188987596775;5593.720313984284;5599.7480113394995;5601.047821870292;5601.831945041656;4497.505274419811;4639.118567472169;4938.160512712848;5028.145215313405;5165.715885626251;4190.910363305563;4218.093109981369;4284.700070995063;4307.676924230633;4346.130773845243;1303.8696130387075;1336.6992426704107;1338.5047532413068;1348.689801345696;1353.5330311042674;992.1071036067597;1013.964511242103;1016.3546148195801;1022.8056669232822;1038.7610849504656;6242.969909964896;6245.125682404467;6247.250329960434;6391.680415979183;6476.190476190476;462.87502374359406;469.00570789925575;472.6903877959942;475.29893407224756;475.370134571687;4022.4368588397647;4237.110206856533;4369;4432.401625780542;4524.524924882906;10962.506687198937;11438.313701177956;11449.59926402572;11821.586244481401;11839.467223974942;11702.473388697528;11704.180707350573;11755.471003804849;11756.05951523876;11769.823452648217;8558.657653693906;8776.727374530657;8787.121287871161;8790.736277911801;8799.340049496313;6319.368063193828;6511.2512061113575;6537.836554086186;6601;6659.367360100783;10476.842847357144;10804.891951080388;10892.237543371888;10909.890901090888;10927.235093543375;10356;10495.00297471739;10688.732781680521;10873.728156795827;10897.945510272715;7275.963620181865;7515.6242187891485;7945.165757595601;8167.264946154874;8368.874466883124;8822;9167.312451566026;9173.908260917306;9178.495182765013;9189.632414703337;7719.729809456642;7863.606819659109;8050.476719013245;8152.225538573826;8218.58907054633;7615.162332143571;7760.185180556186;7946.364290856834;7956.6419511123295;7993.240642138988;7084.787456376317;7217.530860494051;7328;7328.267173282842;7331.78004659861;5470.6842572700925;5568.276124103841;5626.746796394254;5643.209950606836;5697.914531282117;5439.292891924025;5560.221568980265;5580.330360356783;5596.300462442195;5627.943720562742 01:41:25 INFO - PROCESS | 24922 | |4;prototype.html;349.90902365384994;357.2854291417166;358.226231340305;360.8736942070274;366.4375184092419;5627.380988091307;5659.377468478464;5665.980123577754;5677.772889084442;5711.228984087152;5592.7762889484475;5614.129809879465;5614.9719251403785;5638.548916086714;5676.403977582357;4199.265128602506;4391.4730232372;4396.593090211134;4403.669724770655;4413.16149931512;4383.211022016036;4383.408239887606;4393.2970724684055;4406.096750166208;4409.779511024452;4363.4327537420095;4365.454318210223;4367.148406060804;4367.71609845359;4385.254506733863;4075.572064933169;4246.534945443817;4328.350747387885;4345.152695224432;4389.890252743675;3789.829457674411;3801.7148713846295;3808.619138086169;3813.48517950076;3826.8660596879254;5437.918431223535;5472.534834539029;5475.178723191513;5482.342118945712;5484.862878428032;4891.192953162724;4945.579625731817;4973.502649735034;5033.572146367563;5042.798288068473;5793.8551536211935;5819.912701309484;5832.416758324176;5846.356900740915;5864.38423965486;4270.73650964986;4271.6582673386365;4275.679324050678;4305.763183024932;4321.762303073329;5699;5887.970560147171;5915.5859089863725;5988.820335389945;6075.544334174954;4247.256730072225;4275.422817919603;4310.3103503439925;4327.221100201994;4365.869023929287;4440.888977775581;4448.266036104072;4457.130859482435;4463.509014008457;4468.687191896536;4913.680610760289;5023.8492845214705;5058.241263810458;5069.138246498104;5078.162103252996;5854.677992710356;5856.736446859902;5875.353711091777;5898.203742494794;5941.574022234719;1492.910425374481;1503.9624009399633;1515.8408367121515;1525.184026545815;1526.206372686219;927.9675211367705;970.1801977329145;971.3540495570508;981.1954197558076;1006.1648831469862;4991.47589503097;5045.697258164522;5065.379078694856;5077.923831142536;5108.2848401223855;5273.762680679302;5279.260903473517;5300.416954135042;5309.2301616266195;5337.973310133502;4882.6826256293225;4985.476524964899;5036.521530454601;5044.798208071709;5073.797048118107;2801.067263588118;2817.042205650089;2824.759895408872;2828.292926768308;2835.844028578407;5324.893502130013;5343.70609616467;5361.437049109865;5369.66419607472;5416.350037995466;4975.850724478271;5313.910648317085;5325.9081888213595;5363.758630861621;5380.408155102936;5325.523631511366;5362.490563396471;5372.543333816591;5409.323109835964;5420.972895135578;5227.2420499028185;5255.921161182662;5261.6579922306455;5263.447338029605;5272.599173532973;5294.682319060868;5920.259967504062;5992.370801065999;5992.400759923973;6034;5973;6092.786752463642;6116;6223.3465486123305;6263.874722505616;4306.2248795217165;4308.418363520885;4331.393604895254;4340.175366680321;4345.130973805189;6054;6054.9091763624465;6057.487686205532;6074.514038876968;6083.69581520931;5932.436418540232;5941.821125697731;5942.821715348547;5945.970270148621;6017.217761691128;5932.0508718604815;5959.284885813557;5979.611325263844;5982.940170598238;5990.520758339409;5598.356189038313;5777.537796976148;5795.861520095436;5837.1244313351635;5920.407959204045;5912.320083190488;5919.408059194218;5922.223333000169;5922.407759224043;5934.525237980864;5596.18855265976;5772.5381969441505;5788;5813.651180929158;5834.095715164156 01:41:25 INFO - PROCESS | 24922 | |5;yui.html;774.5314084978588;789.28175360422;794.4557977785217;836.6444261188993;881.9162179592936;17220;17789.199486023143;17808;17832.375866844664;17934.28262869485;16039.278232479537;16339.509814705578;16364.099974501398;16375.4268600599;16397.344106235738;700.7407259314061;718.4791026505794;838.7357982235607;842.3219308456705;870.1776820904248;858.51064893011;863.0592654007131;866.0516734176099;866.4844417571535;866.6360128745941;920.5305294299922;920.9171174594285;921.0375157959948;922.3174850610534;922.557172557173;918.3342076994193;919.0625561926864;919.3013309884502;919.4804935211585;919.6735159018552;670.3162773970571;675.0582936803132;675.6959368284291;675.8850995330756;675.9594424334507;14785.26073696311;14812;14824.407023719146;14844.183569903651;14845.703085938329;13046.869531304756;13495.730085398336;13894;14114.71770564583;15924.12417317047;13929.233892135928;14403.927980360137;14465.638359041002;14662.853371466363;14963.700725985529;4703.270992996091;4710.69380490267;4723.787429565644;4755.381800365965;4842.782074806642;83899;84287.57856210742;84543;84641;84791.5760421194;12423.875761242452;12450.128491005724;12588.937055314665;12641.43113559892;12751.238201109732;22237.777622223894;22607.660885086738;22621.20825771123;22711.20510782148;22782.088716451155;8729;8775.824483510294;8841.55792210387;9014.323925705598;9037.63849446028;4909.5090490950615;4990.326305948723;5051.444341122474;5056.089903817275;5169.405518365361;3441;3538.5046093546925;3574.803385813805;3575.4636804479014;3576.213233088716;631.1511017681212;635.8251480842713;637.6142433827561;637.7735903754101;641.788209890735;688.3288793426469;691.8339598496325;697.5709938387855;704.7357241034612;715.0525553641446;8542.572871356408;9565.665201717906;10806.459677016117;11096.611618593472;11162;758.5145506875604;779.9415043871619;786.5516655506307;805.4603415711488;809.7408829174608;4804.663673542888;4942.9258561121615;5024.673396229234;5051.873703157377;5061.44324124346;69958.65020674864;70926;85470;120466.39766801285;120934;89516.10483895078;101718;103149.48425257826;103585.48207259066;104344.47827760813;55835.72082139645;70458;88174.55912720524;90434.54782726044;91355;9897.109260166331;10189.694309170736;10207.54066067014;10411.1671066313;10535.947320263349;40535.3919691199;40559.79720101499;49210.75394623004;55474.72262638661;57498;66062;66764;68098;80122;80458.19541804507;13576.59270221895;14886.404543818559;14912.85087149158;14949.77575336349;15222.923885380502;16577.254023568727;16792.24434900408;16814.915925420293;17168.74246886273;18147;14227.430902763774;14738.26308684583;14938.626534336294;15249.923750381176;15418.152001640017;13471.663208419868;13575;13670;14111.364988575744;14228.644283892985;14089.577312680636;15013;15031.774523381939;15051.397944082115;15383.769243461133;11220.943895280472;11531.596394126502;12081.577144800242;12829.550965716155;12985.740285194055;12253.326067066397;12389.194702344319;12918.289494077915;12927.676808079874;13134.671633209246 01:41:25 INFO - PROCESS | 24922 | __end_tp_report 01:41:25 INFO - PROCESS | 24922 | __start_cc_report 01:41:25 INFO - PROCESS | 24922 | _x_x_mozilla_cycle_collect,864 01:41:25 INFO - PROCESS | 24922 | __end_cc_report 01:41:25 INFO - PROCESS | 24922 | __startTimestamp1483954885150__endTimestamp 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | ------- Summary: start ------- 01:41:25 INFO - PROCESS | 24922 | Number of tests: 6 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | [#0] dojo.html Cycles:165 Average:5647.05 Median:5859.33 stddev:658.35 (11.2%) stddev-sans-first:629.68 01:41:25 INFO - PROCESS | 24922 | Values: 3114.5 3154.7 3166.4 3182.0 3196.3 5589.3 5654.2 5655.2 5661.2 5685.3 5718.3 5798.7 5801.3 5802.3 5810.2 5218.4 5268.8 5284.4 5299.6 5323.8 4975.0 5329.0 5351.8 5352.1 5354.2 4931.4 5085.7 5141.8 5205.8 5220.5 5110.2 5145.5 5149.7 5216.3 5221.0 5061.6 5096.7 5174.7 5187.5 5218.3 5459.7 5497.6 5498.1 5596.7 5597.4 6034.5 6042.2 6049.2 6055.4 6059.0 6093.8 6099.1 6100.7 6107.7 6110.2 5600.2 5606.1 5651.3 5656.5 5693.5 6274.3 6279.6 6281.5 6284.5 6288.5 5999.9 6007.9 6008.6 6010.2 6012.8 5813.2 5936.2 5970.8 6054.7 6094.3 6115.4 6121.2 6124.8 6127.3 6129.9 6105.2 6106.3 6109.7 6112.4 6135.6 4536.6 4550.4 4589.9 4693.2 4696.1 4111.4 4129.9 4149.7 4179.1 4188.1 5812.5 5817.2 5818.7 5833.5 5850.2 5817.9 5874.8 6096.5 6144.5 6150.6 5770.8 5908.0 5940.5 5948.8 5951.1 5235.3 5240.5 5253.6 5255.2 5258.8 6021.2 6354.0 6408.2 6426.5 6428.9 5999.5 6024.4 6095.5 6109.0 6170.2 6318.2 6319.2 6321.6 6325.7 6355.6 5986.2 6003.0 6057.0 6063.1 6070.1 5875.2 5908.0 5922.6 5982.9 6060.6 5864.0 5885.4 5886.6 5887.2 5917.8 5825.7 5853.6 5853.8 5857.9 5859.3 5690.7 5807.2 5823.7 5855.8 5894.5 6023.8 6039.1 6043.2 6053.2 6067.8 5703.1 5893.1 5908.7 5912.3 5918.6 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | [#1] ext.html Cycles:180 Average:62380.22 Median:21488.89 stddev:123477.54 (574.6%) stddev-sans-first:123736.80 01:41:25 INFO - PROCESS | 24922 | Values: 599.2 621.8 639.3 648.0 660.2 34279.0 37496.6 44104.0 46112.5 46275.5 46271.3 46671.5 46705.5 46768.0 46781.5 8426.5 9220.9 9355.9 9362.9 9374.8 8308.9 8576.1 8579.9 10719.8 11010.8 11077.4 11156.5 11158.9 11159.8 11161.6 10962.8 11033.3 11036.1 11040.2 11042.8 10879.5 10947.5 10947.9 10948.6 10954.7 44085.0 44566.0 44607.0 44661.0 44689.3 3791.7 3799.7 3839.8 3925.6 4188.5 34444.8 34445.0 34469.5 34699.8 34923.7 4418.3 4477.0 4482.5 4591.2 4601.4 352631.0 353995.0 354268.0 355643.0 356411.0 60509.7 60708.7 60805.4 60861.0 60893.7 39719.0 40953.8 41066.2 42810.4 64702.4 19246.4 19381.9 19400.8 19429.4 19433.4 15124.6 15233.9 15234.9 15248.9 15259.2 18003.5 18568.0 19494.1 20370.9 20951.3 7139.7 7139.9 7200.6 7232.5 7318.7 988.6 1041.7 1127.0 1134.6 1219.4 30820.2 33121.7 33177.7 33228.8 33253.7 1155.2 1176.8 1201.3 1229.5 1264.4 17321.8 17878.2 17890.7 17954.7 18046.8 532339.0 537882.0 539400.0 539790.0 542385.0 60463.4 61052.4 61129.4 61173.4 61430.4 1419.5 1451.2 1457.6 1463.1 1472.5 30393.4 32272.8 32414.0 32417.4 32873.0 50497.5 51259.7 51290.5 51382.7 51473.5 484252.9 485008.0 485825.0 486215.0 488045.0 26988.3 30095.0 30200.0 31021.7 31054.1 40722.8 42552.8 43957.6 45538.6 52064.5 40978.6 42732.8 43325.4 43577.3 44148.3 42489.8 43011.0 43502.8 44122.8 44597.0 42919.4 43858.3 43958.6 44107.3 44131.1 36656.6 37855.4 38786.2 38951.5 39052.2 28517.9 28607.7 28782.3 28884.1 29352.9 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | [#2] jquery.html Cycles:180 Average:29987.35 Median:41739.27 stddev:86648.17 (207.6%) stddev-sans-first:86862.78 01:41:25 INFO - PROCESS | 24922 | Values: 412.5 424.4 427.3 431.9 443.6 18791.8 19072.6 20449.0 20476.0 20555.3 19586.0 19611.5 19999.9 20127.7 20209.0 5800.6 6049.2 6049.8 6058.5 6059.4 5921.9 6005.2 6008.4 6030.7 6097.6 5986.2 6031.7 6032.5 6036.9 6037.8 5802.0 5966.4 6002.5 6039.2 6060.8 5445.9 5453.1 5456.9 5460.8 5461.2 24285.2 24440.3 24474.5 24668.3 24703.9 2489.1 2639.4 2715.5 2860.8 2900.2 15426.5 15437.0 15439.8 15503.9 15506.8 2083.6 2118.5 2120.6 2120.7 2276.0 63151.7 67400.0 69579.7 72608.0 77523.0 11977.5 12000.9 12118.5 12166.1 12366.1 17172.0 17182.6 17204.6 17215.6 17222.5 8436.1 8846.3 8956.3 8958.8 9140.1 8859.3 8912.1 8924.6 9055.5 9105.4 5238.6 5275.4 5285.0 5310.8 5380.1 2020.4 2031.8 2049.1 2063.2 2131.8 485.3 977.5 1055.0 1060.3 1075.4 12325.6 13280.2 13395.5 13400.9 13442.6 513.5 515.3 516.6 536.9 550.5 7045.7 7172.0 7200.1 7254.1 7280.5 519778.0 526085.0 527342.0 533014.0 534984.0 78818.0 79620.6 91540.5 91963.5 96507.5 1055.9 1066.1 1105.5 1114.3 1114.4 18266.0 18575.6 18713.7 18953.5 19117.1 40496.6 41640.4 41838.2 44989.8 45406.0 52217.5 60995.7 61145.7 62138.0 62833.4 5819.2 7422.7 8446.9 8771.7 9126.2 13190.0 13350.1 13720.2 13928.3 14082.9 12056.0 12131.6 12155.3 12160.1 12422.9 11894.8 11993.5 12117.5 12171.3 12456.2 11941.5 12481.7 12508.6 12742.9 12781.8 8937.3 9165.5 9171.4 9213.0 9359.3 12311.8 12465.9 12583.1 12734.0 12934.3 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | [#3] mootools.html Cycles:180 Average:6075.68 Median:2551.00 stddev:3496.82 (137.1%) stddev-sans-first:3489.58 01:41:25 INFO - PROCESS | 24922 | Values: 1481.6 1532.0 1659.0 1805.8 1806.3 8754.0 9468.4 9479.0 9481.6 9489.1 7898.0 8131.6 8406.4 8649.5 9313.7 2377.5 2389.7 2392.3 2394.0 2395.2 2376.4 2394.1 2395.8 2399.1 2401.6 2395.3 2397.5 2398.1 2404.2 2423.9 2349.8 2356.1 2360.9 2366.7 2379.8 2323.9 2334.5 2337.5 2339.4 2346.5 8869.3 9307.8 9376.1 9399.6 9434.1 5351.3 5523.1 5525.5 5527.9 5537.1 3422.5 3463.8 3490.0 3491.6 3521.6 1189.9 1195.7 1201.5 1209.4 1226.8 12784.4 12799.4 12837.1 12852.7 12858.2 4705.2 4735.0 4741.0 4887.3 4905.3 10883.8 10890.0 10892.4 10902.3 10937.3 5593.2 5593.7 5599.7 5601.0 5601.8 4497.5 4639.1 4938.2 5028.1 5165.7 4190.9 4218.1 4284.7 4307.7 4346.1 1303.9 1336.7 1338.5 1348.7 1353.5 992.1 1014.0 1016.4 1022.8 1038.8 6243.0 6245.1 6247.3 6391.7 6476.2 462.9 469.0 472.7 475.3 475.4 4022.4 4237.1 4369.0 4432.4 4524.5 10962.5 11438.3 11449.6 11821.6 11839.5 11702.5 11704.2 11755.5 11756.1 11769.8 8558.7 8776.7 8787.1 8790.7 8799.3 6319.4 6511.3 6537.8 6601.0 6659.4 10476.8 10804.9 10892.2 10909.9 10927.2 10356.0 10495.0 10688.7 10873.7 10897.9 7276.0 7515.6 7945.2 8167.3 8368.9 8822.0 9167.3 9173.9 9178.5 9189.6 7719.7 7863.6 8050.5 8152.2 8218.6 7615.2 7760.2 7946.4 7956.6 7993.2 7084.8 7217.5 7328.0 7328.3 7331.8 5470.7 5568.3 5626.7 5643.2 5697.9 5439.3 5560.2 5580.3 5596.3 5627.9 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | [#4] prototype.html Cycles:180 Average:4797.92 Median:5304.82 stddev:1389.98 (26.2%) stddev-sans-first:1353.19 01:41:25 INFO - PROCESS | 24922 | Values: 349.9 357.3 358.2 360.9 366.4 5627.4 5659.4 5666.0 5677.8 5711.2 5592.8 5614.1 5615.0 5638.5 5676.4 4199.3 4391.5 4396.6 4403.7 4413.2 4383.2 4383.4 4393.3 4406.1 4409.8 4363.4 4365.5 4367.1 4367.7 4385.3 4075.6 4246.5 4328.4 4345.2 4389.9 3789.8 3801.7 3808.6 3813.5 3826.9 5437.9 5472.5 5475.2 5482.3 5484.9 4891.2 4945.6 4973.5 5033.6 5042.8 5793.9 5819.9 5832.4 5846.4 5864.4 4270.7 4271.7 4275.7 4305.8 4321.8 5699.0 5888.0 5915.6 5988.8 6075.5 4247.3 4275.4 4310.3 4327.2 4365.9 4440.9 4448.3 4457.1 4463.5 4468.7 4913.7 5023.8 5058.2 5069.1 5078.2 5854.7 5856.7 5875.4 5898.2 5941.6 1492.9 1504.0 1515.8 1525.2 1526.2 928.0 970.2 971.4 981.2 1006.2 4991.5 5045.7 5065.4 5077.9 5108.3 5273.8 5279.3 5300.4 5309.2 5338.0 4882.7 4985.5 5036.5 5044.8 5073.8 2801.1 2817.0 2824.8 2828.3 2835.8 5324.9 5343.7 5361.4 5369.7 5416.4 4975.9 5313.9 5325.9 5363.8 5380.4 5325.5 5362.5 5372.5 5409.3 5421.0 5227.2 5255.9 5261.7 5263.4 5272.6 5294.7 5920.3 5992.4 5992.4 6034.0 5973.0 6092.8 6116.0 6223.3 6263.9 4306.2 4308.4 4331.4 4340.2 4345.1 6054.0 6054.9 6057.5 6074.5 6083.7 5932.4 5941.8 5942.8 5946.0 6017.2 5932.1 5959.3 5979.6 5982.9 5990.5 5598.4 5777.5 5795.9 5837.1 5920.4 5912.3 5919.4 5922.2 5922.4 5934.5 5596.2 5772.5 5788.0 5813.7 5834.1 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - PROCESS | 24922 | [#5] yui.html Cycles:180 Average:20731.12 Median:4706.98 stddev:28428.24 (604.0%) stddev-sans-first:28468.50 01:41:25 INFO - PROCESS | 24922 | Values: 774.5 789.3 794.5 836.6 881.9 17220.0 17789.2 17808.0 17832.4 17934.3 16039.3 16339.5 16364.1 16375.4 16397.3 700.7 718.5 838.7 842.3 870.2 858.5 863.1 866.1 866.5 866.6 920.5 920.9 921.0 922.3 922.6 918.3 919.1 919.3 919.5 919.7 670.3 675.1 675.7 675.9 676.0 14785.3 14812.0 14824.4 14844.2 14845.7 13046.9 13495.7 13894.0 14114.7 15924.1 13929.2 14403.9 14465.6 14662.9 14963.7 4703.3 4710.7 4723.8 4755.4 4842.8 83899.0 84287.6 84543.0 84641.0 84791.6 12423.9 12450.1 12588.9 12641.4 12751.2 22237.8 22607.7 22621.2 22711.2 22782.1 8729.0 8775.8 8841.6 9014.3 9037.6 4909.5 4990.3 5051.4 5056.1 5169.4 3441.0 3538.5 3574.8 3575.5 3576.2 631.2 635.8 637.6 637.8 641.8 688.3 691.8 697.6 704.7 715.1 8542.6 9565.7 10806.5 11096.6 11162.0 758.5 779.9 786.6 805.5 809.7 4804.7 4942.9 5024.7 5051.9 5061.4 69958.7 70926.0 85470.0 120466.4 120934.0 89516.1 101718.0 103149.5 103585.5 104344.5 55835.7 70458.0 88174.6 90434.5 91355.0 9897.1 10189.7 10207.5 10411.2 10535.9 40535.4 40559.8 49210.8 55474.7 57498.0 66062.0 66764.0 68098.0 80122.0 80458.2 13576.6 14886.4 14912.9 14949.8 15222.9 16577.3 16792.2 16814.9 17168.7 18147.0 14227.4 14738.3 14938.6 15249.9 15418.2 13471.7 13575.0 13670.0 14111.4 14228.6 14089.6 15013.0 15031.8 15051.4 15383.8 11220.9 11531.6 12081.6 12829.6 12985.7 12253.3 12389.2 12918.3 12927.7 13134.7 01:41:25 INFO - PROCESS | 24922 | -------- Summary: end -------- 01:41:25 INFO - PROCESS | 24922 | 01:41:25 INFO - TEST-INFO | 24922: exit 0 01:41:25 INFO - TEST-OK | dromaeo_css | took 1113557ms 01:41:25 INFO - TEST-START | kraken 01:41:25 INFO - operating with platform_type : linux_ 01:41:25 INFO - Initialising browser for kraken test... 01:41:25 INFO - TEST-INFO | started process 25227 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzJ4pCA/profile http://localhost:42039/getInfo.html) 01:41:30 INFO - PROCESS | 25227 | __metrics Screen width/height:1600/1200 01:41:30 INFO - PROCESS | 25227 | colorDepth:24 01:41:30 INFO - PROCESS | 25227 | Browser inner width/height: 1024/697 01:41:30 INFO - PROCESS | 25227 | __metrics 01:41:30 INFO - PROCESS | 25227 | 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" 01:41:30 INFO - PROCESS | 25227 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:41:31 INFO - PROCESS | 25227 | console.error: PushService: 01:41:31 INFO - PROCESS | 25227 | stateChangeProcessEnqueue: Error transitioning state 01:41:31 INFO - PROCESS | 25227 | UnknownError 01:41:31 INFO - TEST-INFO | 25227: exit 0 01:41:31 INFO - Browser initialized. 01:41:31 INFO - Running cycle 1/1 for kraken test... 01:41:31 INFO - TEST-INFO | started process 25320 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzJ4pCA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:43 INFO - PROCESS | 25320 | RSS: Main: 143822848 01:41:43 INFO - PROCESS | 25320 | 01:41:46 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42039/tests/kraken/driver.html?testName=audio-beat-detection) 01:41:46 INFO - PROCESS | 25320 | RSS: Main: 186900480 01:41:46 INFO - PROCESS | 25320 | 01:41:49 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42039/tests/kraken/driver.html?testName=audio-dft) 01:41:49 INFO - PROCESS | 25320 | RSS: Main: 174391296 01:41:49 INFO - PROCESS | 25320 | 01:41:52 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42039/tests/kraken/driver.html?testName=audio-fft) 01:41:53 INFO - PROCESS | 25320 | RSS: Main: 266276864 01:41:53 INFO - PROCESS | 25320 | 01:41:55 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42039/tests/kraken/driver.html?testName=audio-oscillator) 01:41:55 INFO - PROCESS | 25320 | RSS: Main: 172863488 01:41:56 INFO - PROCESS | 25320 | 01:41:58 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42039/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:41:58 INFO - PROCESS | 25320 | RSS: Main: 174170112 01:41:58 INFO - PROCESS | 25320 | 01:42:01 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42039/tests/kraken/driver.html?testName=imaging-darkroom) 01:42:02 INFO - PROCESS | 25320 | RSS: Main: 210288640 01:42:02 INFO - PROCESS | 25320 | 01:42:05 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42039/tests/kraken/driver.html?testName=imaging-desaturate) 01:42:06 INFO - PROCESS | 25320 | RSS: Main: 208338944 01:42:06 INFO - PROCESS | 25320 | 01:42:08 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42039/tests/kraken/driver.html?testName=json-parse-financial) 01:42:09 INFO - PROCESS | 25320 | RSS: Main: 208134144 01:42:09 INFO - PROCESS | 25320 | 01:42:10 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42039/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:42:11 INFO - PROCESS | 25320 | RSS: Main: 170291200 01:42:11 INFO - PROCESS | 25320 | 01:42:13 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:42:13 INFO - PROCESS | 25320 | RSS: Main: 178081792 01:42:13 INFO - PROCESS | 25320 | 01:42:15 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:42:16 INFO - PROCESS | 25320 | RSS: Main: 187019264 01:42:16 INFO - PROCESS | 25320 | 01:42:18 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:42:19 INFO - PROCESS | 25320 | RSS: Main: 196476928 01:42:19 INFO - PROCESS | 25320 | 01:42:22 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:42:22 INFO - PROCESS | 25320 | RSS: Main: 192942080 01:42:22 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | Cycle 1(1): loaded http://localhost:42039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42039/tests/kraken/driver.html?testName=ai-astar) 01:42:24 INFO - PROCESS | 25320 | RSS: Main: 245059584 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | __start_tp_report 01:42:24 INFO - PROCESS | 25320 | _x_x_mozilla_page_load 01:42:24 INFO - PROCESS | 25320 | _x_x_mozilla_page_load_details 01:42:24 INFO - PROCESS | 25320 | |i|pagename|runs| 01:42:24 INFO - PROCESS | 25320 | |0;ai-astar;110;110;111;111;111;112;110;110;110;109 01:42:24 INFO - PROCESS | 25320 | |1;audio-beat-detection;149;124;115;159;116;117;117;121;155;118 01:42:24 INFO - PROCESS | 25320 | |2;audio-dft;161;160;160;160;161;161;160;161;160;160 01:42:24 INFO - PROCESS | 25320 | |3;audio-fft;73;74;75;123;74;76;75;76;99;74 01:42:24 INFO - PROCESS | 25320 | |4;audio-oscillator;82;83;125;82;80;82;100;81;81;105 01:42:24 INFO - PROCESS | 25320 | |5;imaging-gaussian-blur;116;117;116;117;117;117;117;117;117;116 01:42:24 INFO - PROCESS | 25320 | |6;imaging-darkroom;146;146;146;146;146;147;145;146;146;145 01:42:24 INFO - PROCESS | 25320 | |7;imaging-desaturate;95;95;95;95;95;95;96;95;95;94 01:42:24 INFO - PROCESS | 25320 | |8;json-parse-financial;49;49;49;49;49;52;50;49;50;49 01:42:24 INFO - PROCESS | 25320 | |9;json-stringify-tinderbox;58;59;59;58;58;59;59;59;59;59 01:42:24 INFO - PROCESS | 25320 | |10;stanford-crypto-aes;73;73;74;76;73;74;82;73;80;73 01:42:24 INFO - PROCESS | 25320 | |11;stanford-crypto-ccm;127;129;122;129;121;117;126;121;162;120 01:42:24 INFO - PROCESS | 25320 | |12;stanford-crypto-pbkdf2;152;154;154;151;152;154;152;152;161;156 01:42:24 INFO - PROCESS | 25320 | |13;stanford-crypto-sha256-iterative;62;62;61;61;62;62;61;64;60;60 01:42:24 INFO - PROCESS | 25320 | __end_tp_report 01:42:24 INFO - PROCESS | 25320 | __start_cc_report 01:42:24 INFO - PROCESS | 25320 | _x_x_mozilla_cycle_collect,1569 01:42:24 INFO - PROCESS | 25320 | __end_cc_report 01:42:24 INFO - PROCESS | 25320 | __startTimestamp1483954944723__endTimestamp 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | ------- Summary: start ------- 01:42:24 INFO - PROCESS | 25320 | Number of tests: 14 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#0] ai-astar Cycles:10 Average:110.40 Median:110.50 stddev:0.84 (0.8%) stddev-sans-first:0.88 01:42:24 INFO - PROCESS | 25320 | Values: 110.0 110.0 111.0 111.0 111.0 112.0 110.0 110.0 110.0 109.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:122.50 stddev:17.76 (14.5%) stddev-sans-first:17.32 01:42:24 INFO - PROCESS | 25320 | Values: 149.0 124.0 115.0 159.0 116.0 117.0 117.0 121.0 155.0 118.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.52 (0.3%) stddev-sans-first:0.50 01:42:24 INFO - PROCESS | 25320 | Values: 161.0 160.0 160.0 160.0 161.0 161.0 160.0 161.0 160.0 160.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.00 stddev:16.37 (21.8%) stddev-sans-first:17.05 01:42:24 INFO - PROCESS | 25320 | Values: 73.0 74.0 75.0 123.0 74.0 76.0 75.0 76.0 99.0 74.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:81.50 stddev:15.10 (18.5%) stddev-sans-first:15.73 01:42:24 INFO - PROCESS | 25320 | Values: 82.0 83.0 125.0 82.0 80.0 82.0 100.0 81.0 81.0 105.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 01:42:24 INFO - PROCESS | 25320 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 01:42:24 INFO - PROCESS | 25320 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 146.0 145.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 01:42:24 INFO - PROCESS | 25320 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:49.00 stddev:0.97 (2.0%) stddev-sans-first:1.01 01:42:24 INFO - PROCESS | 25320 | Values: 49.0 49.0 49.0 49.0 49.0 52.0 50.0 49.0 50.0 49.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 01:42:24 INFO - PROCESS | 25320 | Values: 58.0 59.0 59.0 58.0 58.0 59.0 59.0 59.0 59.0 59.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:74.00 stddev:3.28 (4.4%) stddev-sans-first:3.39 01:42:24 INFO - PROCESS | 25320 | Values: 73.0 73.0 74.0 76.0 73.0 74.0 82.0 73.0 80.0 73.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:126.50 stddev:12.82 (10.1%) stddev-sans-first:13.59 01:42:24 INFO - PROCESS | 25320 | Values: 127.0 129.0 122.0 129.0 121.0 117.0 126.0 121.0 162.0 120.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.80 Median:154.00 stddev:2.94 (1.9%) stddev-sans-first:3.04 01:42:24 INFO - PROCESS | 25320 | Values: 152.0 154.0 154.0 151.0 152.0 154.0 152.0 152.0 161.0 156.0 01:42:24 INFO - PROCESS | 25320 | 01:42:24 INFO - PROCESS | 25320 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.18 (1.9%) stddev-sans-first:1.24 01:42:24 INFO - PROCESS | 25320 | Values: 62.0 62.0 61.0 61.0 62.0 62.0 61.0 64.0 60.0 60.0 01:42:24 INFO - PROCESS | 25320 | -------- Summary: end -------- 01:42:24 INFO - PROCESS | 25320 | 01:42:25 INFO - TEST-INFO | 25320: exit 0 01:42:25 INFO - TEST-OK | kraken | took 59910ms 01:42:25 INFO - SUITE-END | took 1173s 01:42:25 INFO - Completed test suite (00:19:33) 01:42:25 INFO - javascript benchmark 01:42:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3114.4705400081984, 3154.700303471171, 3166.3667266546695, 3182.0453863840844, 3196.3287709580986, 5589.301337332833, 5654.151877218419, 5655.236543066685, 5661.207430959664, 5685.260916080909, 5718.285214348207, 5798.7100644967795, 5801.274840644919, 5802.303723553169, 5810.215620891178, 5218.425973142951, 5268.841934741964, 5284.365876094874, 5299.60252981026, 5323.760430780624, 4974.950250497503, 5328.973355133219, 5351.839444816662, 5352.116900711378, 5354.196870469422, 4931.408231012284, 5085.669431486942, 5141.76862041209, 5205.817796377128, 5220.451852555465, 5110.156824124034, 5145.536901678829, 5149.716765577873, 5216.347956505437, 5221.0, 5061.5950723942, 5096.694198348111, 5174.715390653512, 5187.455317191716, 5218.321618189649, 5459.69971651559, 5497.615166938316, 5498.092814685572, 5596.720163991784, 5597.412271711452, 6034.547408944347, 6042.214512113397, 6049.243844519435, 6055.394460553909, 6058.96970515149, 6093.817185484442, 6099.085137229406, 6100.664463454508, 6107.725152368154, 6110.175126357929, 5600.187972743929, 5606.131049687305, 5651.2935883014625, 5656.547476201935, 5693.459121383462, 6274.2784579773, 6279.560430769802, 6281.528885333527, 6284.497240220771, 6288.496920246369, 5999.880002399927, 6007.909881351783, 6008.639481631116, 6010.2186715726675, 6012.759489620454, 5813.244278243801, 5936.22829032223, 5970.820875373745, 6054.727537260834, 6094.329623741385, 6115.358579221981, 6121.234845644294, 6124.846878828065, 6127.295361033538, 6129.877402451926, 6105.236845394325, 6106.267247930276, 6109.694515274307, 6112.449879510866, 6135.601185922901, 4536.637069034469, 4550.385697930803, 4589.862304130881, 4693.155232058194, 4696.107739529479, 4111.403846442249, 4129.938050929239, 4149.7095203335475, 4179.1432756284885, 4188.078622703, 5812.505936995318, 5817.243758311477, 5818.738156782955, 5833.4749872511675, 5850.151727999501, 5817.854553636108, 5874.765009399662, 6096.451319381277, 6144.539159563051, 6150.600210986271, 5770.826875193751, 5907.970460147758, 5940.495057920125, 5948.821535353946, 5951.137085122719, 5235.319408476874, 5240.4759524047295, 5253.579713622901, 5255.185446255837, 5258.815941442108, 6021.187139736079, 6354.046892966185, 6408.198975128109, 6426.4537514311805, 6428.871422571522, 5999.520038397005, 6024.367441418588, 6095.481884039907, 6108.969455152695, 6170.167027451236, 6318.178636777373, 6319.210098737658, 6321.557490975588, 6325.746970121328, 6355.586886852339, 5986.16193732869, 6003.0, 6057.0, 6063.060225665029, 6070.1198326241565, 5875.206847075761, 5908.025175846109, 5922.585419020627, 5982.850428739316, 6060.575759696955, 5864.0, 5885.3820348862755, 5886.558508111994, 5887.234659494238, 5917.792877249276, 5825.650460972186, 5853.590248682722, 5853.824385268278, 5857.853553661193, 5859.326177489644, 5690.687012214368, 5807.186993820953, 5823.7379317931645, 5855.765769369348, 5894.498967587803, 6023.819285421444, 6039.124326972652, 6043.153958445733, 6053.213082299273, 6067.787627433018, 5703.116017017369, 5893.145493903446, 5908.7045647718305, 5912.260967379078, 5918.585699001201], "value": 5600.686557084378, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.2150283129101, 621.7823761683411, 639.3191251317344, 647.9902801457976, 660.1945626335869, 34279.0, 37496.62503374965, 44103.999999999956, 46112.538874611244, 46275.53724462754, 46271.305930410985, 46671.533284667144, 46705.532944670544, 46768.0, 46781.532184678144, 8426.452280601772, 9220.861687074683, 9355.859662105075, 9362.859557106649, 9374.765630859214, 8308.916910830905, 8576.0995095515, 8579.871301930445, 10719.78560428791, 11010.779784404307, 11077.446127693624, 11156.497957591928, 11158.944205278962, 11159.776804463905, 11161.609343672973, 10962.780744385107, 11033.337999720003, 11036.061934735557, 11040.171987100959, 11042.834357484644, 10879.456027198608, 10947.4526273686, 10947.890521094767, 10948.616798412017, 10954.671359859218, 44085.0, 44566.0, 44607.0, 44661.0, 44689.329660055126, 3791.7156213284115, 3799.696024318048, 3839.78881161536, 3925.568187499373, 4188.497380314351, 34444.82777586096, 34445.0, 34469.48295775565, 34699.82650086759, 34923.65076349255, 4418.3372494125815, 4476.955230447719, 4482.484514280834, 4591.1506371321075, 4601.447826260836, 352631.0, 353995.0, 354268.0, 355642.99999999744, 356411.0, 60509.69745151246, 60708.696456517435, 60805.39194608086, 60861.0, 60893.69553152206, 39719.0, 40953.79523102365, 41066.1786764263, 42810.35784463236, 64702.352976470575, 19246.4226073218, 19381.90309048446, 19400.805991940182, 19429.417117486497, 19433.416997489814, 15124.621884452758, 15233.923830380778, 15234.923825380802, 15248.923755381375, 15259.237038148049, 18003.549911252325, 18568.0, 19494.132051244036, 20370.898145509473, 20951.266705665228, 7139.714411423589, 7139.92860071403, 7200.567965922061, 7232.529885557422, 7318.670659820321, 988.6342053440129, 1041.6874937518714, 1126.985712858434, 1134.551852018448, 1219.3903048475763, 30820.24502114664, 33121.66878331234, 33177.66822331794, 33228.83385583057, 33253.66746332506, 1155.156735583029, 1176.7999440095034, 1201.3272567362305, 1229.5327775445453, 1264.3804535777588, 17321.826781732274, 17878.195481203376, 17890.731639025424, 17954.730679039825, 18046.819531804776, 532339.0, 537882.0, 539400.0, 539790.0, 542385.0, 60463.39536604666, 61052.389476105556, 61129.38870611326, 61173.388266116766, 61430.38569614336, 1419.496078891999, 1451.2308476507467, 1457.6283047822737, 1463.122126723957, 1472.4772705689327, 30393.39213215679, 32272.83863580667, 32414.0, 32417.351652966336, 32873.0, 50497.49502504928, 51259.74370128274, 51290.48709512857, 51382.74308628433, 51473.48526514837, 484252.8936776609, 485008.0, 485825.0, 486215.0, 488045.0, 26988.32529186786, 30094.976541938857, 30200.0, 31021.68978310188, 31054.068377948697, 40722.79638601788, 42552.787236063625, 43957.56042439662, 45538.58460615401, 52064.479355205964, 40978.59021409867, 42732.78633606812, 43325.35011974886, 43577.346339804295, 44148.33777493404, 42489.78755106204, 43011.0, 43502.782486087366, 44122.77938610415, 44597.0, 42919.356209657504, 43858.34212486879, 43958.56041439545, 44107.33838992482, 44131.11737765291, 36656.633433666386, 37855.43216851805, 38786.22427551377, 38951.512701905114, 39052.21895562016, 28517.857410712815, 28607.713922860505, 28782.280442989093, 28884.133475995754, 29352.853235733684], "value": 21211.11213600988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.46173743265047, 424.42278501238314, 427.3226935307537, 431.89418592444855, 443.56752166637534, 18791.812081879176, 19072.618547629037, 20448.977551122458, 20476.0, 20555.280565180223, 19586.0, 19611.509712257164, 19999.900000499976, 20127.698084528707, 20209.0, 5800.564957628174, 6049.213602231704, 6049.78825741097, 6058.454739073482, 6059.39406059395, 5921.934051870661, 6005.2193214882, 6008.399160083978, 6030.698465076751, 6097.634141951475, 5986.191864098357, 6031.6984150792505, 6032.487238584726, 6036.909446358308, 6037.849053773647, 5801.970990145043, 5966.37353077927, 6002.519798416138, 6039.184710064129, 6060.787872424444, 5445.918311225335, 5453.127499600045, 5456.863578410532, 5460.836174914759, 5461.208124821878, 24285.150019749224, 24440.26679199627, 24474.510509789703, 24668.259952201464, 24703.876480617662, 2489.0914816092177, 2639.366552027519, 2715.4704832557463, 2860.8426536540483, 2900.216941425824, 15426.46007389736, 15437.0, 15439.845601543953, 15503.922480387639, 15506.844931550653, 2083.5832833433374, 2118.5445129297123, 2120.6394912864694, 2120.650092734697, 2275.954480910389, 63151.68424157896, 67400.0, 69579.65210173967, 72608.0, 77523.0, 11977.461014254206, 12000.939995299968, 12118.454669539891, 12166.148369614219, 12366.134370594153, 17172.0, 17182.570435739206, 17204.569885752957, 17215.56961075983, 17222.483325500256, 8436.072032076467, 8846.292296616255, 8956.283497320199, 8958.776030599287, 9140.085991400807, 8859.335549833788, 8912.108789121166, 8924.598393072327, 9055.501947392957, 9105.40814847046, 5238.633295669343, 5275.446078161738, 5285.0, 5310.7610157543, 5380.085385484477, 2020.4241791089469, 2031.7866624004562, 2049.149602914774, 2063.2366024570706, 2131.8061885344255, 485.2842057964474, 977.5454413697522, 1054.9630762923261, 1060.2843080920502, 1075.387029393254, 12325.630231093082, 13280.203187808762, 13395.464181432633, 13400.865991339962, 13442.596722098353, 513.5403813586819, 515.2991930973873, 516.630609114485, 536.9033573956725, 550.4797965922165, 7045.7181712731945, 7172.031775710317, 7200.1359836818865, 7254.056972593529, 7280.526765760209, 519778.0, 526085.0, 527342.0, 533014.0, 534984.0, 78818.0, 79620.60189699015, 91540.54229728942, 91963.54018229866, 96507.51746241364, 1055.8785739640039, 1066.0565399621391, 1105.4859490337033, 1114.3202646385698, 1114.4093630375914, 18266.0, 18575.628487430447, 18713.719294210325, 18953.526161846064, 19117.13972871252, 40496.595034050464, 41640.375394369716, 41838.16323673449, 44989.77505112453, 45406.0, 52217.47782522126, 60995.69502152461, 61145.694271530134, 62138.0, 62833.37166628458, 5819.185314055951, 7422.703091876265, 8446.915530844613, 8771.728099425674, 9126.17864392208, 13190.0, 13350.132241404277, 13720.245386503839, 13928.303584820516, 14082.859171408154, 12056.0, 12131.575394861138, 12155.331456769962, 12160.087993400708, 12422.937885310515, 11894.821577676516, 11993.460294286604, 12117.515299387926, 12171.2697238166, 12456.190347627375, 11941.52233910634, 12481.687957801129, 12508.624741257778, 12742.936285318514, 12781.8082728761, 8937.261574636677, 9165.495897725688, 9171.44971301724, 9213.0, 9359.251259899327, 12311.815322770344, 12465.85916161953, 12583.119181657195, 12734.0, 12934.288614126313], "value": 9347.248756525158, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.555533339998, 1532.0041972717731, 1658.9502314930548, 1805.837474627285, 1806.3045727394958, 8754.0, 9468.43189408635, 9479.0, 9481.573329200186, 9489.098535639121, 7897.960510197456, 8131.552764597945, 8406.369522285822, 8649.481031138135, 9313.720588382343, 2377.5363804058215, 2389.6654468374354, 2392.3301475586863, 2393.9521209575796, 2395.161693407311, 2376.417777644471, 2394.1022116706235, 2395.796357309631, 2399.124319623342, 2401.5677178107935, 2395.341281147681, 2397.4725560376687, 2398.0767404549288, 2404.2066118181046, 2423.9151629692965, 2349.7532759060223, 2356.0693526057285, 2360.905563777447, 2366.692329997098, 2379.8334116611845, 2323.9070437182663, 2334.5447637710654, 2337.4506990857135, 2339.415146213447, 2346.4720437901506, 8869.334799890034, 9307.813843723088, 9376.109269619375, 9399.62401503939, 9434.05659434058, 5351.331083614548, 5523.143912693539, 5525.475079867406, 5527.917081243744, 5537.058700021006, 3422.5208470814105, 3463.809490478023, 3490.022793617791, 3491.5810102787573, 3521.6126226115107, 1189.9473408370577, 1195.713028873064, 1201.531402752927, 1209.4436559182698, 1226.8466441694789, 12784.360781960864, 12799.424025918855, 12837.1014029018, 12852.742945141139, 12858.164219325714, 4705.200115980291, 4734.95265047352, 4741.019620019853, 4887.315775791392, 4905.3132561441425, 10883.83674244887, 10890.0, 10892.40091794959, 10902.345859248471, 10937.343759374464, 5593.188987596775, 5593.720313984284, 5599.7480113394995, 5601.047821870292, 5601.831945041656, 4497.505274419811, 4639.118567472169, 4938.160512712848, 5028.145215313405, 5165.715885626251, 4190.910363305563, 4218.093109981369, 4284.700070995063, 4307.676924230633, 4346.130773845243, 1303.8696130387075, 1336.6992426704107, 1338.5047532413068, 1348.689801345696, 1353.5330311042674, 992.1071036067597, 1013.964511242103, 1016.3546148195801, 1022.8056669232822, 1038.7610849504656, 6242.969909964896, 6245.125682404467, 6247.250329960434, 6391.680415979183, 6476.190476190476, 462.87502374359406, 469.00570789925575, 472.6903877959942, 475.29893407224756, 475.370134571687, 4022.4368588397647, 4237.110206856533, 4369.0, 4432.401625780542, 4524.524924882906, 10962.506687198937, 11438.313701177956, 11449.59926402572, 11821.586244481401, 11839.467223974942, 11702.473388697528, 11704.180707350573, 11755.471003804849, 11756.05951523876, 11769.823452648217, 8558.657653693906, 8776.727374530657, 8787.121287871161, 8790.736277911801, 8799.340049496313, 6319.368063193828, 6511.2512061113575, 6537.836554086186, 6601.0, 6659.367360100783, 10476.842847357144, 10804.891951080388, 10892.237543371888, 10909.890901090888, 10927.235093543375, 10356.0, 10495.00297471739, 10688.732781680521, 10873.728156795827, 10897.945510272715, 7275.963620181865, 7515.6242187891485, 7945.165757595601, 8167.264946154874, 8368.874466883124, 8822.0, 9167.312451566026, 9173.908260917306, 9178.495182765013, 9189.632414703337, 7719.729809456642, 7863.606819659109, 8050.476719013245, 8152.225538573826, 8218.58907054633, 7615.162332143571, 7760.185180556186, 7946.364290856834, 7956.6419511123295, 7993.240642138988, 7084.787456376317, 7217.530860494051, 7328.0, 7328.267173282842, 7331.78004659861, 5470.6842572700925, 5568.276124103841, 5626.746796394254, 5643.209950606836, 5697.914531282117, 5439.292891924025, 5560.221568980265, 5580.330360356783, 5596.300462442195, 5627.943720562742], "value": 4767.027956200065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.90902365384994, 357.2854291417166, 358.226231340305, 360.8736942070274, 366.4375184092419, 5627.380988091307, 5659.377468478464, 5665.980123577754, 5677.772889084442, 5711.228984087152, 5592.7762889484475, 5614.129809879465, 5614.9719251403785, 5638.548916086714, 5676.403977582357, 4199.265128602506, 4391.4730232372, 4396.593090211134, 4403.669724770655, 4413.16149931512, 4383.211022016036, 4383.408239887606, 4393.2970724684055, 4406.096750166208, 4409.779511024452, 4363.4327537420095, 4365.454318210223, 4367.148406060804, 4367.71609845359, 4385.254506733863, 4075.572064933169, 4246.534945443817, 4328.350747387885, 4345.152695224432, 4389.890252743675, 3789.829457674411, 3801.7148713846295, 3808.619138086169, 3813.48517950076, 3826.8660596879254, 5437.918431223535, 5472.534834539029, 5475.178723191513, 5482.342118945712, 5484.862878428032, 4891.192953162724, 4945.579625731817, 4973.502649735034, 5033.572146367563, 5042.798288068473, 5793.8551536211935, 5819.912701309484, 5832.416758324176, 5846.356900740915, 5864.38423965486, 4270.73650964986, 4271.6582673386365, 4275.679324050678, 4305.763183024932, 4321.762303073329, 5699.0, 5887.970560147171, 5915.5859089863725, 5988.820335389945, 6075.544334174954, 4247.256730072225, 4275.422817919603, 4310.3103503439925, 4327.221100201994, 4365.869023929287, 4440.888977775581, 4448.266036104072, 4457.130859482435, 4463.509014008457, 4468.687191896536, 4913.680610760289, 5023.8492845214705, 5058.241263810458, 5069.138246498104, 5078.162103252996, 5854.677992710356, 5856.736446859902, 5875.353711091777, 5898.203742494794, 5941.574022234719, 1492.910425374481, 1503.9624009399633, 1515.8408367121515, 1525.184026545815, 1526.206372686219, 927.9675211367705, 970.1801977329145, 971.3540495570508, 981.1954197558076, 1006.1648831469862, 4991.47589503097, 5045.697258164522, 5065.379078694856, 5077.923831142536, 5108.2848401223855, 5273.762680679302, 5279.260903473517, 5300.416954135042, 5309.2301616266195, 5337.973310133502, 4882.6826256293225, 4985.476524964899, 5036.521530454601, 5044.798208071709, 5073.797048118107, 2801.067263588118, 2817.042205650089, 2824.759895408872, 2828.292926768308, 2835.844028578407, 5324.893502130013, 5343.70609616467, 5361.437049109865, 5369.66419607472, 5416.350037995466, 4975.850724478271, 5313.910648317085, 5325.9081888213595, 5363.758630861621, 5380.408155102936, 5325.523631511366, 5362.490563396471, 5372.543333816591, 5409.323109835964, 5420.972895135578, 5227.2420499028185, 5255.921161182662, 5261.6579922306455, 5263.447338029605, 5272.599173532973, 5294.682319060868, 5920.259967504062, 5992.370801065999, 5992.400759923973, 6034.0, 5973.0, 6092.786752463642, 6116.0, 6223.3465486123305, 6263.874722505616, 4306.2248795217165, 4308.418363520885, 4331.393604895254, 4340.175366680321, 4345.130973805189, 6054.0, 6054.9091763624465, 6057.487686205532, 6074.514038876968, 6083.69581520931, 5932.436418540232, 5941.821125697731, 5942.821715348547, 5945.970270148621, 6017.217761691128, 5932.0508718604815, 5959.284885813557, 5979.611325263844, 5982.940170598238, 5990.520758339409, 5598.356189038313, 5777.537796976148, 5795.861520095436, 5837.1244313351635, 5920.407959204045, 5912.320083190488, 5919.408059194218, 5922.223333000169, 5922.407759224043, 5934.525237980864, 5596.18855265976, 5772.5381969441505, 5788.0, 5813.651180929158, 5834.095715164156], "value": 4359.92801945289, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.5314084978588, 789.28175360422, 794.4557977785217, 836.6444261188993, 881.9162179592936, 17220.0, 17789.199486023143, 17808.0, 17832.375866844664, 17934.28262869485, 16039.278232479537, 16339.509814705578, 16364.099974501398, 16375.4268600599, 16397.344106235738, 700.7407259314061, 718.4791026505794, 838.7357982235607, 842.3219308456705, 870.1776820904248, 858.51064893011, 863.0592654007131, 866.0516734176099, 866.4844417571535, 866.6360128745941, 920.5305294299922, 920.9171174594285, 921.0375157959948, 922.3174850610534, 922.557172557173, 918.3342076994193, 919.0625561926864, 919.3013309884502, 919.4804935211585, 919.6735159018552, 670.3162773970571, 675.0582936803132, 675.6959368284291, 675.8850995330756, 675.9594424334507, 14785.26073696311, 14812.0, 14824.407023719146, 14844.183569903651, 14845.703085938329, 13046.869531304756, 13495.730085398336, 13894.0, 14114.71770564583, 15924.12417317047, 13929.233892135928, 14403.927980360137, 14465.638359041002, 14662.853371466363, 14963.700725985529, 4703.270992996091, 4710.69380490267, 4723.787429565644, 4755.381800365965, 4842.782074806642, 83899.0, 84287.57856210742, 84543.0, 84641.0, 84791.5760421194, 12423.875761242452, 12450.128491005724, 12588.937055314665, 12641.43113559892, 12751.238201109732, 22237.777622223894, 22607.660885086738, 22621.20825771123, 22711.20510782148, 22782.088716451155, 8729.0, 8775.824483510294, 8841.55792210387, 9014.323925705598, 9037.63849446028, 4909.5090490950615, 4990.326305948723, 5051.444341122474, 5056.089903817275, 5169.405518365361, 3441.0, 3538.5046093546925, 3574.803385813805, 3575.4636804479014, 3576.213233088716, 631.1511017681212, 635.8251480842713, 637.6142433827561, 637.7735903754101, 641.788209890735, 688.3288793426469, 691.8339598496325, 697.5709938387855, 704.7357241034612, 715.0525553641446, 8542.572871356408, 9565.665201717906, 10806.459677016117, 11096.611618593472, 11162.0, 758.5145506875604, 779.9415043871619, 786.5516655506307, 805.4603415711488, 809.7408829174608, 4804.663673542888, 4942.9258561121615, 5024.673396229234, 5051.873703157377, 5061.44324124346, 69958.65020674864, 70926.0, 85470.0, 120466.39766801285, 120934.0, 89516.10483895078, 101718.0, 103149.48425257826, 103585.48207259066, 104344.47827760813, 55835.72082139645, 70458.0, 88174.55912720524, 90434.54782726044, 91355.0, 9897.109260166331, 10189.694309170736, 10207.54066067014, 10411.1671066313, 10535.947320263349, 40535.3919691199, 40559.79720101499, 49210.75394623004, 55474.72262638661, 57498.0, 66062.0, 66764.0, 68098.0, 80122.0, 80458.19541804507, 13576.59270221895, 14886.404543818559, 14912.85087149158, 14949.77575336349, 15222.923885380502, 16577.254023568727, 16792.24434900408, 16814.915925420293, 17168.74246886273, 18147.0, 14227.430902763774, 14738.26308684583, 14938.626534336294, 15249.923750381176, 15418.152001640017, 13471.663208419868, 13575.0, 13670.0, 14111.364988575744, 14228.644283892985, 14089.577312680636, 15013.0, 15031.774523381939, 15051.397944082115, 15383.769243461133, 11220.943895280472, 11531.596394126502, 12081.577144800242, 12829.550965716155, 12985.740285194055, 12253.326067066397, 12389.194702344319, 12918.289494077915, 12927.676808079874, 13134.671633209246], "value": 7975.917648009504, "unit": "score"}], "name": "dromaeo_css", "value": 7542.350712671915, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 111.0, 111.0, 111.0, 112.0, 110.0, 110.0, 110.0, 109.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 124.0, 115.0, 159.0, 116.0, 117.0, 117.0, 121.0, 155.0, 118.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 160.0, 161.0, 160.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 123.0, 74.0, 76.0, 75.0, 76.0, 99.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 125.0, 82.0, 80.0, 82.0, 100.0, 81.0, 81.0, 105.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 49.0, 52.0, 50.0, 49.0, 50.0, 49.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 58.0, 58.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 76.0, 73.0, 74.0, 82.0, 73.0, 80.0, 73.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 129.0, 122.0, 129.0, 121.0, 117.0, 126.0, 121.0, 162.0, 120.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 154.0, 154.0, 151.0, 152.0, 154.0, 152.0, 152.0, 161.0, 156.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 62.0, 62.0, 61.0, 64.0, 60.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1455.5, "alertThreshold": 2.0}]} 01:42:26 INFO - Return code: 0 01:42:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:42:26 INFO - # TBPL SUCCESS # 01:42:26 INFO - Running post-action listener: _resource_record_post_action 01:42:26 INFO - [mozharness: 2017-01-09 09:42:26.071381Z] Finished run-tests step (success) 01:42:26 INFO - Running post-run listener: _resource_record_post_run 01:42:26 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 319041536; Read time: 48; Write time: 244764 01:42:26 INFO - TinderboxPrint: CPU usage
13.0% 01:42:26 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 48 01:42:26 INFO - TinderboxPrint: I/O write bytes / time
319,041,536 / 244,764 01:42:26 INFO - TinderboxPrint: CPU idle
8,253.9 (87.0%) 01:42:26 INFO - TinderboxPrint: CPU user
1,185.8 (12.5%) 01:42:26 INFO - TinderboxPrint: Swap in / out
0 / 0 01:42:26 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 100335616; Read time: 0; Write time: 103856 01:42:26 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 218705920; Read time: 0; Write time: 140908 01:42:26 INFO - Running post-run listener: _upload_blobber_files 01:42:26 INFO - Blob upload gear active. 01:42:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:42:26 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 01:42:26 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:42:26 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:42:27 INFO - (blobuploader) - INFO - Open directory for files ... 01:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:27 INFO - (blobuploader) - INFO - Done attempting. 01:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:42:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:30 INFO - (blobuploader) - INFO - Done attempting. 01:42:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:42:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:42:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:42:32 INFO - (blobuploader) - INFO - Done attempting. 01:42:32 INFO - (blobuploader) - INFO - Iteration through files over. 01:42:32 INFO - Return code: 0 01:42:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:42:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:42:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f40de0be122aa70065bfa2ea4cc7061c7783759e763ee0cfa58c6cb435b7caec54ef7ffcea0b3cb0c102672c9002cab8f3d14c77140a8a6b686284a1648ff419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5489d3601b039684a8f47819e1a0c6e4cb0cbf44e08e30c45f312e88d4ddebd76c16735319f010de89adca382f724701916c36522cd18203ca7a31d725fc1d62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db8ac3c47f2d1b01b979dcbf7d45ad663f1491d10f4dcfd40c6b35ff78dc39a90bbf31d847cefeb131496b118794534655d1e55ca39842daec5e5717945755d7"} 01:42:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:42:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:42:32 INFO - Contents: 01:42:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f40de0be122aa70065bfa2ea4cc7061c7783759e763ee0cfa58c6cb435b7caec54ef7ffcea0b3cb0c102672c9002cab8f3d14c77140a8a6b686284a1648ff419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5489d3601b039684a8f47819e1a0c6e4cb0cbf44e08e30c45f312e88d4ddebd76c16735319f010de89adca382f724701916c36522cd18203ca7a31d725fc1d62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db8ac3c47f2d1b01b979dcbf7d45ad663f1491d10f4dcfd40c6b35ff78dc39a90bbf31d847cefeb131496b118794534655d1e55ca39842daec5e5717945755d7"} 01:42:32 INFO - Running post-run listener: copy_logs_to_upload_dir 01:42:32 INFO - Copying logs to upload dir... 01:42:32 INFO - mkdir: /builds/slave/test/build/upload/logs 01:42:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.833145 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2017-01-09 01:42:31.404266) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 01:42:31.407568) ========= 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=920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717 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-Beta/sha512/f40de0be122aa70065bfa2ea4cc7061c7783759e763ee0cfa58c6cb435b7caec54ef7ffcea0b3cb0c102672c9002cab8f3d14c77140a8a6b686284a1648ff419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5489d3601b039684a8f47819e1a0c6e4cb0cbf44e08e30c45f312e88d4ddebd76c16735319f010de89adca382f724701916c36522cd18203ca7a31d725fc1d62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db8ac3c47f2d1b01b979dcbf7d45ad663f1491d10f4dcfd40c6b35ff78dc39a90bbf31d847cefeb131496b118794534655d1e55ca39842daec5e5717945755d7"} build_url:https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/Y2OxEeL-QHelt3NaSOY8Gw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f40de0be122aa70065bfa2ea4cc7061c7783759e763ee0cfa58c6cb435b7caec54ef7ffcea0b3cb0c102672c9002cab8f3d14c77140a8a6b686284a1648ff419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5489d3601b039684a8f47819e1a0c6e4cb0cbf44e08e30c45f312e88d4ddebd76c16735319f010de89adca382f724701916c36522cd18203ca7a31d725fc1d62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db8ac3c47f2d1b01b979dcbf7d45ad663f1491d10f4dcfd40c6b35ff78dc39a90bbf31d847cefeb131496b118794534655d1e55ca39842daec5e5717945755d7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 01:42:31.432761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:42:31.433075) ========= 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=920470bf432669ad7e3a5387000001ca-1483950298.407450-1711154717 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 01:42:31.516563) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 01:42:31.516865) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 01:42:31.517142) ========= ========= Total master_lag: 0.25 =========