builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1478913010.75 results: success (0) buildid: 20161111142920 builduid: 664d51ce73674da68f43596cec3846d0 revision: 3d380055aaedfda2da14f5aee37eda8079105387 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.750001) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.750420) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.750750) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.805890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.806238) ========= 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=17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-11 17:10:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-11-11 17:10:10 (113 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103197 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.939979) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.940259) ========= 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=17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017501 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.994817) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:10.995215) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3d380055aaedfda2da14f5aee37eda8079105387 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3d380055aaedfda2da14f5aee37eda8079105387 --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=17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-11 17:10:11,114 truncating revision to first 12 chars 2016-11-11 17:10:11,114 Setting DEBUG logging. 2016-11-11 17:10:11,114 attempt 1/10 2016-11-11 17:10:11,114 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3d380055aaed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-11 17:10:11,476 unpacking tar archive at: mozilla-aurora-3d380055aaed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.689863 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:11.698410) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:11.698725) ========= script_repo_revision: 3d380055aaedfda2da14f5aee37eda8079105387 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:11.699134) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:11.699402) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 17:10:11.712976) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-11-11 17:10:11.713259) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:10:11 INFO - MultiFileLogger online at 20161111 17:10:11 in /builds/slave/test 17:10:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:10:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:10:11 INFO - {'append_to_log': False, 17:10:11 INFO - 'base_work_dir': '/builds/slave/test', 17:10:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:10:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:10:11 INFO - 'branch': 'Mozilla-Aurora', 17:10:11 INFO - 'buildbot_json_path': 'buildprops.json', 17:10:11 INFO - 'config_files': ('talos/linux_config.py',), 17:10:11 INFO - 'default_actions': ('clobber', 17:10:11 INFO - 'read-buildbot-config', 17:10:11 INFO - 'download-and-extract', 17:10:11 INFO - 'populate-webroot', 17:10:11 INFO - 'create-virtualenv', 17:10:11 INFO - 'install', 17:10:11 INFO - 'run-tests'), 17:10:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:10:11 INFO - 'download_minidump_stackwalk': True, 17:10:11 INFO - 'download_symbols': 'ondemand', 17:10:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:10:11 INFO - 'tooltool.py': '/tools/tooltool.py', 17:10:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:10:11 INFO - '/tools/misc-python/virtualenv.py')}, 17:10:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:10:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:10:11 INFO - 'installer_path': 'installer.exe', 17:10:11 INFO - 'log_level': 'info', 17:10:11 INFO - 'log_name': 'talos', 17:10:11 INFO - 'log_to_console': True, 17:10:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:10:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:10:11 INFO - 'opt_config_files': (), 17:10:11 INFO - 'pip_index': False, 17:10:11 INFO - 'sps_profile': False, 17:10:11 INFO - 'sps_profile_interval': 0, 17:10:11 INFO - 'suite': 'dromaeojs', 17:10:11 INFO - 'system_bits': '32', 17:10:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:10:11 INFO - 'title': 'talos-linux64-ix-009', 17:10:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:10:11 INFO - 'use_talos_json': True, 17:10:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:10:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:10:11 INFO - 'work_dir': 'build'} 17:10:11 INFO - [mozharness: 2016-11-12 01:10:11.924074Z] Running clobber step. 17:10:11 INFO - Running pre-action listener: _resource_record_pre_action 17:10:11 INFO - Running main action method: clobber 17:10:11 INFO - rmtree: /builds/slave/test/build 17:10:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:10:12 INFO - Running post-action listener: _resource_record_post_action 17:10:12 INFO - [mozharness: 2016-11-12 01:10:12.377298Z] Finished clobber step (success) 17:10:12 INFO - [mozharness: 2016-11-12 01:10:12.377418Z] Running read-buildbot-config step. 17:10:12 INFO - Running pre-action listener: _resource_record_pre_action 17:10:12 INFO - Running main action method: read_buildbot_config 17:10:12 INFO - Using buildbot properties: 17:10:12 INFO - { 17:10:12 INFO - "project": "", 17:10:12 INFO - "product": "firefox", 17:10:12 INFO - "script_repo_revision": "production", 17:10:12 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:10:12 INFO - "repository": "", 17:10:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:10:12 INFO - "buildid": "20161111142920", 17:10:12 INFO - "pgo_build": "True", 17:10:12 INFO - "basedir": "/builds/slave/test", 17:10:12 INFO - "buildnumber": 887, 17:10:12 INFO - "slavename": "talos-linux64-ix-009", 17:10:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:10:12 INFO - "platform": "ubuntu64_hw", 17:10:12 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:10:12 INFO - "branch": "mozilla-aurora", 17:10:12 INFO - "repo_path": "releases/mozilla-aurora", 17:10:12 INFO - "revision": "3d380055aaedfda2da14f5aee37eda8079105387", 17:10:12 INFO - "stage_platform": "linux64-pgo", 17:10:12 INFO - "builduid": "664d51ce73674da68f43596cec3846d0", 17:10:12 INFO - "slavebuilddir": "test" 17:10:12 INFO - } 17:10:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 17:10:12 INFO - Running post-action listener: _resource_record_post_action 17:10:12 INFO - [mozharness: 2016-11-12 01:10:12.379855Z] Finished read-buildbot-config step (success) 17:10:12 INFO - [mozharness: 2016-11-12 01:10:12.379945Z] Running download-and-extract step. 17:10:12 INFO - Running pre-action listener: _resource_record_pre_action 17:10:12 INFO - Running main action method: download_and_extract 17:10:12 INFO - mkdir: /builds/slave/test/build/tests 17:10: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')]} 17:10:12 INFO - trying https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 17:10:12 INFO - Downloading https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 17:10:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:10:12 INFO - Downloaded 1361 bytes. 17:10:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 17:10:12 INFO - Using the following test package requirements: 17:10:12 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 17:10:12 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:10:12 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:10:12 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'jsshell-linux-x86_64.zip'], 17:10:12 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:10:12 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 17:10:12 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:10:12 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:10:12 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:10:12 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:10:12 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:10:12 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:10:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 17:10:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:10:12 INFO - Fetch https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 17:10:13 INFO - Expected file size: 21960801 17:10:17 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:10:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 17:10:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:10:17 INFO - Fetch https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 17:10:18 INFO - Expected file size: 21960801 17:10:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 17:10:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:10:23 INFO - Fetch https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 17:10:23 INFO - Expected file size: 14414089 17:10:25 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')]} 17:10:25 INFO - trying https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:10:25 INFO - Downloading https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:10:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:10:27 INFO - Downloaded 64067807 bytes. 17:10:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:10:27 INFO - mkdir: /builds/slave/test/properties 17:10:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:10:27 INFO - Writing to file /builds/slave/test/properties/build_url 17:10:27 INFO - Contents: 17:10:27 INFO - build_url:https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:10:28 INFO - Running post-action listener: _resource_record_post_action 17:10:28 INFO - Running post-action listener: set_extra_try_arguments 17:10:28 INFO - [mozharness: 2016-11-12 01:10:28.440433Z] Finished download-and-extract step (success) 17:10:28 INFO - [mozharness: 2016-11-12 01:10:28.440662Z] Running populate-webroot step. 17:10:28 INFO - Running pre-action listener: _resource_record_pre_action 17:10:28 INFO - Running main action method: populate_webroot 17:10:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:10:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:10:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:10:28 INFO - '--tppagecycles', 17:10:28 INFO - '7'], 17:10:28 INFO - 'tests': ['tsvgm']}}, 17:10:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:10:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:10:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:10:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:10:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:10:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:10:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['damp', 'tps']}, 17:10:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'tests': ['damp', 'tps']}, 17:10:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['dromaeo_dom']}, 17:10:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:10:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['basic_compositor_video']}, 17:10:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:10:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['a11yr', 17:10:28 INFO - 'ts_paint', 17:10:28 INFO - 'tpaint', 17:10:28 INFO - 'sessionrestore', 17:10:28 INFO - 'sessionrestore_no_auto_restore', 17:10:28 INFO - 'tabpaint']}, 17:10:28 INFO - 'other-e10s': {'tests': ['a11yr', 17:10:28 INFO - 'ts_paint', 17:10:28 INFO - 'tpaint', 17:10:28 INFO - 'sessionrestore', 17:10:28 INFO - 'sessionrestore_no_auto_restore', 17:10:28 INFO - 'tabpaint']}, 17:10:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:10:28 INFO - 'ts_paint', 17:10:28 INFO - 'tpaint', 17:10:28 INFO - 'sessionrestore', 17:10:28 INFO - 'sessionrestore_no_auto_restore', 17:10:28 INFO - 'tabpaint']}, 17:10:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:10:28 INFO - 'ts_paint', 17:10:28 INFO - 'tpaint', 17:10:28 INFO - 'sessionrestore', 17:10:28 INFO - 'sessionrestore_no_auto_restore', 17:10:28 INFO - 'tabpaint']}, 17:10:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['a11yr', 17:10:28 INFO - 'ts_paint', 17:10:28 INFO - 'tpaint', 17:10:28 INFO - 'sessionrestore', 17:10:28 INFO - 'sessionrestore_no_auto_restore', 17:10:28 INFO - 'tabpaint']}, 17:10:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['a11yr', 17:10:28 INFO - 'ts_paint', 17:10:28 INFO - 'tpaint', 17:10:28 INFO - 'sessionrestore', 17:10:28 INFO - 'sessionrestore_no_auto_restore', 17:10:28 INFO - 'tabpaint']}, 17:10:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['tsvgx', 17:10:28 INFO - 'tsvgr_opacity', 17:10:28 INFO - 'tart', 17:10:28 INFO - 'tscrollx', 17:10:28 INFO - 'cart']}, 17:10:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:10:28 INFO - 'tsvgr_opacity', 17:10:28 INFO - 'tart', 17:10:28 INFO - 'tscrollx', 17:10:28 INFO - 'cart']}, 17:10:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'talos_options': ['--disable-e10s'], 17:10:28 INFO - 'tests': ['tp5o']}, 17:10:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'tests': ['tp5o']}, 17:10:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'talos_options': ['--disable-e10s', 17:10:28 INFO - '--xperf_path', 17:10:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:10:28 INFO - 'tests': ['tp5n']}, 17:10:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:10:28 INFO - 'talos_options': ['--xperf_path', 17:10:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:10:28 INFO - 'tests': ['tp5n']}}, 17:10:28 INFO - 'talos.zip': {'path': '', 17:10:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:10:28 INFO - Running post-action listener: _resource_record_post_action 17:10:28 INFO - [mozharness: 2016-11-12 01:10:28.464544Z] Finished populate-webroot step (success) 17:10:28 INFO - [mozharness: 2016-11-12 01:10:28.464633Z] Running create-virtualenv step. 17:10:28 INFO - Running pre-action listener: _resource_record_pre_action 17:10:28 INFO - Running main action method: create_virtualenv 17:10:28 INFO - Creating virtualenv /builds/slave/test/build/venv 17:10:28 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 17:10:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:10:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:10:28 INFO - Using real prefix '/usr' 17:10:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:10:29 INFO - Installing distribute.............................................................................................................................................................................................done. 17:10:31 INFO - Installing pip.................done. 17:10:31 INFO - Return code: 0 17:10:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:10:31 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')]} 17:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:31 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')]} 17:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:31 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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:31 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 17:10:31 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 17:10:31 INFO - Using env: {'DISPLAY': ':0', 17:10:31 INFO - 'HOME': '/home/cltbld', 17:10:31 INFO - 'LANG': 'en_US.UTF-8', 17:10:31 INFO - 'LANGUAGE': 'en_US:en', 17:10:31 INFO - 'LOGNAME': 'cltbld', 17:10:31 INFO - 'MAIL': '/var/mail/cltbld', 17:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:31 INFO - 'MOZ_NO_REMOTE': '1', 17:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:31 INFO - 'NO_EM_RESTART': '1', 17:10:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:31 INFO - 'PWD': '/builds/slave/test', 17:10:31 INFO - 'SHELL': '/bin/bash', 17:10:31 INFO - 'SHLVL': '1', 17:10:31 INFO - 'TERM': 'linux', 17:10:31 INFO - 'TMOUT': '86400', 17:10:31 INFO - 'USER': 'cltbld', 17:10:31 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:31 INFO - '_': '/tools/buildbot/bin/python'} 17:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:31 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 17:10:31 INFO - Cleaning up... 17:10:31 INFO - Return code: 0 17:10:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:10:31 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')]} 17:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:31 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')]} 17:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:31 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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:31 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 17:10:31 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 17:10:31 INFO - Using env: {'DISPLAY': ':0', 17:10:31 INFO - 'HOME': '/home/cltbld', 17:10:31 INFO - 'LANG': 'en_US.UTF-8', 17:10:31 INFO - 'LANGUAGE': 'en_US:en', 17:10:31 INFO - 'LOGNAME': 'cltbld', 17:10:31 INFO - 'MAIL': '/var/mail/cltbld', 17:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:31 INFO - 'MOZ_NO_REMOTE': '1', 17:10:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:31 INFO - 'NO_EM_RESTART': '1', 17:10:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:31 INFO - 'PWD': '/builds/slave/test', 17:10:31 INFO - 'SHELL': '/bin/bash', 17:10:31 INFO - 'SHLVL': '1', 17:10:31 INFO - 'TERM': 'linux', 17:10:31 INFO - 'TMOUT': '86400', 17:10:31 INFO - 'USER': 'cltbld', 17:10:31 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:31 INFO - '_': '/tools/buildbot/bin/python'} 17:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:31 INFO - Downloading/unpacking psutil>=3.1.1 17:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:31 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 17:10:31 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 17:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:10:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:10:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:10:33 INFO - Installing collected packages: psutil 17:10:33 INFO - Running setup.py install for psutil 17:10:33 INFO - building 'psutil._psutil_linux' extension 17:10:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:10:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:10:33 INFO - building 'psutil._psutil_posix' extension 17:10:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:10:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:10:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:10:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:10:34 INFO - Successfully installed psutil 17:10:34 INFO - Cleaning up... 17:10:34 INFO - Return code: 0 17:10:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:10:34 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')]} 17:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:34 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')]} 17:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:34 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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:34 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 17:10:34 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 17:10:34 INFO - Using env: {'DISPLAY': ':0', 17:10:34 INFO - 'HOME': '/home/cltbld', 17:10:34 INFO - 'LANG': 'en_US.UTF-8', 17:10:34 INFO - 'LANGUAGE': 'en_US:en', 17:10:34 INFO - 'LOGNAME': 'cltbld', 17:10:34 INFO - 'MAIL': '/var/mail/cltbld', 17:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:34 INFO - 'MOZ_NO_REMOTE': '1', 17:10:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:34 INFO - 'NO_EM_RESTART': '1', 17:10:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:34 INFO - 'PWD': '/builds/slave/test', 17:10:34 INFO - 'SHELL': '/bin/bash', 17:10:34 INFO - 'SHLVL': '1', 17:10:34 INFO - 'TERM': 'linux', 17:10:34 INFO - 'TMOUT': '86400', 17:10:34 INFO - 'USER': 'cltbld', 17:10:34 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:34 INFO - '_': '/tools/buildbot/bin/python'} 17:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:10: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 17:10: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 17:10: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 17:10: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 17:10: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 17:10: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 17:10:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:10:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:10:36 INFO - Installing collected packages: mozsystemmonitor 17:10:36 INFO - Running setup.py install for mozsystemmonitor 17:10:36 INFO - Successfully installed mozsystemmonitor 17:10:36 INFO - Cleaning up... 17:10:36 INFO - Return code: 0 17:10:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:10: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')]} 17:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10: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')]} 17:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:10:36 INFO - Using env: {'DISPLAY': ':0', 17:10:36 INFO - 'HOME': '/home/cltbld', 17:10:36 INFO - 'LANG': 'en_US.UTF-8', 17:10:36 INFO - 'LANGUAGE': 'en_US:en', 17:10:36 INFO - 'LOGNAME': 'cltbld', 17:10:36 INFO - 'MAIL': '/var/mail/cltbld', 17:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:36 INFO - 'MOZ_NO_REMOTE': '1', 17:10:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:36 INFO - 'NO_EM_RESTART': '1', 17:10:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:36 INFO - 'PWD': '/builds/slave/test', 17:10:36 INFO - 'SHELL': '/bin/bash', 17:10:36 INFO - 'SHLVL': '1', 17:10:36 INFO - 'TERM': 'linux', 17:10:36 INFO - 'TMOUT': '86400', 17:10:36 INFO - 'USER': 'cltbld', 17:10:36 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:36 INFO - '_': '/tools/buildbot/bin/python'} 17:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:36 INFO - Downloading/unpacking jsonschema==2.5.1 17:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:10:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:38 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 17:10:38 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 17:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:38 INFO - Downloading functools32-3.2.3-2.tar.gz 17:10:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:10:38 INFO - warning: no files found matching '*.txt' 17:10:38 INFO - no previously-included directories found matching 'build' 17:10:38 INFO - no previously-included directories found matching 'dist' 17:10:38 INFO - no previously-included directories found matching '.git*' 17:10:38 INFO - Installing collected packages: jsonschema, functools32 17:10:38 INFO - Running setup.py install for functools32 17:10:38 INFO - warning: no files found matching '*.txt' 17:10:38 INFO - no previously-included directories found matching 'build' 17:10:38 INFO - no previously-included directories found matching 'dist' 17:10:38 INFO - no previously-included directories found matching '.git*' 17:10:38 INFO - Successfully installed jsonschema functools32 17:10:38 INFO - Cleaning up... 17:10:39 INFO - Return code: 0 17:10:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:10:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:10:39 INFO - Using env: {'DISPLAY': ':0', 17:10:39 INFO - 'HOME': '/home/cltbld', 17:10:39 INFO - 'LANG': 'en_US.UTF-8', 17:10:39 INFO - 'LANGUAGE': 'en_US:en', 17:10:39 INFO - 'LOGNAME': 'cltbld', 17:10:39 INFO - 'MAIL': '/var/mail/cltbld', 17:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:39 INFO - 'MOZ_NO_REMOTE': '1', 17:10:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:39 INFO - 'NO_EM_RESTART': '1', 17:10:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:39 INFO - 'PWD': '/builds/slave/test', 17:10:39 INFO - 'SHELL': '/bin/bash', 17:10:39 INFO - 'SHLVL': '1', 17:10:39 INFO - 'TERM': 'linux', 17:10:39 INFO - 'TMOUT': '86400', 17:10:39 INFO - 'USER': 'cltbld', 17:10:39 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:39 INFO - '_': '/tools/buildbot/bin/python'} 17:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:10:39 INFO - Cleaning up... 17:10:39 INFO - Return code: 0 17:10:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:10:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:10:39 INFO - Using env: {'DISPLAY': ':0', 17:10:39 INFO - 'HOME': '/home/cltbld', 17:10:39 INFO - 'LANG': 'en_US.UTF-8', 17:10:39 INFO - 'LANGUAGE': 'en_US:en', 17:10:39 INFO - 'LOGNAME': 'cltbld', 17:10:39 INFO - 'MAIL': '/var/mail/cltbld', 17:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:39 INFO - 'MOZ_NO_REMOTE': '1', 17:10:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:39 INFO - 'NO_EM_RESTART': '1', 17:10:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:39 INFO - 'PWD': '/builds/slave/test', 17:10:39 INFO - 'SHELL': '/bin/bash', 17:10:39 INFO - 'SHLVL': '1', 17:10:39 INFO - 'TERM': 'linux', 17:10:39 INFO - 'TMOUT': '86400', 17:10:39 INFO - 'USER': 'cltbld', 17:10:39 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:39 INFO - '_': '/tools/buildbot/bin/python'} 17:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:39 INFO - Downloading/unpacking blobuploader==1.2.4 17:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:41 INFO - Downloading blobuploader-1.2.4.tar.gz 17:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:10:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:10:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:42 INFO - Downloading docopt-0.6.1.tar.gz 17:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:10:42 INFO - Installing collected packages: blobuploader, requests, docopt 17:10:42 INFO - Running setup.py install for blobuploader 17:10:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:10:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:10:42 INFO - Running setup.py install for requests 17:10:42 INFO - Running setup.py install for docopt 17:10:42 INFO - Successfully installed blobuploader requests docopt 17:10:42 INFO - Cleaning up... 17:10:42 INFO - Return code: 0 17:10:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:42 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')]} 17:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:42 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')]} 17:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:42 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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:42 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 17:10:42 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 17:10:42 INFO - Using env: {'DISPLAY': ':0', 17:10:42 INFO - 'HOME': '/home/cltbld', 17:10:42 INFO - 'LANG': 'en_US.UTF-8', 17:10:42 INFO - 'LANGUAGE': 'en_US:en', 17:10:42 INFO - 'LOGNAME': 'cltbld', 17:10:42 INFO - 'MAIL': '/var/mail/cltbld', 17:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:42 INFO - 'MOZ_NO_REMOTE': '1', 17:10:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:42 INFO - 'NO_EM_RESTART': '1', 17:10:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:42 INFO - 'PWD': '/builds/slave/test', 17:10:42 INFO - 'SHELL': '/bin/bash', 17:10:42 INFO - 'SHLVL': '1', 17:10:42 INFO - 'TERM': 'linux', 17:10:42 INFO - 'TMOUT': '86400', 17:10:42 INFO - 'USER': 'cltbld', 17:10:42 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:42 INFO - '_': '/tools/buildbot/bin/python'} 17:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:10:43 INFO - Running setup.py (path:/tmp/pip-PzHRer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:10:43 INFO - Running setup.py (path:/tmp/pip-FGYwGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:10:43 INFO - Running setup.py (path:/tmp/pip-fTXFno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:10:43 INFO - Running setup.py (path:/tmp/pip-FdSr0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:10:43 INFO - Running setup.py (path:/tmp/pip-ADSBwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:10:43 INFO - Running setup.py (path:/tmp/pip-wDgFwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:10:43 INFO - Running setup.py (path:/tmp/pip-LSDF60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:10:43 INFO - Running setup.py (path:/tmp/pip-CwxfxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:10:43 INFO - Running setup.py (path:/tmp/pip-KgfUg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:10:43 INFO - Running setup.py (path:/tmp/pip-wDLzcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:10:43 INFO - Running setup.py (path:/tmp/pip-3FIuSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:10:43 INFO - Running setup.py (path:/tmp/pip-CxKs8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:10:43 INFO - Running setup.py (path:/tmp/pip-F3av2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:10:43 INFO - Running setup.py (path:/tmp/pip-FyZcvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:44 INFO - Running setup.py (path:/tmp/pip-4p7PCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:10:44 INFO - Running setup.py (path:/tmp/pip-B7VxWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:10:44 INFO - Running setup.py (path:/tmp/pip-tJ1gIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:10:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:10:44 INFO - Running setup.py install for manifestparser 17:10:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:10:44 INFO - Running setup.py install for mozcrash 17:10:44 INFO - Running setup.py install for mozdebug 17:10:44 INFO - Running setup.py install for mozdevice 17:10:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:10:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:10:44 INFO - Running setup.py install for mozfile 17:10:44 INFO - Running setup.py install for mozhttpd 17:10:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:10:44 INFO - Running setup.py install for mozinfo 17:10:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:10:44 INFO - Running setup.py install for mozInstall 17:10:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Running setup.py install for mozleak 17:10:45 INFO - Running setup.py install for mozlog 17:10:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Running setup.py install for moznetwork 17:10:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Running setup.py install for mozprocess 17:10:45 INFO - Running setup.py install for mozprofile 17:10:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Running setup.py install for mozrunner 17:10:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:10:45 INFO - Running setup.py install for mozscreenshot 17:10:45 INFO - Running setup.py install for moztest 17:10:45 INFO - Running setup.py install for mozversion 17:10:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:10:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:10:46 INFO - Cleaning up... 17:10:46 INFO - Return code: 0 17:10:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:46 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')]} 17:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:46 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')]} 17:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:46 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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:46 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 17:10:46 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 17:10:46 INFO - Using env: {'DISPLAY': ':0', 17:10:46 INFO - 'HOME': '/home/cltbld', 17:10:46 INFO - 'LANG': 'en_US.UTF-8', 17:10:46 INFO - 'LANGUAGE': 'en_US:en', 17:10:46 INFO - 'LOGNAME': 'cltbld', 17:10:46 INFO - 'MAIL': '/var/mail/cltbld', 17:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:46 INFO - 'MOZ_NO_REMOTE': '1', 17:10:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:46 INFO - 'NO_EM_RESTART': '1', 17:10:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:46 INFO - 'PWD': '/builds/slave/test', 17:10:46 INFO - 'SHELL': '/bin/bash', 17:10:46 INFO - 'SHLVL': '1', 17:10:46 INFO - 'TERM': 'linux', 17:10:46 INFO - 'TMOUT': '86400', 17:10:46 INFO - 'USER': 'cltbld', 17:10:46 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:46 INFO - '_': '/tools/buildbot/bin/python'} 17:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:10:46 INFO - Running setup.py (path:/tmp/pip-AVxwVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:10:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:10:46 INFO - Running setup.py (path:/tmp/pip-xo036E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:10:46 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)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:10:46 INFO - Running setup.py (path:/tmp/pip-gE0z7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:10:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:10:46 INFO - Running setup.py (path:/tmp/pip-xhfWE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:10:46 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)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:10:46 INFO - Running setup.py (path:/tmp/pip-GvaTkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:10:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:10:46 INFO - Running setup.py (path:/tmp/pip-PD9Dc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:10:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:10:46 INFO - Running setup.py (path:/tmp/pip-Cfh3n6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:10:46 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)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:10:46 INFO - Running setup.py (path:/tmp/pip-kettfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:10:46 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)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:10:46 INFO - Running setup.py (path:/tmp/pip-4pYFLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:10:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:10:46 INFO - Running setup.py (path:/tmp/pip-krqnL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:10:46 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)) 17:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:10:46 INFO - Running setup.py (path:/tmp/pip-UlBgSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:10:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:10:47 INFO - Running setup.py (path:/tmp/pip-oOf40A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:10:47 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)) 17:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:10:47 INFO - Running setup.py (path:/tmp/pip-mzw1Ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:10:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:10:47 INFO - Running setup.py (path:/tmp/pip-57lPlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:10:47 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)) 17:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:47 INFO - Running setup.py (path:/tmp/pip-eQuz7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:10:47 INFO - Running setup.py (path:/tmp/pip-gzm6H3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:10:47 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)) 17:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:10:47 INFO - Running setup.py (path:/tmp/pip-4mqxID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:10:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:10:47 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)) 17:10:47 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)) 17:10:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:10:47 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)) 17:10:47 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)) 17:10:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:47 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 17:10:47 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 17:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:49 INFO - Downloading blessings-1.6.tar.gz 17:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:10:49 INFO - Installing collected packages: blessings 17:10:49 INFO - Running setup.py install for blessings 17:10:49 INFO - Successfully installed blessings 17:10:49 INFO - Cleaning up... 17:10:49 INFO - Return code: 0 17:10:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:10:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:49 INFO - Reading from file tmpfile_stdout 17:10:49 INFO - Current package versions: 17:10:49 INFO - argparse == 1.2.1 17:10:49 INFO - blessings == 1.6 17:10:49 INFO - blobuploader == 1.2.4 17:10:49 INFO - docopt == 0.6.1 17:10:49 INFO - functools32 == 3.2.3-2 17:10:49 INFO - jsonschema == 2.5.1 17:10:49 INFO - manifestparser == 1.1 17:10:49 INFO - mozInstall == 1.12 17:10:49 INFO - mozcrash == 0.17 17:10:49 INFO - mozdebug == 0.1 17:10:49 INFO - mozdevice == 0.48 17:10:49 INFO - mozfile == 1.2 17:10:49 INFO - mozhttpd == 0.7 17:10:49 INFO - mozinfo == 0.9 17:10:49 INFO - mozleak == 0.1 17:10:49 INFO - mozlog == 3.4 17:10:49 INFO - moznetwork == 0.27 17:10:49 INFO - mozprocess == 0.23 17:10:49 INFO - mozprofile == 0.28 17:10:49 INFO - mozrunner == 6.12 17:10:49 INFO - mozscreenshot == 0.1 17:10:49 INFO - mozsystemmonitor == 0.3 17:10:49 INFO - moztest == 0.7 17:10:49 INFO - mozversion == 1.4 17:10:49 INFO - psutil == 3.1.1 17:10:49 INFO - requests == 1.2.3 17:10:49 INFO - wsgiref == 0.1.2 17:10:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:49 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')]} 17:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:49 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')]} 17:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:49 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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:49 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 17:10:49 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 17:10:49 INFO - Using env: {'DISPLAY': ':0', 17:10:49 INFO - 'HOME': '/home/cltbld', 17:10:49 INFO - 'LANG': 'en_US.UTF-8', 17:10:49 INFO - 'LANGUAGE': 'en_US:en', 17:10:49 INFO - 'LOGNAME': 'cltbld', 17:10:49 INFO - 'MAIL': '/var/mail/cltbld', 17:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:49 INFO - 'MOZ_NO_REMOTE': '1', 17:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:49 INFO - 'NO_EM_RESTART': '1', 17:10:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:49 INFO - 'PWD': '/builds/slave/test', 17:10:49 INFO - 'SHELL': '/bin/bash', 17:10:49 INFO - 'SHLVL': '1', 17:10:49 INFO - 'TERM': 'linux', 17:10:49 INFO - 'TMOUT': '86400', 17:10:49 INFO - 'USER': 'cltbld', 17:10:49 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:49 INFO - '_': '/tools/buildbot/bin/python'} 17:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:10:50 INFO - Cleaning up... 17:10:50 INFO - Return code: 0 17:10:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:10:50 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')]} 17:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:50 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')]} 17:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:50 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 0x7fa89ba96f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22a3ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, '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': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:50 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 17:10:50 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 17:10:50 INFO - Using env: {'DISPLAY': ':0', 17:10:50 INFO - 'HOME': '/home/cltbld', 17:10:50 INFO - 'LANG': 'en_US.UTF-8', 17:10:50 INFO - 'LANGUAGE': 'en_US:en', 17:10:50 INFO - 'LOGNAME': 'cltbld', 17:10:50 INFO - 'MAIL': '/var/mail/cltbld', 17:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:50 INFO - 'MOZ_NO_REMOTE': '1', 17:10:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:50 INFO - 'NO_EM_RESTART': '1', 17:10:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:50 INFO - 'PWD': '/builds/slave/test', 17:10:50 INFO - 'SHELL': '/bin/bash', 17:10:50 INFO - 'SHLVL': '1', 17:10:50 INFO - 'TERM': 'linux', 17:10:50 INFO - 'TMOUT': '86400', 17:10:50 INFO - 'USER': 'cltbld', 17:10:50 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:50 INFO - '_': '/tools/buildbot/bin/python'} 17:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:10:50 INFO - Cleaning up... 17:10:50 INFO - Return code: 0 17:10:50 INFO - Running post-action listener: _resource_record_post_action 17:10:50 INFO - Running post-action listener: _start_resource_monitoring 17:10:50 INFO - Starting resource monitoring. 17:10:50 INFO - [mozharness: 2016-11-12 01:10:50.361832Z] Finished create-virtualenv step (success) 17:10:50 INFO - [mozharness: 2016-11-12 01:10:50.362323Z] Running install step. 17:10:50 INFO - Running pre-action listener: _resource_record_pre_action 17:10:50 INFO - Running main action method: install 17:10:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:50 INFO - Reading from file tmpfile_stdout 17:10:50 INFO - Detecting whether we're running mozinstall >=1.0... 17:10:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:10:50 INFO - Reading from file tmpfile_stdout 17:10:50 INFO - Output received: 17:10:50 INFO - Usage: mozinstall [options] installer 17:10:50 INFO - Options: 17:10:50 INFO - -h, --help show this help message and exit 17:10:50 INFO - -d DEST, --destination=DEST 17:10:50 INFO - Directory to install application into. [default: 17:10:50 INFO - "/builds/slave/test"] 17:10:50 INFO - --app=APP Application being installed. [default: firefox] 17:10:50 INFO - mkdir: /builds/slave/test/build/application 17:10:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:10:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:11:07 INFO - Reading from file tmpfile_stdout 17:11:07 INFO - Output received: 17:11:07 INFO - /builds/slave/test/build/application/firefox/firefox 17:11:07 INFO - Running post-action listener: _resource_record_post_action 17:11:07 INFO - [mozharness: 2016-11-12 01:11:07.341602Z] Finished install step (success) 17:11:07 INFO - [mozharness: 2016-11-12 01:11:07.341846Z] Running run-tests step. 17:11:07 INFO - Running pre-action listener: _resource_record_pre_action 17:11:07 INFO - Running main action method: run_tests 17:11:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:11:07 INFO - Python 2.7.3 17:11:07 INFO - Return code: 0 17:11:07 INFO - grabbing minidump binary from tooltool 17:11:07 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')]} 17:11:07 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 0x22a0f60>, '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 0x22a1d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22a21d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:11:07 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 17:11:07 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 17:11:07 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 17:11:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:11:07 INFO - Return code: 0 17:11:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:11:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:11:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:11:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:11:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:11:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 17:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 17:11:07 INFO - Using env: {'DISPLAY': ':0', 17:11:07 INFO - 'HOME': '/home/cltbld', 17:11:07 INFO - 'LANG': 'en_US.UTF-8', 17:11:07 INFO - 'LANGUAGE': 'en_US:en', 17:11:07 INFO - 'LOGNAME': 'cltbld', 17:11:07 INFO - 'MAIL': '/var/mail/cltbld', 17:11:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:11:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:11:07 INFO - 'MOZ_NO_REMOTE': '1', 17:11:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:11:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:11:07 INFO - 'NO_EM_RESTART': '1', 17:11:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:11:07 INFO - 'PWD': '/builds/slave/test', 17:11:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:11:07 INFO - 'SHELL': '/bin/bash', 17:11:07 INFO - 'SHLVL': '1', 17:11:07 INFO - 'TERM': 'linux', 17:11:07 INFO - 'TMOUT': '86400', 17:11:07 INFO - 'USER': 'cltbld', 17:11:07 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952', 17:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:11:07 INFO - '_': '/tools/buildbot/bin/python'} 17:11:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 17:11:07 INFO - mozversion application_buildid: 20161111142920 17:11:07 INFO - mozversion application_changeset: 3d380055aaedfda2da14f5aee37eda8079105387 17:11:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:11:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:11:07 INFO - mozversion application_name: Firefox 17:11:07 INFO - mozversion application_remotingname: firefox-dev 17:11:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:11:07 INFO - mozversion application_vendor: Mozilla 17:11:07 INFO - mozversion application_version: 51.0a2 17:11:07 INFO - mozversion platform_buildid: 20161111142920 17:11:07 INFO - mozversion platform_changeset: 3d380055aaedfda2da14f5aee37eda8079105387 17:11:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:11:07 INFO - mozversion platform_version: 51.0a2 17:11:07 INFO - using testdate: 1478913067 17:11:07 INFO - actual date: 1478913067 17:11:07 INFO - starting webserver on 'localhost:57723' 17:11:07 INFO - SUITE-START | Running 2 tests 17:11:07 INFO - TEST-START | dromaeo_css 17:11:07 INFO - operating with platform_type : linux_ 17:11:07 INFO - Initialising browser for dromaeo_css test... 17:11:07 INFO - TEST-INFO | started process 22212 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGavT3/profile http://localhost:57723/getInfo.html) 17:11:11 INFO - PROCESS | 22212 | __metrics Screen width/height:1600/1200 17:11:11 INFO - PROCESS | 22212 | colorDepth:24 17:11:11 INFO - PROCESS | 22212 | Browser inner width/height: 1024/697 17:11:11 INFO - PROCESS | 22212 | __metrics 17:11:12 INFO - PROCESS | 22212 | 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" 17:11:12 INFO - PROCESS | 22212 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:11:12 INFO - PROCESS | 22212 | console.error: PushService: 17:11:12 INFO - PROCESS | 22212 | stateChangeProcessEnqueue: Error transitioning state 17:11:12 INFO - PROCESS | 22212 | UnknownError 17:11:13 INFO - TEST-INFO | 22212: exit 0 17:11:13 INFO - Browser initialized. 17:11:13 INFO - Running cycle 1/1 for dromaeo_css test... 17:11:13 INFO - TEST-INFO | started process 22301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGavT3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:11:24 INFO - PROCESS | 22301 | RSS: Main: 145129472 17:11:24 INFO - PROCESS | 22301 | 17:12:16 INFO - PROCESS | 22301 | 1478913136052 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] 17:14:25 INFO - PROCESS | 22301 | Cycle 1(1): loaded http://localhost:57723/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57723/tests/dromaeo/cssquery-ext.html) 17:14:26 INFO - PROCESS | 22301 | RSS: Main: 242593792 17:14:26 INFO - PROCESS | 22301 | 17:17:28 INFO - PROCESS | 22301 | Cycle 1(1): loaded http://localhost:57723/tests/dromaeo/cssquery-ext.html (next: http://localhost:57723/tests/dromaeo/cssquery-jquery.html) 17:17:29 INFO - PROCESS | 22301 | RSS: Main: 229826560 17:17:29 INFO - PROCESS | 22301 | 17:20:31 INFO - PROCESS | 22301 | Cycle 1(1): loaded http://localhost:57723/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57723/tests/dromaeo/cssquery-mootools.html) 17:20:31 INFO - PROCESS | 22301 | RSS: Main: 175304704 17:20:31 INFO - PROCESS | 22301 | 17:23:33 INFO - PROCESS | 22301 | Cycle 1(1): loaded http://localhost:57723/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57723/tests/dromaeo/cssquery-prototype.html) 17:23:34 INFO - PROCESS | 22301 | RSS: Main: 201641984 17:23:34 INFO - PROCESS | 22301 | 17:26:36 INFO - PROCESS | 22301 | Cycle 1(1): loaded http://localhost:57723/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57723/tests/dromaeo/cssquery-yui.html) 17:26:37 INFO - PROCESS | 22301 | RSS: Main: 177188864 17:26:37 INFO - PROCESS | 22301 | 17:29:39 INFO - PROCESS | 22301 | Cycle 1(1): loaded http://localhost:57723/tests/dromaeo/cssquery-yui.html (next: http://localhost:57723/tests/dromaeo/cssquery-dojo.html) 17:29:40 INFO - PROCESS | 22301 | RSS: Main: 271052800 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | __start_tp_report 17:29:40 INFO - PROCESS | 22301 | _x_x_mozilla_page_load 17:29:40 INFO - PROCESS | 22301 | _x_x_mozilla_page_load_details 17:29:40 INFO - PROCESS | 22301 | |i|pagename|runs| 17:29:40 INFO - PROCESS | 22301 | |0;dojo.html;3157.952630710539;3193.8722451101958;3204.2149673330036;3225.467797813361;3243.821589812561;5608.18681291213;5679.062954612489;5697.373288938224;5719.227904232927;5735.168400581908;5652;5727.198192253088;5742.368339482654;5754.971225143879;5774;5180.922286165701;5188.299579556749;5221.843344699665;5232.529072383485;5278.683279003253;5060.5951523878;5085.160948443502;5291.074062039147;5296.788128474856;5331.280277162572;5082.923756143661;5089.770960306795;5098.362704661918;5100.336956195708;5144.22836574515;5137.972405518893;5224.39919409269;5226.529612334908;5231.267622532849;5259.973700131475;4987.526185012418;5045.4702256262935;5119.769610367543;5135.922961155586;5174.353205849269;5419.105847535152;5425.9728701356635;5460.208269800856;5481.205225242317;5490.039243132436;5813.47678708914;5851.502622277111;5919.940800591982;5925.229720136354;5928.40715928408;5899.823005309848;5939.643621382731;5950.315713692938;5951.107333899907;5958.5828991970575;5481.671099733989;5510.476504732084;5542.61201715876;5546.91679624806;5581.553475721932;6120.051392034242;6123.387661233841;6124.050772130327;6125.142480052797;6126.08108783686;5753.942460575425;5888.028475301613;5888.764449422061;5896.970515147483;5902;5842.298924129132;5881.882362352729;5889.734961926638;5892.204552385373;6007.789727359609;5933;5933.58464907461;5950.2264705587995;5956.374580668968;5960.1059841023325;5865.061590145641;5928.703564821742;5929.673867937305;5932.2584676915385;5954.404559544011;4521.231390663529;4560.88597785058;4566.13243483737;4609.193391156601;4624.491305956342;4110.568390318974;4141.4409054777825;4142.730722503027;4150.875473735793;4162.7502349859105;5737.799177028867;5746.339170995389;5747.39652336507;5756.625819321731;5764.221830052973;5785.971070144622;5857.677827719516;5870.559708021916;5907.586468947218;6096.664683442454;5641.746121424546;5644.266245388156;5645.266115404971;5650.717464126859;5666.660000399989;5021.698698078054;5029.094762942632;5030.949690503048;5044.54599086084;5057.747112644353;6054;6109.541784366279;6115.510759139258;6169.907451388232;6182.567220294536;5889.293284805851;5958.0169272069625;5978.521718262442;6027.547933905063;6064.545159112996;6212.032332793077;6230.59501132425;6243;6243.250809902841;6251.0623406487575;5673.602847800615;5912.172295878494;5913.2608423947;5914.4381283777975;5951.523878089829;5885.5880088395115;5956.13636022783;6008.309044459768;6029.78895738647;6144.907826382519;5914.822555323347;5948.910766338422;5962.70186490665;5965.045592705146;5968.104784282393;5891.617044892068;5894.646321220741;5895.793647222326;5901.822945311647;5906.173135761082;5638.859028524155;5746.339170995389;5761;5836.416358364129;5865.149553314831;5930.940690593211;5933.970330148321;5940.079287710412;5943.227380440515;5998.9100163496705;5593.328800543961;5647.971760141173;5824.883502329845;5832.358440571449;5835.533157347317 17:29:40 INFO - PROCESS | 22301 | |1;ext.html;593.525179856115;625.4027403829342;646.9223693156822;665.773636963433;666.1905784471866;34452.31095378094;37433.81283093581;37454.62545374545;37500.624993750054;37522;38454.61545384545;46293.30560041602;47582.76208618961;47696.761516192455;52448.737756311166;9037.277017838574;9153.816923661556;9166.954165229165;9177.770555736093;9179.127982841619;8243.917560824404;9083.454992700426;9160.35877488576;9167.495787731672;9168.449893006407;8435.114312997108;10850.620228292011;11050.778984420307;11064.557417703281;11431.199816012882;10995.505202265917;11027;11084.612038578693;11109.44452777362;11116.944415277952;10784.083352915011;10894.564217431292;10895.346279223193;10899.891001089967;10903.29128606638;46042.76978615119;46186.538134618895;46240;46333;46568.767156164344;3512.455569386749;3659.7804131751914;3713.0160507465544;3725.497057897176;3740.831662575191;34749.6380038712;35141.472877906854;35209.82395088034;35332.82333588316;36140.81929590362;4603.792829322689;4653.883652908705;4703.43558772946;4715.622750179978;4794.880127996793;366774;368263;370059;370626;372642;66140.33859661438;66544.33455665382;66591;66599.66700166468;66613.666931666;38416.23167536633;39338;39613;42290.788546057076;42364.576354236066;16115.516534503984;16241.756373654405;16251.10618915971;16265.268062937197;16283.837161628233;12304.200226985216;12406.317652529016;12437.253764773961;12440.626781196579;12443.128980971245;19030.809691902905;19204.908552721954;20979.475513112295;21282.787172128392;21324.14703411846;6988.44092472601;7677.500962437423;7729.985305730769;7753.263439973193;7797;1060.5545670818274;1082.6156714366443;1092.9672109836717;1112.7496313329598;1113.9387333696725;34936;35211.82394088013;35468.93593192208;35484.64515354813;35560.64439355625;1143.3082984794246;1175.9647210583696;1219.975600488003;1268.765278423476;1277.2783377391745;17426.302947881944;17998.333025558568;18012.79777812186;18166.90916545435;18291.176897039666;613753;615280;618789;621299;622485;62123.37876621176;62202.68898655568;62748.372516275165;62794;62854.371456284855;1421.623269833495;1438.6331485471137;1438.6906815034608;1454.0984589554332;1468.5227301127177;31868.84065579657;33816.3236735259;34104.215311550615;34177.82911085428;34187.65812341845;54232.72883635556;54763.72618137043;54805.1779223304;55033.8724595665;55067;438005;438772;439083;441166;441310;25282.873585631954;27735.30661733473;28846.134615961553;30525.23686907845;30785.692143078282;42912.570874292105;43391;50446.24330635117;50750;50804.745976269885;42043.36934945917;42295.7885210572;43764.34353484764;44892;45452.77273613611;43023.56976430196;43289.567104328555;44671.55328446674;46167.76916115398;48916.51083489262;45958;46952.7652361736;47103.05793884172;47148;47249;38036.61963380442;40756;41102.38346424866;41138.38292425556;41241.381379279934;30366.544501832934;30631.2342191447;30867.536986945663;30873.382532348776;31344 17:29:40 INFO - PROCESS | 22301 | |2;jquery.html;383.9750416222947;399.2394488499408;412.20855956563406;416.70205802850967;426.18385791209835;14593.270336483172;16804.991700497965;20689.689654655154;21544.784552154473;22064.33806985789;19379.12793924273;19484.707729384034;19670.60658786827;20470.078846451906;20994;5739.282589676291;5935.198748168985;5985.401459854023;5996.130561068642;6219.346968568303;4077.2660921034203;6012.549058820584;6022.397760223987;6022.728977196015;6025.397460253984;4167.812173530544;4265.573442655741;4318.265894797876;6567.802965911031;6606.900896486556;5708.400617935098;6673.399394054534;6736.3937245647885;6745.865082698368;6755.898661520081;5828.5628577856405;5833.562482813806;5834.737436815311;5836.212111364954;5836.795712150097;13860.861391386159;17629.647407051787;24299.149529766375;24453.755462445326;24804.875975620005;2347;2416.516696660675;2437.6465412515086;2508.686414198225;2553.2126023319274;10572.101371383442;10663.733406664818;10822.512986915608;10951.890481095166;11088.778224435548;2054.7123402723632;2072.450800537859;2078.7505499340023;2083.614531311712;2091.1008266445424;66141.338586614;67340.66329668369;79399;85358.57320713357;89974.99999999935;11573.594924177612;11850.940745296219;11877.168598198043;11925.582604608971;12127.211731237618;16955.067471288952;17048.14759262032;17077.829221707623;17077.829221707623;17118.14409279531;8091.462622101782;8238.876416853753;8343.916560834436;8407.747767566983;8477.745667629857;7956.164602716747;7959.164287749702;8145.266925976691;8155.469894456842;8158.3065439438315;4940.629452791055;5099.59203263738;5125;5229.215617657382;5316.6544174628525;2037.7962203779502;2052.415061707406;2058.547119633678;2107.9367618971455;2117.1213946212456;891.31814162166;988.2692834549632;1031.9122874555596;1051.4059556350637;1104.18983686518;20632;21016.474588135417;21129.78870211309;21211.363659090253;21410.357689269345;1059.8834128245887;1070.2080460459204;1089.145021158387;1104.8342748587777;1105.4693747001484;10652.254342195973;11651.475683594259;11747.588834390755;11813;11819;505081;516624;525457;532000;534422;81302;102240;103725.48137259416;105863;108431.45784271028;1023.155896385485;1051.4742628685658;1061.968140955757;1072.6942821295893;1080.173667144635;18716.719249211546;18812.341568045602;18965.90517047406;19188;19198.264789521014;45841.54158458373;45971.30902570153;46846;47452.288215676104;49138.01723965429;59374.406255938615;59459.10811337747;60396.39603603908;62033;69171;8687.739367818975;8809.603567839335;8820.823583528165;8862.64549418016;9012.684556040507;11806.527738890349;13628.591142265748;13672.453101876212;13908.51320203788;14024.08843425174;11994;12548.937255313665;12666.366681665695;12760.936195318964;12855.678608034874;10897.509612067635;11830.290182589073;12050.397480126134;12330.32183229895;12481.188722732993;12859;12898.806517902427;12910;12928.353582321035;12978.286194259406;8688;8741.300695944436;8924.821503569763;9001.054889236535;9004.969214626506;11246.325220486797;12543.874561254635;12650.936745316214;12742.426590803621;13096.869031309565 17:29:40 INFO - PROCESS | 22301 | |3;mootools.html;1368.2748143483957;1522.3986525322498;1559.8128224613047;1697.5756060984754;1793.5785090503737;8191.713290034842;9102.408343457679;9370.250379969602;9370.297227707932;9649.662261820838;9375.95312023439;9464.290178236643;9517.619295228182;9534.90465095349;9539.141477267045;2464.9999999999955;2495.538325409796;2503.173952595648;2503.311589312935;2503.849769013865;2468.9753102469017;2500.712418071918;2503.561876671575;2504.511620234055;2507.3480894967347;2449.4121410861353;2461.729209786922;2494.239257026606;2495.3262619092834;2505.24842547236;2362.5038741864228;2373.8931748071377;2378.714554253492;2383.5471260460545;2392.784649381564;2353.9058437662643;2389.689340385739;2391.354334329721;2398.112698301622;2401.6157414813547;9305.860412093823;9368;9409.717708468757;9471.147596716328;9472.857907131398;5501.559875209973;5647.858803529944;5686.0617998030275;5693.28833895763;5697.743601537941;3579.2483578448555;3597.3704601694863;3655.232401195752;3656.5063716398477;3680.9447858282147;1101.9063379612742;1108.534415545473;1155.78040172367;1160.187868492059;1182.1665725663386;13170;13183.274919879403;13276.07067505275;13276.601701948957;13300.201987880759;4488.349189367524;4687.507811679725;4715.55202255792;4823.252395878644;4857.489963553847;10367.792644147075;10375.325603835727;10375.377477351383;10378.169746420268;10390.06489415941;5552.611317207838;5554.1113421853115;5557.249771280825;5559.833205003856;5561.721913904288;4595.9080818383445;4728.361671174347;4997.800087996439;5089.033083714083;5166.405863325691;3672.59601443841;3774.5659249186147;3785.470034195215;3837.9424308635394;3906.687465002793;1230.969225769363;1238.7708273969165;1250.5373011985441;1252.7995520716643;1262.450833887262;891.3582220801013;1000.799840031982;1005.3415013166388;1017.659084206799;1032.256775121911;5981.581289309706;6043.395660433922;6244.1570387997035;6395.456386207225;6401.391867772554;458.4452812097333;460.4290679557392;467.5791787391375;469.72286351053043;469.8519966210701;4197.160567886435;4266.573342665771;4329.891752706208;4397.164538737629;4418.624416924597;11502.94248528752;11946.163768536118;11995.820062699066;12009.399530023464;12055.095867809949;11822.467988940518;11860.584879529175;11900.523979040741;11910.166288359731;11932;8343.45767525109;8537.359698022548;8662.350323725746;8667.089955554577;8892.244159246537;6137.7851775187655;6198.535109866688;6325.841853953688;6355.269144048493;6446.129772480655;10870.184736144663;10947.616833410792;11022.173336999596;11215.49530271156;11282.94358528202;10910.072643825364;11180.385078820744;11198.272112312674;11199.66401007971;11263.380514071805;8265.421420500748;8343.707970221014;8387.66449342044;8390.496570205807;8420.705275315335;9038.548072596475;9051.456912585267;9067.319951003568;9094.590743416695;9108.089191080839;7865.646045927832;7888.329491993015;7954.482958607672;7963.243491868263;8063.153368896416;7876.369890408868;7901.565413902291;8035.437519373588;8114.715984940735;8184.54984975832;7640.579768112807;7654.617269136632;7672.846543068996;7679;7695.268949449794;5417.6478528895495;5533.529649979636;5563.721813909207;5593.0491816390495;5720.542356611378;5237.502437268454;5376.166694162254;5398.08232600451;5422.783088676567;5612.915806262991 17:29:40 INFO - PROCESS | 22301 | |4;prototype.html;335.1403649638677;341.3718757486226;348.66461452636406;349.85306171408007;350.2067816395864;5330.333708286465;5480.369757477886;5491.972540137303;5549.611527193093;5553.639013464122;5257.86855328617;5397.325334333208;5406.648567843087;5411.132099987003;5426.674399536021;4329.220740266751;4338.089001309713;4338.284183109783;4349.086691794727;4355.782210889459;4199.3700944858365;4302.397664326997;4348.543402942693;4349.565043495641;4362.32383980482;4288.421063156465;4301.526832048487;4302.763348015857;4304.741715497064;4316.330968699856;4293.957060429402;4301.74189548625;4304.6125848673455;4313.97843010783;4330.913381732347;3762.1800329613907;3874.9031274218087;3878.9030274243087;3879.3792993121247;3892.863749768773;5270.8155214567305;5275.736213189325;5287.070219369903;5318.893622127536;5382.9730851345885;4900.387451568554;4901.460839307673;4910.950890491121;4924.162892308316;4925.458199598042;5456.61803673743;5626.352969408529;5628.155776633497;5635.154726790973;5642.4357564243655;4217.283061879457;4217.599328063829;4236.2586547354085;4245.915081698379;4253.29820579604;5915.1718759373725;5922.022866227067;5925.703714814242;5927.881442371172;5951.434613711691;4205.17998990194;4359.738415695068;4389.890252743643;4392.5607439256455;4400.339949007611;4385.368778732734;4393.0994146199955;4395.978020109879;4399.64802815774;4403.669724770655;4907.68100073494;4930.926036109461;4932.506749325039;4935.235038569028;4939.3084968104495;5612.130119831432;5672.290963629546;5677.886442271214;5704.547044770706;5711.628744131701;1494.5292232946588;1510.554386455976;1511.2594828534159;1512.8335883052855;1516.6284260356063;944.1219665710818;961.8124465729115;976.5098997556345;989.737719504332;996.9850452243077;4814.470408255089;4815.855524334275;4821.300911367832;4834.734089625034;4863.2461968394955;5096.490350964948;5099.770510327045;5128.564072053917;5147.279380886679;5204.583633309327;4542.750148741785;4835.661503694778;4888.804447822047;4927.482614325516;4937.382827146606;2727.6085502341216;2732.7540521352776;2734.7128551502205;2736.233854520737;2742.5337692592307;5267.341582302212;5275.625968314274;5278.762455689503;5279.762410691528;5344.888768936248;5214.32213812202;5214.843554693365;5663.178839068312;6028.487578555872;6096.3903609639565;6041.365656606081;6077.817665470043;6099.512039036867;6146.262448506119;6154.415330543591;5630.930123276565;5930.229070220844;5932.466078052996;5933.732982015733;5946.167536544801;5811;5813.941860581509;5839.35767065614;5843.678597677169;5857.58996870215;6077.270727512727;6079.696015199311;6101.725422356093;6106.236720409949;6135.325114237519;3967.5635680075748;3981.5023122109737;4212.1365120149685;4246.193223287565;4250.829966801293;5827.912581311369;5841.357450680507;5845.649261044351;5845.707714614337;5849.327327357409;5566.749496272759;5688.744006519633;5688.914666279927;5707.5433965281845;5719.685417302088;5671.120976248688;5701.7434215461235;5710.371859095413;5711;5715.885402346503;5605.831825045255;5617.859553511195;5670.744816483186;5670.971645141748;5678.829635110953;5551.417101204315;5714.685692286798;5732.856678583069;5737.569682273763;5737.5983681141915;5584.301962254718;5598.384177740364;5599.972000139973;5603.579731520071;5610.270664813548 17:29:40 INFO - PROCESS | 22301 | |5;yui.html;779.3803925878926;820.4584973917216;837.7235512280948;875.8598624220126;884.1512148337598;16156.43452479162;18452.354167604135;18665.440036798886;18703.158357873876;18709.158087886044;16741.581460463494;16742.41401550946;16905.323787048535;17115.229814658338;17135.314587416487;848.5672307123381;880.9295256379506;882.5984177199391;882.8763973043779;885.3581153663607;883.2492381475759;886.8802711633912;888.0320450708726;889.937704360695;892.2906289499831;931.4876817750244;931.753085432361;933.3886304470583;934.2152591822868;934.5374039850284;918.150710592699;927.4528028463205;929.4702019848655;929.8000929800057;932.3892850183107;683.5556888022825;684.5653010338422;685.9005444210561;685.9862802743967;686.615495322616;15119.16844573548;15295.15876626785;15438.382464701399;15480.767788483181;15492.690146197127;14410.711785764226;14815.407383704638;14905.850941490555;14988.100713957198;15098.094114353175;12488.937555312255;12656.936715316364;12725.30010849403;12868;13011.089223754341;4066.715329926906;4088.7342322749223;4160.084781348099;4167.604077612622;4180.707350485467;86216.56891715502;88414;88942.55528722315;89309.55345223297;90978;11046.834297485544;11248.550057997752;11267.15496337778;11279.718007049889;11420.828687569694;22112.77887221107;22132.33602991913;22152.113915443526;22158.113675453125;22164.113435462725;8769.166929141722;9030;9072.637094516278;9250.583723732449;9572.473513956798;4878.707277563287;4945.678530895552;5015.749212539431;5034.924476132861;5182.144946083854;3645;3776.2258736958856;3783.981080094582;3807.2004878975727;3809;652.292262894755;663.0518358746941;683.111954459201;685.0922527650882;694.9339812717784;575.088484751665;587.2629849538802;589.4724221821446;653.0269897852261;675.7837492002512;9510.38182518134;9853.605855765833;9895.455749933679;10266.845997310047;10354.689359319082;549.8185598752402;656.9343065693392;665.5241502325764;699.0597646165899;704.8378872859168;4725.339633786149;4992.475790042066;5018.5985121190215;5022.924656130161;5057.8735531612;70377.64811175912;75727.62136189285;77093.2290677086;78453.21546784573;78762.6061869687;83405;91944;116311;119115;119941.40029299798;83879.58060209782;88789.55605221933;92238.53880730554;95828;96296;9774;9991.850122248317;11017.83473247918;11061.5022323994;11100.278481898651;53995.73002134964;54434.45565544294;55760.32719018436;56496.71751641216;56948.71525642345;79810;81049.59475202586;82494;84200.57899710462;85558.57220714106;13603.183808971493;13650.317484125953;13804.309784510533;13870.653233668836;13979.790303145257;15862.127582982584;15971.121588312755;16070.705505371605;16283.674326513641;16512.587185320463;14632.341544630304;15464.072155670696;15523.068615882632;15874.68250635004;16152.353905843634;14155.473105692494;14327.856721432652;14479.927600362353;14649.340779665154;14750.852491475378;14420.567382978528;14451.421943122577;14722.116672999655;15298.158601276591;15480.5355839325;11783.351915644722;11988.882117748508;12078.154529182873;12408.255504669749;13102.213867168;12382.442790074649;12779.80830287565;12829.93585032069;13010.414531346301;13113.934430328169 17:29:40 INFO - PROCESS | 22301 | __end_tp_report 17:29:40 INFO - PROCESS | 22301 | __start_cc_report 17:29:40 INFO - PROCESS | 22301 | _x_x_mozilla_cycle_collect,851 17:29:40 INFO - PROCESS | 22301 | __end_cc_report 17:29:40 INFO - PROCESS | 22301 | __startTimestamp1478914180083__endTimestamp 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | ------- Summary: start ------- 17:29:40 INFO - PROCESS | 22301 | Number of tests: 6 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | [#0] dojo.html Cycles:165 Average:5564.95 Median:5785.97 stddev:622.33 (10.8%) stddev-sans-first:594.90 17:29:40 INFO - PROCESS | 22301 | Values: 3158.0 3193.9 3204.2 3225.5 3243.8 5608.2 5679.1 5697.4 5719.2 5735.2 5652.0 5727.2 5742.4 5755.0 5774.0 5180.9 5188.3 5221.8 5232.5 5278.7 5060.6 5085.2 5291.1 5296.8 5331.3 5082.9 5089.8 5098.4 5100.3 5144.2 5138.0 5224.4 5226.5 5231.3 5260.0 4987.5 5045.5 5119.8 5135.9 5174.4 5419.1 5426.0 5460.2 5481.2 5490.0 5813.5 5851.5 5919.9 5925.2 5928.4 5899.8 5939.6 5950.3 5951.1 5958.6 5481.7 5510.5 5542.6 5546.9 5581.6 6120.1 6123.4 6124.1 6125.1 6126.1 5753.9 5888.0 5888.8 5897.0 5902.0 5842.3 5881.9 5889.7 5892.2 6007.8 5933.0 5933.6 5950.2 5956.4 5960.1 5865.1 5928.7 5929.7 5932.3 5954.4 4521.2 4560.9 4566.1 4609.2 4624.5 4110.6 4141.4 4142.7 4150.9 4162.8 5737.8 5746.3 5747.4 5756.6 5764.2 5786.0 5857.7 5870.6 5907.6 6096.7 5641.7 5644.3 5645.3 5650.7 5666.7 5021.7 5029.1 5030.9 5044.5 5057.7 6054.0 6109.5 6115.5 6169.9 6182.6 5889.3 5958.0 5978.5 6027.5 6064.5 6212.0 6230.6 6243.0 6243.3 6251.1 5673.6 5912.2 5913.3 5914.4 5951.5 5885.6 5956.1 6008.3 6029.8 6144.9 5914.8 5948.9 5962.7 5965.0 5968.1 5891.6 5894.6 5895.8 5901.8 5906.2 5638.9 5746.3 5761.0 5836.4 5865.1 5930.9 5934.0 5940.1 5943.2 5998.9 5593.3 5648.0 5824.9 5832.4 5835.5 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | [#1] ext.html Cycles:180 Average:64086.31 Median:38435.42 stddev:129498.31 (336.9%) stddev-sans-first:129773.84 17:29:40 INFO - PROCESS | 22301 | Values: 593.5 625.4 646.9 665.8 666.2 34452.3 37433.8 37454.6 37500.6 37522.0 38454.6 46293.3 47582.8 47696.8 52448.7 9037.3 9153.8 9167.0 9177.8 9179.1 8243.9 9083.5 9160.4 9167.5 9168.4 8435.1 10850.6 11050.8 11064.6 11431.2 10995.5 11027.0 11084.6 11109.4 11116.9 10784.1 10894.6 10895.3 10899.9 10903.3 46042.8 46186.5 46240.0 46333.0 46568.8 3512.5 3659.8 3713.0 3725.5 3740.8 34749.6 35141.5 35209.8 35332.8 36140.8 4603.8 4653.9 4703.4 4715.6 4794.9 366774.0 368263.0 370059.0 370626.0 372642.0 66140.3 66544.3 66591.0 66599.7 66613.7 38416.2 39338.0 39613.0 42290.8 42364.6 16115.5 16241.8 16251.1 16265.3 16283.8 12304.2 12406.3 12437.3 12440.6 12443.1 19030.8 19204.9 20979.5 21282.8 21324.1 6988.4 7677.5 7730.0 7753.3 7797.0 1060.6 1082.6 1093.0 1112.7 1113.9 34936.0 35211.8 35468.9 35484.6 35560.6 1143.3 1176.0 1220.0 1268.8 1277.3 17426.3 17998.3 18012.8 18166.9 18291.2 613753.0 615280.0 618789.0 621299.0 622485.0 62123.4 62202.7 62748.4 62794.0 62854.4 1421.6 1438.6 1438.7 1454.1 1468.5 31868.8 33816.3 34104.2 34177.8 34187.7 54232.7 54763.7 54805.2 55033.9 55067.0 438005.0 438772.0 439083.0 441166.0 441310.0 25282.9 27735.3 28846.1 30525.2 30785.7 42912.6 43391.0 50446.2 50750.0 50804.7 42043.4 42295.8 43764.3 44892.0 45452.8 43023.6 43289.6 44671.6 46167.8 48916.5 45958.0 46952.8 47103.1 47148.0 47249.0 38036.6 40756.0 41102.4 41138.4 41241.4 30366.5 30631.2 30867.5 30873.4 31344.0 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | [#2] jquery.html Cycles:180 Average:30642.14 Median:21310.86 stddev:86188.98 (404.4%) stddev-sans-first:86400.82 17:29:40 INFO - PROCESS | 22301 | Values: 384.0 399.2 412.2 416.7 426.2 14593.3 16805.0 20689.7 21544.8 22064.3 19379.1 19484.7 19670.6 20470.1 20994.0 5739.3 5935.2 5985.4 5996.1 6219.3 4077.3 6012.5 6022.4 6022.7 6025.4 4167.8 4265.6 4318.3 6567.8 6606.9 5708.4 6673.4 6736.4 6745.9 6755.9 5828.6 5833.6 5834.7 5836.2 5836.8 13860.9 17629.6 24299.1 24453.8 24804.9 2347.0 2416.5 2437.6 2508.7 2553.2 10572.1 10663.7 10822.5 10951.9 11088.8 2054.7 2072.5 2078.8 2083.6 2091.1 66141.3 67340.7 79399.0 85358.6 89975.0 11573.6 11850.9 11877.2 11925.6 12127.2 16955.1 17048.1 17077.8 17077.8 17118.1 8091.5 8238.9 8343.9 8407.7 8477.7 7956.2 7959.2 8145.3 8155.5 8158.3 4940.6 5099.6 5125.0 5229.2 5316.7 2037.8 2052.4 2058.5 2107.9 2117.1 891.3 988.3 1031.9 1051.4 1104.2 20632.0 21016.5 21129.8 21211.4 21410.4 1059.9 1070.2 1089.1 1104.8 1105.5 10652.3 11651.5 11747.6 11813.0 11819.0 505081.0 516624.0 525457.0 532000.0 534422.0 81302.0 102240.0 103725.5 105863.0 108431.5 1023.2 1051.5 1062.0 1072.7 1080.2 18716.7 18812.3 18965.9 19188.0 19198.3 45841.5 45971.3 46846.0 47452.3 49138.0 59374.4 59459.1 60396.4 62033.0 69171.0 8687.7 8809.6 8820.8 8862.6 9012.7 11806.5 13628.6 13672.5 13908.5 14024.1 11994.0 12548.9 12666.4 12760.9 12855.7 10897.5 11830.3 12050.4 12330.3 12481.2 12859.0 12898.8 12910.0 12928.4 12978.3 8688.0 8741.3 8924.8 9001.1 9005.0 11246.3 12543.9 12650.9 12742.4 13096.9 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | [#3] mootools.html Cycles:180 Average:6141.07 Median:2527.18 stddev:3587.98 (142.0%) stddev-sans-first:3580.12 17:29:40 INFO - PROCESS | 22301 | Values: 1368.3 1522.4 1559.8 1697.6 1793.6 8191.7 9102.4 9370.3 9370.3 9649.7 9376.0 9464.3 9517.6 9534.9 9539.1 2465.0 2495.5 2503.2 2503.3 2503.8 2469.0 2500.7 2503.6 2504.5 2507.3 2449.4 2461.7 2494.2 2495.3 2505.2 2362.5 2373.9 2378.7 2383.5 2392.8 2353.9 2389.7 2391.4 2398.1 2401.6 9305.9 9368.0 9409.7 9471.1 9472.9 5501.6 5647.9 5686.1 5693.3 5697.7 3579.2 3597.4 3655.2 3656.5 3680.9 1101.9 1108.5 1155.8 1160.2 1182.2 13170.0 13183.3 13276.1 13276.6 13300.2 4488.3 4687.5 4715.6 4823.3 4857.5 10367.8 10375.3 10375.4 10378.2 10390.1 5552.6 5554.1 5557.2 5559.8 5561.7 4595.9 4728.4 4997.8 5089.0 5166.4 3672.6 3774.6 3785.5 3837.9 3906.7 1231.0 1238.8 1250.5 1252.8 1262.5 891.4 1000.8 1005.3 1017.7 1032.3 5981.6 6043.4 6244.2 6395.5 6401.4 458.4 460.4 467.6 469.7 469.9 4197.2 4266.6 4329.9 4397.2 4418.6 11502.9 11946.2 11995.8 12009.4 12055.1 11822.5 11860.6 11900.5 11910.2 11932.0 8343.5 8537.4 8662.4 8667.1 8892.2 6137.8 6198.5 6325.8 6355.3 6446.1 10870.2 10947.6 11022.2 11215.5 11282.9 10910.1 11180.4 11198.3 11199.7 11263.4 8265.4 8343.7 8387.7 8390.5 8420.7 9038.5 9051.5 9067.3 9094.6 9108.1 7865.6 7888.3 7954.5 7963.2 8063.2 7876.4 7901.6 8035.4 8114.7 8184.5 7640.6 7654.6 7672.8 7679.0 7695.3 5417.6 5533.5 5563.7 5593.0 5720.5 5237.5 5376.2 5398.1 5422.8 5612.9 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | [#4] prototype.html Cycles:180 Average:4744.63 Median:5275.68 stddev:1377.55 (26.1%) stddev-sans-first:1341.07 17:29:40 INFO - PROCESS | 22301 | Values: 335.1 341.4 348.7 349.9 350.2 5330.3 5480.4 5492.0 5549.6 5553.6 5257.9 5397.3 5406.6 5411.1 5426.7 4329.2 4338.1 4338.3 4349.1 4355.8 4199.4 4302.4 4348.5 4349.6 4362.3 4288.4 4301.5 4302.8 4304.7 4316.3 4294.0 4301.7 4304.6 4314.0 4330.9 3762.2 3874.9 3878.9 3879.4 3892.9 5270.8 5275.7 5287.1 5318.9 5383.0 4900.4 4901.5 4911.0 4924.2 4925.5 5456.6 5626.4 5628.2 5635.2 5642.4 4217.3 4217.6 4236.3 4245.9 4253.3 5915.2 5922.0 5925.7 5927.9 5951.4 4205.2 4359.7 4389.9 4392.6 4400.3 4385.4 4393.1 4396.0 4399.6 4403.7 4907.7 4930.9 4932.5 4935.2 4939.3 5612.1 5672.3 5677.9 5704.5 5711.6 1494.5 1510.6 1511.3 1512.8 1516.6 944.1 961.8 976.5 989.7 997.0 4814.5 4815.9 4821.3 4834.7 4863.2 5096.5 5099.8 5128.6 5147.3 5204.6 4542.8 4835.7 4888.8 4927.5 4937.4 2727.6 2732.8 2734.7 2736.2 2742.5 5267.3 5275.6 5278.8 5279.8 5344.9 5214.3 5214.8 5663.2 6028.5 6096.4 6041.4 6077.8 6099.5 6146.3 6154.4 5630.9 5930.2 5932.5 5933.7 5946.2 5811.0 5813.9 5839.4 5843.7 5857.6 6077.3 6079.7 6101.7 6106.2 6135.3 3967.6 3981.5 4212.1 4246.2 4250.8 5827.9 5841.4 5845.6 5845.7 5849.3 5566.7 5688.7 5688.9 5707.5 5719.7 5671.1 5701.7 5710.4 5711.0 5715.9 5605.8 5617.9 5670.7 5671.0 5678.8 5551.4 5714.7 5732.9 5737.6 5737.6 5584.3 5598.4 5600.0 5603.6 5610.3 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - PROCESS | 22301 | [#5] yui.html Cycles:180 Average:21292.82 Median:4969.08 stddev:29344.20 (590.5%) stddev-sans-first:29386.09 17:29:40 INFO - PROCESS | 22301 | Values: 779.4 820.5 837.7 875.9 884.2 16156.4 18452.4 18665.4 18703.2 18709.2 16741.6 16742.4 16905.3 17115.2 17135.3 848.6 880.9 882.6 882.9 885.4 883.2 886.9 888.0 889.9 892.3 931.5 931.8 933.4 934.2 934.5 918.2 927.5 929.5 929.8 932.4 683.6 684.6 685.9 686.0 686.6 15119.2 15295.2 15438.4 15480.8 15492.7 14410.7 14815.4 14905.9 14988.1 15098.1 12488.9 12656.9 12725.3 12868.0 13011.1 4066.7 4088.7 4160.1 4167.6 4180.7 86216.6 88414.0 88942.6 89309.6 90978.0 11046.8 11248.6 11267.2 11279.7 11420.8 22112.8 22132.3 22152.1 22158.1 22164.1 8769.2 9030.0 9072.6 9250.6 9572.5 4878.7 4945.7 5015.7 5034.9 5182.1 3645.0 3776.2 3784.0 3807.2 3809.0 652.3 663.1 683.1 685.1 694.9 575.1 587.3 589.5 653.0 675.8 9510.4 9853.6 9895.5 10266.8 10354.7 549.8 656.9 665.5 699.1 704.8 4725.3 4992.5 5018.6 5022.9 5057.9 70377.6 75727.6 77093.2 78453.2 78762.6 83405.0 91944.0 116311.0 119115.0 119941.4 83879.6 88789.6 92238.5 95828.0 96296.0 9774.0 9991.9 11017.8 11061.5 11100.3 53995.7 54434.5 55760.3 56496.7 56948.7 79810.0 81049.6 82494.0 84200.6 85558.6 13603.2 13650.3 13804.3 13870.7 13979.8 15862.1 15971.1 16070.7 16283.7 16512.6 14632.3 15464.1 15523.1 15874.7 16152.4 14155.5 14327.9 14479.9 14649.3 14750.9 14420.6 14451.4 14722.1 15298.2 15480.5 11783.4 11988.9 12078.2 12408.3 13102.2 12382.4 12779.8 12829.9 13010.4 13113.9 17:29:40 INFO - PROCESS | 22301 | -------- Summary: end -------- 17:29:40 INFO - PROCESS | 22301 | 17:29:40 INFO - TEST-INFO | 22301: exit 0 17:29:40 INFO - TEST-OK | dromaeo_css | took 1113146ms 17:29:40 INFO - TEST-START | kraken 17:29:40 INFO - operating with platform_type : linux_ 17:29:40 INFO - Initialising browser for kraken test... 17:29:40 INFO - TEST-INFO | started process 22623 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVWakBq/profile http://localhost:57723/getInfo.html) 17:29:44 INFO - PROCESS | 22623 | __metrics Screen width/height:1600/1200 17:29:44 INFO - PROCESS | 22623 | colorDepth:24 17:29:44 INFO - PROCESS | 22623 | Browser inner width/height: 1024/697 17:29:44 INFO - PROCESS | 22623 | __metrics 17:29:45 INFO - PROCESS | 22623 | 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" 17:29:45 INFO - PROCESS | 22623 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:29:45 INFO - PROCESS | 22623 | console.error: PushService: 17:29:45 INFO - PROCESS | 22623 | stateChangeProcessEnqueue: Error transitioning state 17:29:45 INFO - PROCESS | 22623 | UnknownError 17:29:46 INFO - TEST-INFO | 22623: exit 0 17:29:46 INFO - Browser initialized. 17:29:46 INFO - Running cycle 1/1 for kraken test... 17:29:46 INFO - TEST-INFO | started process 22717 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVWakBq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:29:58 INFO - PROCESS | 22717 | RSS: Main: 144547840 17:29:58 INFO - PROCESS | 22717 | 17:30:00 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57723/tests/kraken/driver.html?testName=audio-beat-detection) 17:30:01 INFO - PROCESS | 22717 | RSS: Main: 186040320 17:30:01 INFO - PROCESS | 22717 | 17:30:04 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57723/tests/kraken/driver.html?testName=audio-dft) 17:30:04 INFO - PROCESS | 22717 | RSS: Main: 175951872 17:30:04 INFO - PROCESS | 22717 | 17:30:07 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57723/tests/kraken/driver.html?testName=audio-fft) 17:30:08 INFO - PROCESS | 22717 | RSS: Main: 266993664 17:30:08 INFO - PROCESS | 22717 | 17:30:10 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57723/tests/kraken/driver.html?testName=audio-oscillator) 17:30:10 INFO - PROCESS | 22717 | RSS: Main: 173473792 17:30:10 INFO - PROCESS | 22717 | 17:30:12 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57723/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:30:13 INFO - PROCESS | 22717 | RSS: Main: 174571520 17:30:13 INFO - PROCESS | 22717 | 17:30:16 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57723/tests/kraken/driver.html?testName=imaging-darkroom) 17:30:16 INFO - PROCESS | 22717 | RSS: Main: 210944000 17:30:16 INFO - PROCESS | 22717 | 17:30:20 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57723/tests/kraken/driver.html?testName=imaging-desaturate) 17:30:20 INFO - PROCESS | 22717 | RSS: Main: 210022400 17:30:20 INFO - PROCESS | 22717 | 17:30:23 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57723/tests/kraken/driver.html?testName=json-parse-financial) 17:30:24 INFO - PROCESS | 22717 | RSS: Main: 209498112 17:30:24 INFO - PROCESS | 22717 | 17:30:25 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57723/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:30:26 INFO - PROCESS | 22717 | RSS: Main: 171941888 17:30:26 INFO - PROCESS | 22717 | 17:30:28 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:30:28 INFO - PROCESS | 22717 | RSS: Main: 179007488 17:30:28 INFO - PROCESS | 22717 | 17:30:30 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:30:31 INFO - PROCESS | 22717 | RSS: Main: 188186624 17:30:31 INFO - PROCESS | 22717 | 17:30:33 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:30:34 INFO - PROCESS | 22717 | RSS: Main: 197177344 17:30:34 INFO - PROCESS | 22717 | 17:30:37 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:30:37 INFO - PROCESS | 22717 | RSS: Main: 193572864 17:30:37 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | Cycle 1(1): loaded http://localhost:57723/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57723/tests/kraken/driver.html?testName=ai-astar) 17:30:39 INFO - PROCESS | 22717 | RSS: Main: 245268480 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | __start_tp_report 17:30:39 INFO - PROCESS | 22717 | _x_x_mozilla_page_load 17:30:39 INFO - PROCESS | 22717 | _x_x_mozilla_page_load_details 17:30:39 INFO - PROCESS | 22717 | |i|pagename|runs| 17:30:39 INFO - PROCESS | 22717 | |0;ai-astar;108;110;110;111;109;111;109;110;109;110 17:30:39 INFO - PROCESS | 22717 | |1;audio-beat-detection;151;117;116;154;117;122;115;116;141;117 17:30:39 INFO - PROCESS | 22717 | |2;audio-dft;159;161;161;161;160;159;161;161;159;161 17:30:39 INFO - PROCESS | 22717 | |3;audio-fft;74;74;75;123;75;75;75;75;101;74 17:30:39 INFO - PROCESS | 22717 | |4;audio-oscillator;80;81;122;81;81;81;98;80;81;104 17:30:39 INFO - PROCESS | 22717 | |5;imaging-gaussian-blur;117;117;116;117;116;116;117;116;117;116 17:30:39 INFO - PROCESS | 22717 | |6;imaging-darkroom;146;146;145;146;147;145;145;146;146;146 17:30:39 INFO - PROCESS | 22717 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;96;95 17:30:39 INFO - PROCESS | 22717 | |8;json-parse-financial;52;51;49;50;50;52;50;49;50;50 17:30:39 INFO - PROCESS | 22717 | |9;json-stringify-tinderbox;59;59;59;59;60;60;58;58;62;60 17:30:39 INFO - PROCESS | 22717 | |10;stanford-crypto-aes;73;74;74;75;73;82;74;73;81;74 17:30:39 INFO - PROCESS | 22717 | |11;stanford-crypto-ccm;122;120;120;126;135;119;122;119;156;123 17:30:39 INFO - PROCESS | 22717 | |12;stanford-crypto-pbkdf2;151;152;153;159;150;150;151;151;149;155 17:30:39 INFO - PROCESS | 22717 | |13;stanford-crypto-sha256-iterative;62;61;61;61;60;62;61;67;60;60 17:30:39 INFO - PROCESS | 22717 | __end_tp_report 17:30:39 INFO - PROCESS | 22717 | __start_cc_report 17:30:39 INFO - PROCESS | 22717 | _x_x_mozilla_cycle_collect,1571 17:30:39 INFO - PROCESS | 22717 | __end_cc_report 17:30:39 INFO - PROCESS | 22717 | __startTimestamp1478914239563__endTimestamp 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | ------- Summary: start ------- 17:30:39 INFO - PROCESS | 22717 | Number of tests: 14 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:0.95 (0.9%) stddev-sans-first:0.78 17:30:39 INFO - PROCESS | 22717 | Values: 108.0 110.0 110.0 111.0 109.0 111.0 109.0 110.0 109.0 110.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:119.50 stddev:15.67 (13.1%) stddev-sans-first:13.91 17:30:39 INFO - PROCESS | 22717 | Values: 151.0 117.0 116.0 154.0 117.0 122.0 115.0 116.0 141.0 117.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.88 17:30:39 INFO - PROCESS | 22717 | Values: 159.0 161.0 161.0 161.0 160.0 159.0 161.0 161.0 159.0 161.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:16.60 (22.1%) stddev-sans-first:17.34 17:30:39 INFO - PROCESS | 22717 | Values: 74.0 74.0 75.0 123.0 75.0 75.0 75.0 75.0 101.0 74.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:81.00 stddev:14.44 (17.8%) stddev-sans-first:14.95 17:30:39 INFO - PROCESS | 22717 | Values: 80.0 81.0 122.0 81.0 81.0 81.0 98.0 80.0 81.0 104.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 17:30:39 INFO - PROCESS | 22717 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 117.0 116.0 117.0 116.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 17:30:39 INFO - PROCESS | 22717 | Values: 146.0 146.0 145.0 146.0 147.0 145.0 145.0 146.0 146.0 146.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:30:39 INFO - PROCESS | 22717 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:1.06 (2.1%) stddev-sans-first:0.93 17:30:39 INFO - PROCESS | 22717 | Values: 52.0 51.0 49.0 50.0 50.0 52.0 50.0 49.0 50.0 50.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.50 stddev:1.17 (2.0%) stddev-sans-first:1.24 17:30:39 INFO - PROCESS | 22717 | Values: 59.0 59.0 59.0 59.0 60.0 60.0 58.0 58.0 62.0 60.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:74.00 stddev:3.33 (4.5%) stddev-sans-first:3.43 17:30:39 INFO - PROCESS | 22717 | Values: 73.0 74.0 74.0 75.0 73.0 82.0 74.0 73.0 81.0 74.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:122.50 stddev:11.51 (9.4%) stddev-sans-first:12.10 17:30:39 INFO - PROCESS | 22717 | Values: 122.0 120.0 120.0 126.0 135.0 119.0 122.0 119.0 156.0 123.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.10 Median:151.50 stddev:2.96 (2.0%) stddev-sans-first:3.11 17:30:39 INFO - PROCESS | 22717 | Values: 151.0 152.0 153.0 159.0 150.0 150.0 151.0 151.0 149.0 155.0 17:30:39 INFO - PROCESS | 22717 | 17:30:39 INFO - PROCESS | 22717 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.00 stddev:2.07 (3.4%) stddev-sans-first:2.19 17:30:39 INFO - PROCESS | 22717 | Values: 62.0 61.0 61.0 61.0 60.0 62.0 61.0 67.0 60.0 60.0 17:30:39 INFO - PROCESS | 22717 | -------- Summary: end -------- 17:30:39 INFO - PROCESS | 22717 | 17:30:40 INFO - TEST-INFO | 22717: exit 0 17:30:40 INFO - TEST-OK | kraken | took 59844ms 17:30:40 INFO - SUITE-END | took 1172s 17:30:40 INFO - Completed test suite (00:19:33) 17:30:40 INFO - javascript benchmark 17:30:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3157.952630710539, 3193.8722451101958, 3204.2149673330036, 3225.467797813361, 3243.821589812561, 5608.18681291213, 5679.062954612489, 5697.373288938224, 5719.227904232927, 5735.168400581908, 5652.0, 5727.198192253088, 5742.368339482654, 5754.971225143879, 5774.0, 5180.922286165701, 5188.299579556749, 5221.843344699665, 5232.529072383485, 5278.683279003253, 5060.5951523878, 5085.160948443502, 5291.074062039147, 5296.788128474856, 5331.280277162572, 5082.923756143661, 5089.770960306795, 5098.362704661918, 5100.336956195708, 5144.22836574515, 5137.972405518893, 5224.39919409269, 5226.529612334908, 5231.267622532849, 5259.973700131475, 4987.526185012418, 5045.4702256262935, 5119.769610367543, 5135.922961155586, 5174.353205849269, 5419.105847535152, 5425.9728701356635, 5460.208269800856, 5481.205225242317, 5490.039243132436, 5813.47678708914, 5851.502622277111, 5919.940800591982, 5925.229720136354, 5928.40715928408, 5899.823005309848, 5939.643621382731, 5950.315713692938, 5951.107333899907, 5958.5828991970575, 5481.671099733989, 5510.476504732084, 5542.61201715876, 5546.91679624806, 5581.553475721932, 6120.051392034242, 6123.387661233841, 6124.050772130327, 6125.142480052797, 6126.08108783686, 5753.942460575425, 5888.028475301613, 5888.764449422061, 5896.970515147483, 5902.0, 5842.298924129132, 5881.882362352729, 5889.734961926638, 5892.204552385373, 6007.789727359609, 5933.0, 5933.58464907461, 5950.2264705587995, 5956.374580668968, 5960.1059841023325, 5865.061590145641, 5928.703564821742, 5929.673867937305, 5932.2584676915385, 5954.404559544011, 4521.231390663529, 4560.88597785058, 4566.13243483737, 4609.193391156601, 4624.491305956342, 4110.568390318974, 4141.4409054777825, 4142.730722503027, 4150.875473735793, 4162.7502349859105, 5737.799177028867, 5746.339170995389, 5747.39652336507, 5756.625819321731, 5764.221830052973, 5785.971070144622, 5857.677827719516, 5870.559708021916, 5907.586468947218, 6096.664683442454, 5641.746121424546, 5644.266245388156, 5645.266115404971, 5650.717464126859, 5666.660000399989, 5021.698698078054, 5029.094762942632, 5030.949690503048, 5044.54599086084, 5057.747112644353, 6054.0, 6109.541784366279, 6115.510759139258, 6169.907451388232, 6182.567220294536, 5889.293284805851, 5958.0169272069625, 5978.521718262442, 6027.547933905063, 6064.545159112996, 6212.032332793077, 6230.59501132425, 6243.0, 6243.250809902841, 6251.0623406487575, 5673.602847800615, 5912.172295878494, 5913.2608423947, 5914.4381283777975, 5951.523878089829, 5885.5880088395115, 5956.13636022783, 6008.309044459768, 6029.78895738647, 6144.907826382519, 5914.822555323347, 5948.910766338422, 5962.70186490665, 5965.045592705146, 5968.104784282393, 5891.617044892068, 5894.646321220741, 5895.793647222326, 5901.822945311647, 5906.173135761082, 5638.859028524155, 5746.339170995389, 5761.0, 5836.416358364129, 5865.149553314831, 5930.940690593211, 5933.970330148321, 5940.079287710412, 5943.227380440515, 5998.9100163496705, 5593.328800543961, 5647.971760141173, 5824.883502329845, 5832.358440571449, 5835.533157347317], "value": 5523.109976512302, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.525179856115, 625.4027403829342, 646.9223693156822, 665.773636963433, 666.1905784471866, 34452.31095378094, 37433.81283093581, 37454.62545374545, 37500.624993750054, 37522.0, 38454.61545384545, 46293.30560041602, 47582.76208618961, 47696.761516192455, 52448.737756311166, 9037.277017838574, 9153.816923661556, 9166.954165229165, 9177.770555736093, 9179.127982841619, 8243.917560824404, 9083.454992700426, 9160.35877488576, 9167.495787731672, 9168.449893006407, 8435.114312997108, 10850.620228292011, 11050.778984420307, 11064.557417703281, 11431.199816012882, 10995.505202265917, 11027.0, 11084.612038578693, 11109.44452777362, 11116.944415277952, 10784.083352915011, 10894.564217431292, 10895.346279223193, 10899.891001089967, 10903.29128606638, 46042.76978615119, 46186.538134618895, 46240.0, 46333.0, 46568.767156164344, 3512.455569386749, 3659.7804131751914, 3713.0160507465544, 3725.497057897176, 3740.831662575191, 34749.6380038712, 35141.472877906854, 35209.82395088034, 35332.82333588316, 36140.81929590362, 4603.792829322689, 4653.883652908705, 4703.43558772946, 4715.622750179978, 4794.880127996793, 366774.0, 368263.0, 370059.0, 370626.0, 372642.0, 66140.33859661438, 66544.33455665382, 66591.0, 66599.66700166468, 66613.666931666, 38416.23167536633, 39338.0, 39613.0, 42290.788546057076, 42364.576354236066, 16115.516534503984, 16241.756373654405, 16251.10618915971, 16265.268062937197, 16283.837161628233, 12304.200226985216, 12406.317652529016, 12437.253764773961, 12440.626781196579, 12443.128980971245, 19030.809691902905, 19204.908552721954, 20979.475513112295, 21282.787172128392, 21324.14703411846, 6988.44092472601, 7677.500962437423, 7729.985305730769, 7753.263439973193, 7797.0, 1060.5545670818274, 1082.6156714366443, 1092.9672109836717, 1112.7496313329598, 1113.9387333696725, 34936.0, 35211.82394088013, 35468.93593192208, 35484.64515354813, 35560.64439355625, 1143.3082984794246, 1175.9647210583696, 1219.975600488003, 1268.765278423476, 1277.2783377391745, 17426.302947881944, 17998.333025558568, 18012.79777812186, 18166.90916545435, 18291.176897039666, 613753.0, 615280.0, 618789.0, 621299.0, 622485.0, 62123.37876621176, 62202.68898655568, 62748.372516275165, 62794.0, 62854.371456284855, 1421.623269833495, 1438.6331485471137, 1438.6906815034608, 1454.0984589554332, 1468.5227301127177, 31868.84065579657, 33816.3236735259, 34104.215311550615, 34177.82911085428, 34187.65812341845, 54232.72883635556, 54763.72618137043, 54805.1779223304, 55033.8724595665, 55067.0, 438005.0, 438772.0, 439083.0, 441166.0, 441310.0, 25282.873585631954, 27735.30661733473, 28846.134615961553, 30525.23686907845, 30785.692143078282, 42912.570874292105, 43391.0, 50446.24330635117, 50750.0, 50804.745976269885, 42043.36934945917, 42295.7885210572, 43764.34353484764, 44892.0, 45452.77273613611, 43023.56976430196, 43289.567104328555, 44671.55328446674, 46167.76916115398, 48916.51083489262, 45958.0, 46952.7652361736, 47103.05793884172, 47148.0, 47249.0, 38036.61963380442, 40756.0, 41102.38346424866, 41138.38292425556, 41241.381379279934, 30366.544501832934, 30631.2342191447, 30867.536986945663, 30873.382532348776, 31344.0], "value": 21242.242949220006, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.9750416222947, 399.2394488499408, 412.20855956563406, 416.70205802850967, 426.18385791209835, 14593.270336483172, 16804.991700497965, 20689.689654655154, 21544.784552154473, 22064.33806985789, 19379.12793924273, 19484.707729384034, 19670.60658786827, 20470.078846451906, 20994.0, 5739.282589676291, 5935.198748168985, 5985.401459854023, 5996.130561068642, 6219.346968568303, 4077.2660921034203, 6012.549058820584, 6022.397760223987, 6022.728977196015, 6025.397460253984, 4167.812173530544, 4265.573442655741, 4318.265894797876, 6567.802965911031, 6606.900896486556, 5708.400617935098, 6673.399394054534, 6736.3937245647885, 6745.865082698368, 6755.898661520081, 5828.5628577856405, 5833.562482813806, 5834.737436815311, 5836.212111364954, 5836.795712150097, 13860.861391386159, 17629.647407051787, 24299.149529766375, 24453.755462445326, 24804.875975620005, 2347.0, 2416.516696660675, 2437.6465412515086, 2508.686414198225, 2553.2126023319274, 10572.101371383442, 10663.733406664818, 10822.512986915608, 10951.890481095166, 11088.778224435548, 2054.7123402723632, 2072.450800537859, 2078.7505499340023, 2083.614531311712, 2091.1008266445424, 66141.338586614, 67340.66329668369, 79399.0, 85358.57320713357, 89974.99999999935, 11573.594924177612, 11850.940745296219, 11877.168598198043, 11925.582604608971, 12127.211731237618, 16955.067471288952, 17048.14759262032, 17077.829221707623, 17077.829221707623, 17118.14409279531, 8091.462622101782, 8238.876416853753, 8343.916560834436, 8407.747767566983, 8477.745667629857, 7956.164602716747, 7959.164287749702, 8145.266925976691, 8155.469894456842, 8158.3065439438315, 4940.629452791055, 5099.59203263738, 5125.0, 5229.215617657382, 5316.6544174628525, 2037.7962203779502, 2052.415061707406, 2058.547119633678, 2107.9367618971455, 2117.1213946212456, 891.31814162166, 988.2692834549632, 1031.9122874555596, 1051.4059556350637, 1104.18983686518, 20632.0, 21016.474588135417, 21129.78870211309, 21211.363659090253, 21410.357689269345, 1059.8834128245887, 1070.2080460459204, 1089.145021158387, 1104.8342748587777, 1105.4693747001484, 10652.254342195973, 11651.475683594259, 11747.588834390755, 11813.0, 11819.0, 505081.0, 516624.0, 525457.0, 532000.0, 534422.0, 81302.0, 102240.0, 103725.48137259416, 105863.0, 108431.45784271028, 1023.155896385485, 1051.4742628685658, 1061.968140955757, 1072.6942821295893, 1080.173667144635, 18716.719249211546, 18812.341568045602, 18965.90517047406, 19188.0, 19198.264789521014, 45841.54158458373, 45971.30902570153, 46846.0, 47452.288215676104, 49138.01723965429, 59374.406255938615, 59459.10811337747, 60396.39603603908, 62033.0, 69171.0, 8687.739367818975, 8809.603567839335, 8820.823583528165, 8862.64549418016, 9012.684556040507, 11806.527738890349, 13628.591142265748, 13672.453101876212, 13908.51320203788, 14024.08843425174, 11994.0, 12548.937255313665, 12666.366681665695, 12760.936195318964, 12855.678608034874, 10897.509612067635, 11830.290182589073, 12050.397480126134, 12330.32183229895, 12481.188722732993, 12859.0, 12898.806517902427, 12910.0, 12928.353582321035, 12978.286194259406, 8688.0, 8741.300695944436, 8924.821503569763, 9001.054889236535, 9004.969214626506, 11246.325220486797, 12543.874561254635, 12650.936745316214, 12742.426590803621, 13096.869031309565], "value": 9655.449571078338, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.2748143483957, 1522.3986525322498, 1559.8128224613047, 1697.5756060984754, 1793.5785090503737, 8191.713290034842, 9102.408343457679, 9370.250379969602, 9370.297227707932, 9649.662261820838, 9375.95312023439, 9464.290178236643, 9517.619295228182, 9534.90465095349, 9539.141477267045, 2464.9999999999955, 2495.538325409796, 2503.173952595648, 2503.311589312935, 2503.849769013865, 2468.9753102469017, 2500.712418071918, 2503.561876671575, 2504.511620234055, 2507.3480894967347, 2449.4121410861353, 2461.729209786922, 2494.239257026606, 2495.3262619092834, 2505.24842547236, 2362.5038741864228, 2373.8931748071377, 2378.714554253492, 2383.5471260460545, 2392.784649381564, 2353.9058437662643, 2389.689340385739, 2391.354334329721, 2398.112698301622, 2401.6157414813547, 9305.860412093823, 9368.0, 9409.717708468757, 9471.147596716328, 9472.857907131398, 5501.559875209973, 5647.858803529944, 5686.0617998030275, 5693.28833895763, 5697.743601537941, 3579.2483578448555, 3597.3704601694863, 3655.232401195752, 3656.5063716398477, 3680.9447858282147, 1101.9063379612742, 1108.534415545473, 1155.78040172367, 1160.187868492059, 1182.1665725663386, 13170.0, 13183.274919879403, 13276.07067505275, 13276.601701948957, 13300.201987880759, 4488.349189367524, 4687.507811679725, 4715.55202255792, 4823.252395878644, 4857.489963553847, 10367.792644147075, 10375.325603835727, 10375.377477351383, 10378.169746420268, 10390.06489415941, 5552.611317207838, 5554.1113421853115, 5557.249771280825, 5559.833205003856, 5561.721913904288, 4595.9080818383445, 4728.361671174347, 4997.800087996439, 5089.033083714083, 5166.405863325691, 3672.59601443841, 3774.5659249186147, 3785.470034195215, 3837.9424308635394, 3906.687465002793, 1230.969225769363, 1238.7708273969165, 1250.5373011985441, 1252.7995520716643, 1262.450833887262, 891.3582220801013, 1000.799840031982, 1005.3415013166388, 1017.659084206799, 1032.256775121911, 5981.581289309706, 6043.395660433922, 6244.1570387997035, 6395.456386207225, 6401.391867772554, 458.4452812097333, 460.4290679557392, 467.5791787391375, 469.72286351053043, 469.8519966210701, 4197.160567886435, 4266.573342665771, 4329.891752706208, 4397.164538737629, 4418.624416924597, 11502.94248528752, 11946.163768536118, 11995.820062699066, 12009.399530023464, 12055.095867809949, 11822.467988940518, 11860.584879529175, 11900.523979040741, 11910.166288359731, 11932.0, 8343.45767525109, 8537.359698022548, 8662.350323725746, 8667.089955554577, 8892.244159246537, 6137.7851775187655, 6198.535109866688, 6325.841853953688, 6355.269144048493, 6446.129772480655, 10870.184736144663, 10947.616833410792, 11022.173336999596, 11215.49530271156, 11282.94358528202, 10910.072643825364, 11180.385078820744, 11198.272112312674, 11199.66401007971, 11263.380514071805, 8265.421420500748, 8343.707970221014, 8387.66449342044, 8390.496570205807, 8420.705275315335, 9038.548072596475, 9051.456912585267, 9067.319951003568, 9094.590743416695, 9108.089191080839, 7865.646045927832, 7888.329491993015, 7954.482958607672, 7963.243491868263, 8063.153368896416, 7876.369890408868, 7901.565413902291, 8035.437519373588, 8114.715984940735, 8184.54984975832, 7640.579768112807, 7654.617269136632, 7672.846543068996, 7679.0, 7695.268949449794, 5417.6478528895495, 5533.529649979636, 5563.721813909207, 5593.0491816390495, 5720.542356611378, 5237.502437268454, 5376.166694162254, 5398.08232600451, 5422.783088676567, 5612.915806262991], "value": 4781.980894498426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.1403649638677, 341.3718757486226, 348.66461452636406, 349.85306171408007, 350.2067816395864, 5330.333708286465, 5480.369757477886, 5491.972540137303, 5549.611527193093, 5553.639013464122, 5257.86855328617, 5397.325334333208, 5406.648567843087, 5411.132099987003, 5426.674399536021, 4329.220740266751, 4338.089001309713, 4338.284183109783, 4349.086691794727, 4355.782210889459, 4199.3700944858365, 4302.397664326997, 4348.543402942693, 4349.565043495641, 4362.32383980482, 4288.421063156465, 4301.526832048487, 4302.763348015857, 4304.741715497064, 4316.330968699856, 4293.957060429402, 4301.74189548625, 4304.6125848673455, 4313.97843010783, 4330.913381732347, 3762.1800329613907, 3874.9031274218087, 3878.9030274243087, 3879.3792993121247, 3892.863749768773, 5270.8155214567305, 5275.736213189325, 5287.070219369903, 5318.893622127536, 5382.9730851345885, 4900.387451568554, 4901.460839307673, 4910.950890491121, 4924.162892308316, 4925.458199598042, 5456.61803673743, 5626.352969408529, 5628.155776633497, 5635.154726790973, 5642.4357564243655, 4217.283061879457, 4217.599328063829, 4236.2586547354085, 4245.915081698379, 4253.29820579604, 5915.1718759373725, 5922.022866227067, 5925.703714814242, 5927.881442371172, 5951.434613711691, 4205.17998990194, 4359.738415695068, 4389.890252743643, 4392.5607439256455, 4400.339949007611, 4385.368778732734, 4393.0994146199955, 4395.978020109879, 4399.64802815774, 4403.669724770655, 4907.68100073494, 4930.926036109461, 4932.506749325039, 4935.235038569028, 4939.3084968104495, 5612.130119831432, 5672.290963629546, 5677.886442271214, 5704.547044770706, 5711.628744131701, 1494.5292232946588, 1510.554386455976, 1511.2594828534159, 1512.8335883052855, 1516.6284260356063, 944.1219665710818, 961.8124465729115, 976.5098997556345, 989.737719504332, 996.9850452243077, 4814.470408255089, 4815.855524334275, 4821.300911367832, 4834.734089625034, 4863.2461968394955, 5096.490350964948, 5099.770510327045, 5128.564072053917, 5147.279380886679, 5204.583633309327, 4542.750148741785, 4835.661503694778, 4888.804447822047, 4927.482614325516, 4937.382827146606, 2727.6085502341216, 2732.7540521352776, 2734.7128551502205, 2736.233854520737, 2742.5337692592307, 5267.341582302212, 5275.625968314274, 5278.762455689503, 5279.762410691528, 5344.888768936248, 5214.32213812202, 5214.843554693365, 5663.178839068312, 6028.487578555872, 6096.3903609639565, 6041.365656606081, 6077.817665470043, 6099.512039036867, 6146.262448506119, 6154.415330543591, 5630.930123276565, 5930.229070220844, 5932.466078052996, 5933.732982015733, 5946.167536544801, 5811.0, 5813.941860581509, 5839.35767065614, 5843.678597677169, 5857.58996870215, 6077.270727512727, 6079.696015199311, 6101.725422356093, 6106.236720409949, 6135.325114237519, 3967.5635680075748, 3981.5023122109737, 4212.1365120149685, 4246.193223287565, 4250.829966801293, 5827.912581311369, 5841.357450680507, 5845.649261044351, 5845.707714614337, 5849.327327357409, 5566.749496272759, 5688.744006519633, 5688.914666279927, 5707.5433965281845, 5719.685417302088, 5671.120976248688, 5701.7434215461235, 5710.371859095413, 5711.0, 5715.885402346503, 5605.831825045255, 5617.859553511195, 5670.744816483186, 5670.971645141748, 5678.829635110953, 5551.417101204315, 5714.685692286798, 5732.856678583069, 5737.569682273763, 5737.5983681141915, 5584.301962254718, 5598.384177740364, 5599.972000139973, 5603.579731520071, 5610.270664813548], "value": 4309.404303276152, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.3803925878926, 820.4584973917216, 837.7235512280948, 875.8598624220126, 884.1512148337598, 16156.43452479162, 18452.354167604135, 18665.440036798886, 18703.158357873876, 18709.158087886044, 16741.581460463494, 16742.41401550946, 16905.323787048535, 17115.229814658338, 17135.314587416487, 848.5672307123381, 880.9295256379506, 882.5984177199391, 882.8763973043779, 885.3581153663607, 883.2492381475759, 886.8802711633912, 888.0320450708726, 889.937704360695, 892.2906289499831, 931.4876817750244, 931.753085432361, 933.3886304470583, 934.2152591822868, 934.5374039850284, 918.150710592699, 927.4528028463205, 929.4702019848655, 929.8000929800057, 932.3892850183107, 683.5556888022825, 684.5653010338422, 685.9005444210561, 685.9862802743967, 686.615495322616, 15119.16844573548, 15295.15876626785, 15438.382464701399, 15480.767788483181, 15492.690146197127, 14410.711785764226, 14815.407383704638, 14905.850941490555, 14988.100713957198, 15098.094114353175, 12488.937555312255, 12656.936715316364, 12725.30010849403, 12868.0, 13011.089223754341, 4066.715329926906, 4088.7342322749223, 4160.084781348099, 4167.604077612622, 4180.707350485467, 86216.56891715502, 88414.0, 88942.55528722315, 89309.55345223297, 90978.0, 11046.834297485544, 11248.550057997752, 11267.15496337778, 11279.718007049889, 11420.828687569694, 22112.77887221107, 22132.33602991913, 22152.113915443526, 22158.113675453125, 22164.113435462725, 8769.166929141722, 9030.0, 9072.637094516278, 9250.583723732449, 9572.473513956798, 4878.707277563287, 4945.678530895552, 5015.749212539431, 5034.924476132861, 5182.144946083854, 3645.0, 3776.2258736958856, 3783.981080094582, 3807.2004878975727, 3809.0, 652.292262894755, 663.0518358746941, 683.111954459201, 685.0922527650882, 694.9339812717784, 575.088484751665, 587.2629849538802, 589.4724221821446, 653.0269897852261, 675.7837492002512, 9510.38182518134, 9853.605855765833, 9895.455749933679, 10266.845997310047, 10354.689359319082, 549.8185598752402, 656.9343065693392, 665.5241502325764, 699.0597646165899, 704.8378872859168, 4725.339633786149, 4992.475790042066, 5018.5985121190215, 5022.924656130161, 5057.8735531612, 70377.64811175912, 75727.62136189285, 77093.2290677086, 78453.21546784573, 78762.6061869687, 83405.0, 91944.0, 116311.0, 119115.0, 119941.40029299798, 83879.58060209782, 88789.55605221933, 92238.53880730554, 95828.0, 96296.0, 9774.0, 9991.850122248317, 11017.83473247918, 11061.5022323994, 11100.278481898651, 53995.73002134964, 54434.45565544294, 55760.32719018436, 56496.71751641216, 56948.71525642345, 79810.0, 81049.59475202586, 82494.0, 84200.57899710462, 85558.57220714106, 13603.183808971493, 13650.317484125953, 13804.309784510533, 13870.653233668836, 13979.790303145257, 15862.127582982584, 15971.121588312755, 16070.705505371605, 16283.674326513641, 16512.587185320463, 14632.341544630304, 15464.072155670696, 15523.068615882632, 15874.68250635004, 16152.353905843634, 14155.473105692494, 14327.856721432652, 14479.927600362353, 14649.340779665154, 14750.852491475378, 14420.567382978528, 14451.421943122577, 14722.116672999655, 15298.158601276591, 15480.5355839325, 11783.351915644722, 11988.882117748508, 12078.154529182873, 12408.255504669749, 13102.213867168, 12382.442790074649, 12779.80830287565, 12829.93585032069, 13010.414531346301, 13113.934430328169], "value": 7991.188694638569, "unit": "score"}], "name": "dromaeo_css", "value": 7559.151501092079, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 110.0, 111.0, 109.0, 111.0, 109.0, 110.0, 109.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 116.0, 154.0, 117.0, 122.0, 115.0, 116.0, 141.0, 117.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 161.0, 160.0, 159.0, 161.0, 161.0, 159.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 123.0, 75.0, 75.0, 75.0, 75.0, 101.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 122.0, 81.0, 81.0, 81.0, 98.0, 80.0, 81.0, 104.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 147.0, 145.0, 145.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 49.0, 50.0, 50.0, 52.0, 50.0, 49.0, 50.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 58.0, 58.0, 62.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 75.0, 73.0, 82.0, 74.0, 73.0, 81.0, 74.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 120.0, 120.0, 126.0, 135.0, 119.0, 122.0, 119.0, 156.0, 123.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 153.0, 159.0, 150.0, 150.0, 151.0, 151.0, 149.0, 155.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 61.0, 60.0, 62.0, 61.0, 67.0, 60.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1449.9, "alertThreshold": 2.0}]} 17:30:40 INFO - Return code: 0 17:30:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:30:40 INFO - # TBPL SUCCESS # 17:30:40 INFO - Running post-action listener: _resource_record_post_action 17:30:40 INFO - [mozharness: 2016-11-12 01:30:40.895749Z] Finished run-tests step (success) 17:30:40 INFO - Running post-run listener: _resource_record_post_run 17:30:40 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 369000448; Read time: 76; Write time: 274852 17:30:40 INFO - TinderboxPrint: CPU usage
13.0% 17:30:40 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 76 17:30:40 INFO - TinderboxPrint: I/O write bytes / time
369,000,448 / 274,852 17:30:40 INFO - TinderboxPrint: CPU idle
8,266.0 (87.0%) 17:30:40 INFO - TinderboxPrint: CPU user
1,186.5 (12.5%) 17:30:40 INFO - TinderboxPrint: Swap in / out
0 / 0 17:30:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 108298240; Read time: 0; Write time: 107404 17:30:40 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 249319424; Read time: 76; Write time: 165076 17:30:41 INFO - Running post-run listener: _upload_blobber_files 17:30:41 INFO - Blob upload gear active. 17:30:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:30:41 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 17:30:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:30:41 INFO - (blobuploader) - INFO - Open directory for files ... 17:30:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:30:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:30:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:42 INFO - (blobuploader) - INFO - Done attempting. 17:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:30:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:43 INFO - (blobuploader) - INFO - Done attempting. 17:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:30:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:47 INFO - (blobuploader) - INFO - Done attempting. 17:30:47 INFO - (blobuploader) - INFO - Iteration through files over. 17:30:47 INFO - Return code: 0 17:30:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:30:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:30:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9989813f218bbf21526e770855a034e45b622b4de74d8894bf3010e2bd89472c66fdc4db15cc8c58714ba7f4589f94ea9253847f3cf909c1692de3d3d9c6670", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5501d1b15e6949d741573e5f4577ca98d16dba3481a38296e5d6e982872d4f4bff60e83c39f7b9e4748d7d385a19a1f90b0d7c16e30cdbc71a00a9637b2c7515", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/562d559d3bab938695af312f1c4152424fa294dcf16fa516630214d4a414b5782ff6f79cefc5cd90d9f62424b9857b8d4234a17baebd74645d8e0a3b54dfcb63"} 17:30:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:30:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:30:47 INFO - Contents: 17:30:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9989813f218bbf21526e770855a034e45b622b4de74d8894bf3010e2bd89472c66fdc4db15cc8c58714ba7f4589f94ea9253847f3cf909c1692de3d3d9c6670", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5501d1b15e6949d741573e5f4577ca98d16dba3481a38296e5d6e982872d4f4bff60e83c39f7b9e4748d7d385a19a1f90b0d7c16e30cdbc71a00a9637b2c7515", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/562d559d3bab938695af312f1c4152424fa294dcf16fa516630214d4a414b5782ff6f79cefc5cd90d9f62424b9857b8d4234a17baebd74645d8e0a3b54dfcb63"} 17:30:47 INFO - Running post-run listener: copy_logs_to_upload_dir 17:30:47 INFO - Copying logs to upload dir... 17:30:47 INFO - mkdir: /builds/slave/test/build/upload/logs 17:30:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.572113 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-11-11 17:30:47.327165) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 17:30:47.335032) ========= 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=17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9989813f218bbf21526e770855a034e45b622b4de74d8894bf3010e2bd89472c66fdc4db15cc8c58714ba7f4589f94ea9253847f3cf909c1692de3d3d9c6670", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5501d1b15e6949d741573e5f4577ca98d16dba3481a38296e5d6e982872d4f4bff60e83c39f7b9e4748d7d385a19a1f90b0d7c16e30cdbc71a00a9637b2c7515", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/562d559d3bab938695af312f1c4152424fa294dcf16fa516630214d4a414b5782ff6f79cefc5cd90d9f62424b9857b8d4234a17baebd74645d8e0a3b54dfcb63"} build_url:https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/DFOWuS8DR1KTgudGqnaQCw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f9989813f218bbf21526e770855a034e45b622b4de74d8894bf3010e2bd89472c66fdc4db15cc8c58714ba7f4589f94ea9253847f3cf909c1692de3d3d9c6670", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5501d1b15e6949d741573e5f4577ca98d16dba3481a38296e5d6e982872d4f4bff60e83c39f7b9e4748d7d385a19a1f90b0d7c16e30cdbc71a00a9637b2c7515", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/562d559d3bab938695af312f1c4152424fa294dcf16fa516630214d4a414b5782ff6f79cefc5cd90d9f62424b9857b8d4234a17baebd74645d8e0a3b54dfcb63"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 17:30:47.362875) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:30:47.363210) ========= 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=17051fcd5208b27b50bedfeb000001dd-1478911978.445487-774547952 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 17:30:47.443446) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 17:30:47.446797) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 17:30:47.447103) ========= ========= Total master_lag: 0.22 =========