builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0299 starttime: 1470891266.97 results: success (0) buildid: 20160810194621 builduid: a021567bd5d04d45aa0754e1983d94ac revision: 517d1167728f4af8bad45cd679d80b509efad854 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:26.973699) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:26.974158) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:26.974427) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:27.000469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:27.000902) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-10 21:54:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 181M=0s 2016-08-10 21:54:27 (181 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128099 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:27.151397) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:27.151736) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.038516 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:27.210374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 21:54:27.210769) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 517d1167728f4af8bad45cd679d80b509efad854 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 517d1167728f4af8bad45cd679d80b509efad854 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-10 21:54:27,293 truncating revision to first 12 chars 2016-08-10 21:54:27,294 Setting DEBUG logging. 2016-08-10 21:54:27,294 attempt 1/10 2016-08-10 21:54:27,294 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/517d1167728f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 21:54:28,312 unpacking tar archive at: autoland-517d1167728f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.298195 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 21:54:28.531873) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:28.532221) ========= script_repo_revision: 517d1167728f4af8bad45cd679d80b509efad854 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:28.532722) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:28.533073) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 21:54:28.551170) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-08-10 21:54:28.551490) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 21:54:28 INFO - MultiFileLogger online at 20160810 21:54:28 in /builds/slave/test 21:54:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:54:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:54:28 INFO - {'append_to_log': False, 21:54:28 INFO - 'base_work_dir': '/builds/slave/test', 21:54:28 INFO - 'blob_upload_branch': 'Autoland', 21:54:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:54:28 INFO - 'branch': 'Autoland', 21:54:28 INFO - 'buildbot_json_path': 'buildprops.json', 21:54:28 INFO - 'config_files': ('talos/mac_config.py',), 21:54:28 INFO - 'default_actions': ('clobber', 21:54:28 INFO - 'read-buildbot-config', 21:54:28 INFO - 'download-and-extract', 21:54:28 INFO - 'populate-webroot', 21:54:28 INFO - 'create-virtualenv', 21:54:28 INFO - 'install', 21:54:28 INFO - 'run-tests'), 21:54:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:54:28 INFO - 'download_minidump_stackwalk': True, 21:54:28 INFO - 'download_symbols': 'ondemand', 21:54:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:54:28 INFO - 'tooltool.py': '/tools/tooltool.py', 21:54:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:54:28 INFO - '/tools/misc-python/virtualenv.py')}, 21:54:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:54:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:54:28 INFO - 'installer_path': 'installer.exe', 21:54:28 INFO - 'log_level': 'info', 21:54:28 INFO - 'log_name': 'talos', 21:54:28 INFO - 'log_to_console': True, 21:54:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:54:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:54:28 INFO - 'opt_config_files': (), 21:54:28 INFO - 'pip_index': False, 21:54:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:54:28 INFO - 'cmd': ('bash', 21:54:28 INFO - '-c', 21:54:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:54:28 INFO - 'enabled': True, 21:54:28 INFO - 'halt_on_failure': False, 21:54:28 INFO - 'name': 'check_screen_resolution'},), 21:54:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:54:28 INFO - 'cmd': ('bash', 21:54:28 INFO - '-c', 21:54:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:54:28 INFO - 'enabled': True, 21:54:28 INFO - 'halt_on_failure': False, 21:54:28 INFO - 'name': 'check_screen_resolution'},), 21:54:28 INFO - 'run_cmd_checks_enabled': True, 21:54:28 INFO - 'sps_profile': False, 21:54:28 INFO - 'sps_profile_interval': 0, 21:54:28 INFO - 'suite': 'dromaeojs-e10s', 21:54:28 INFO - 'system_bits': '32', 21:54:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:54:28 INFO - 'title': 't-yosemite-r7-0299', 21:54:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:54:28 INFO - 'use_talos_json': True, 21:54:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:54:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:54:28 INFO - 'work_dir': 'build'} 21:54:28 INFO - [mozharness: 2016-08-11 04:54:28.744977Z] Running clobber step. 21:54:28 INFO - Running pre-action listener: _resource_record_pre_action 21:54:28 INFO - Running main action method: clobber 21:54:28 INFO - rmtree: /builds/slave/test/build 21:54:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:54:30 INFO - Running post-action listener: _resource_record_post_action 21:54:30 INFO - [mozharness: 2016-08-11 04:54:30.669800Z] Finished clobber step (success) 21:54:30 INFO - [mozharness: 2016-08-11 04:54:30.669927Z] Running read-buildbot-config step. 21:54:30 INFO - Running pre-action listener: _resource_record_pre_action 21:54:30 INFO - Running main action method: read_buildbot_config 21:54:30 INFO - Using buildbot properties: 21:54:30 INFO - { 21:54:30 INFO - "project": "", 21:54:30 INFO - "product": "firefox", 21:54:30 INFO - "script_repo_revision": "production", 21:54:30 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 21:54:30 INFO - "repository": "", 21:54:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 21:54:30 INFO - "buildid": "20160810194621", 21:54:30 INFO - "pgo_build": "False", 21:54:30 INFO - "basedir": "/builds/slave/test", 21:54:30 INFO - "buildnumber": 124, 21:54:30 INFO - "slavename": "t-yosemite-r7-0299", 21:54:30 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 21:54:30 INFO - "platform": "yosemite_r7", 21:54:30 INFO - "scheduler": "tests-autoland-macosx64-talos", 21:54:30 INFO - "branch": "autoland", 21:54:30 INFO - "repo_path": "integration/autoland", 21:54:30 INFO - "revision": "517d1167728f4af8bad45cd679d80b509efad854", 21:54:30 INFO - "stage_platform": "macosx64", 21:54:30 INFO - "builduid": "a021567bd5d04d45aa0754e1983d94ac", 21:54:30 INFO - "slavebuilddir": "test" 21:54:30 INFO - } 21:54:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 21:54:30 INFO - Running post-action listener: _resource_record_post_action 21:54:30 INFO - [mozharness: 2016-08-11 04:54:30.673370Z] Finished read-buildbot-config step (success) 21:54:30 INFO - [mozharness: 2016-08-11 04:54:30.673508Z] Running download-and-extract step. 21:54:30 INFO - Running pre-action listener: _resource_record_pre_action 21:54:30 INFO - Running main action method: download_and_extract 21:54:30 INFO - mkdir: /builds/slave/test/build/tests 21:54:30 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')]} 21:54:30 INFO - trying https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 21:54:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:54:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 21:54:31 INFO - Downloaded 1199 bytes. 21:54:31 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 21:54:31 INFO - Using the following test package requirements: 21:54:31 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:54:31 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 21:54:31 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 21:54:31 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'jsshell-mac.zip'], 21:54:31 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 21:54:31 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 21:54:31 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 21:54:31 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 21:54:31 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 21:54:31 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 21:54:31 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 21:54:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:54: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')]} 21:54:31 INFO - trying https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:54:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:54:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:54:32 INFO - Downloaded 16328337 bytes. 21:54:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:54:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:54:35 INFO - Return code: 0 21:54:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:54:35 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')]} 21:54:35 INFO - trying https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:54:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 21:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:54:36 INFO - Downloaded 16328337 bytes. 21:54:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:54:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:54:40 INFO - Return code: 0 21:54:40 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')]} 21:54:40 INFO - trying https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:54:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 21:54:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:54:40 INFO - Downloaded 14413719 bytes. 21:54:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:54:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:54:41 INFO - Return code: 0 21:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:41 INFO - trying https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:54:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 21:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 21:54:44 INFO - Downloaded 110911009 bytes. 21:54:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:54:44 INFO - mkdir: /builds/slave/test/properties 21:54:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:54:44 INFO - Writing to file /builds/slave/test/properties/build_url 21:54:44 INFO - Contents: 21:54:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 21:54:44 INFO - Running post-action listener: _resource_record_post_action 21:54:44 INFO - Running post-action listener: set_extra_try_arguments 21:54:44 INFO - [mozharness: 2016-08-11 04:54:44.745455Z] Finished download-and-extract step (success) 21:54:44 INFO - [mozharness: 2016-08-11 04:54:44.745595Z] Running populate-webroot step. 21:54:44 INFO - Running pre-action listener: _resource_record_pre_action 21:54:44 INFO - Running main action method: populate_webroot 21:54:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:54:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:54:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:54:44 INFO - '--tppagecycles', 21:54:44 INFO - '7'], 21:54:44 INFO - 'tests': ['tsvgm']}}, 21:54:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:54:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:54:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:54:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:54:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['damp', 'tps']}, 21:54:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'tests': ['damp', 'tps']}, 21:54:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['dromaeo_dom']}, 21:54:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:54:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['basic_compositor_video']}, 21:54:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:54:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['a11yr', 21:54:44 INFO - 'ts_paint', 21:54:44 INFO - 'tpaint', 21:54:44 INFO - 'sessionrestore', 21:54:44 INFO - 'sessionrestore_no_auto_restore', 21:54:44 INFO - 'tabpaint']}, 21:54:44 INFO - 'other-e10s': {'tests': ['a11yr', 21:54:44 INFO - 'ts_paint', 21:54:44 INFO - 'tpaint', 21:54:44 INFO - 'sessionrestore', 21:54:44 INFO - 'sessionrestore_no_auto_restore', 21:54:44 INFO - 'tabpaint']}, 21:54:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:54:44 INFO - 'ts_paint', 21:54:44 INFO - 'tpaint', 21:54:44 INFO - 'sessionrestore', 21:54:44 INFO - 'sessionrestore_no_auto_restore', 21:54:44 INFO - 'tabpaint']}, 21:54:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:54:44 INFO - 'ts_paint', 21:54:44 INFO - 'tpaint', 21:54:44 INFO - 'sessionrestore', 21:54:44 INFO - 'sessionrestore_no_auto_restore', 21:54:44 INFO - 'tabpaint']}, 21:54:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['a11yr', 21:54:44 INFO - 'ts_paint', 21:54:44 INFO - 'tpaint', 21:54:44 INFO - 'sessionrestore', 21:54:44 INFO - 'sessionrestore_no_auto_restore', 21:54:44 INFO - 'tabpaint']}, 21:54:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['a11yr', 21:54:44 INFO - 'ts_paint', 21:54:44 INFO - 'tpaint', 21:54:44 INFO - 'sessionrestore', 21:54:44 INFO - 'sessionrestore_no_auto_restore', 21:54:44 INFO - 'tabpaint']}, 21:54:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['tsvgx', 21:54:44 INFO - 'tsvgr_opacity', 21:54:44 INFO - 'tart', 21:54:44 INFO - 'tscrollx', 21:54:44 INFO - 'cart']}, 21:54:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:54:44 INFO - 'tsvgr_opacity', 21:54:44 INFO - 'tart', 21:54:44 INFO - 'tscrollx', 21:54:44 INFO - 'cart']}, 21:54:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'talos_options': ['--disable-e10s'], 21:54:44 INFO - 'tests': ['tp5o']}, 21:54:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'tests': ['tp5o']}, 21:54:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'talos_options': ['--disable-e10s', 21:54:44 INFO - '--xperf_path', 21:54:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:44 INFO - 'tests': ['tp5n']}, 21:54:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:44 INFO - 'talos_options': ['--xperf_path', 21:54:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:44 INFO - 'tests': ['tp5n']}}, 21:54:44 INFO - 'talos.zip': {'path': '', 21:54:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:54:44 INFO - Running post-action listener: _resource_record_post_action 21:54:44 INFO - [mozharness: 2016-08-11 04:54:44.764445Z] Finished populate-webroot step (success) 21:54:44 INFO - [mozharness: 2016-08-11 04:54:44.764526Z] Running create-virtualenv step. 21:54:44 INFO - Running pre-action listener: _resource_record_pre_action 21:54:44 INFO - Running main action method: create_virtualenv 21:54:44 INFO - Creating virtualenv /builds/slave/test/build/venv 21:54:44 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 21:54:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:54:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:54:44 INFO - Using real prefix '/tools/python27' 21:54:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:54:45 INFO - Installing distribute.............................................................................................................................................................................................done. 21:54:48 INFO - Installing pip.................done. 21:54:48 INFO - Return code: 0 21:54:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:54:48 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 21:54:48 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 21:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:48 INFO - 'HOME': '/Users/cltbld', 21:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:48 INFO - 'LOGNAME': 'cltbld', 21:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:48 INFO - 'MOZ_NO_REMOTE': '1', 21:54:48 INFO - 'NO_EM_RESTART': '1', 21:54:48 INFO - 'PAGER': '/bin/cat', 21:54:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:48 INFO - 'PWD': '/builds/slave/test', 21:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:48 INFO - 'SHELL': '/bin/bash', 21:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:54:48 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:48 INFO - 'USER': 'cltbld', 21:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:48 INFO - 'XPC_FLAGS': '0x0', 21:54:48 INFO - 'XPC_SERVICE_NAME': '0', 21:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:48 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 21:54:48 INFO - Cleaning up... 21:54:48 INFO - Return code: 0 21:54:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:54:48 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 21:54:48 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 21:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:48 INFO - 'HOME': '/Users/cltbld', 21:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:48 INFO - 'LOGNAME': 'cltbld', 21:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:48 INFO - 'MOZ_NO_REMOTE': '1', 21:54:48 INFO - 'NO_EM_RESTART': '1', 21:54:48 INFO - 'PAGER': '/bin/cat', 21:54:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:48 INFO - 'PWD': '/builds/slave/test', 21:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:48 INFO - 'SHELL': '/bin/bash', 21:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:54:48 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:48 INFO - 'USER': 'cltbld', 21:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:48 INFO - 'XPC_FLAGS': '0x0', 21:54:48 INFO - 'XPC_SERVICE_NAME': '0', 21:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:49 INFO - Downloading/unpacking psutil>=3.1.1 21:54:49 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 21:54:49 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 21:54:49 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 21:54:49 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 21:54:49 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 21:54:49 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 21:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:54:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:52 INFO - Installing collected packages: psutil 21:54:52 INFO - Running setup.py install for psutil 21:54:52 INFO - building 'psutil._psutil_osx' extension 21:54:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 21:54:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 21:54:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 21:54:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 21:54:52 INFO - building 'psutil._psutil_posix' extension 21:54:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 21:54:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:54:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:54:52 INFO - ^ 21:54:53 INFO - 1 warning generated. 21:54:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 21:54:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:53 INFO - Successfully installed psutil 21:54:53 INFO - Cleaning up... 21:54:53 INFO - Return code: 0 21:54:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:54:53 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')]} 21:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:53 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')]} 21:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:54:53 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 21:54:53 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 21:54:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:54:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:53 INFO - 'HOME': '/Users/cltbld', 21:54:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:53 INFO - 'LOGNAME': 'cltbld', 21:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:53 INFO - 'MOZ_NO_REMOTE': '1', 21:54:53 INFO - 'NO_EM_RESTART': '1', 21:54:53 INFO - 'PAGER': '/bin/cat', 21:54:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:53 INFO - 'PWD': '/builds/slave/test', 21:54:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:53 INFO - 'SHELL': '/bin/bash', 21:54:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:54:53 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:54:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:53 INFO - 'USER': 'cltbld', 21:54:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:53 INFO - 'XPC_FLAGS': '0x0', 21:54:53 INFO - 'XPC_SERVICE_NAME': '0', 21:54:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:53 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 21:54:53 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 21:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:54:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:54:56 INFO - Installing collected packages: mozsystemmonitor 21:54:56 INFO - Running setup.py install for mozsystemmonitor 21:54:56 INFO - Successfully installed mozsystemmonitor 21:54:56 INFO - Cleaning up... 21:54:56 INFO - Return code: 0 21:54:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:54:56 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')]} 21:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:56 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')]} 21:54:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:56 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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:54:56 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 21:54:56 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 21:54:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:54:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:56 INFO - 'HOME': '/Users/cltbld', 21:54:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:56 INFO - 'LOGNAME': 'cltbld', 21:54:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:56 INFO - 'MOZ_NO_REMOTE': '1', 21:54:56 INFO - 'NO_EM_RESTART': '1', 21:54:56 INFO - 'PAGER': '/bin/cat', 21:54:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:56 INFO - 'PWD': '/builds/slave/test', 21:54:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:56 INFO - 'SHELL': '/bin/bash', 21:54:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:54:56 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:54:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:56 INFO - 'USER': 'cltbld', 21:54:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:56 INFO - 'XPC_FLAGS': '0x0', 21:54:56 INFO - 'XPC_SERVICE_NAME': '0', 21:54:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:54:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:56 INFO - Downloading/unpacking jsonschema==2.5.1 21:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:56 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 21:54:56 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 21:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:54:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:54:59 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 21:54:59 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 21:54:59 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 21:54:59 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 21:54:59 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 21:54:59 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 21:54:59 INFO - Downloading functools32-3.2.3-2.tar.gz 21:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:54:59 INFO - warning: no files found matching '*.txt' 21:54:59 INFO - no previously-included directories found matching 'build' 21:54:59 INFO - no previously-included directories found matching 'dist' 21:54:59 INFO - no previously-included directories found matching '.git*' 21:54:59 INFO - Installing collected packages: jsonschema, functools32 21:54:59 INFO - Running setup.py install for functools32 21:54:59 INFO - warning: no files found matching '*.txt' 21:54:59 INFO - no previously-included directories found matching 'build' 21:54:59 INFO - no previously-included directories found matching 'dist' 21:54:59 INFO - no previously-included directories found matching '.git*' 21:54:59 INFO - Successfully installed jsonschema functools32 21:54:59 INFO - Cleaning up... 21:54:59 INFO - Return code: 0 21:54:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:54:59 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')]} 21:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:59 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')]} 21:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:59 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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:54:59 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 21:54:59 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 21:54:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:54:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:54:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:54:59 INFO - 'HOME': '/Users/cltbld', 21:54:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:54:59 INFO - 'LOGNAME': 'cltbld', 21:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:59 INFO - 'MOZ_NO_REMOTE': '1', 21:54:59 INFO - 'NO_EM_RESTART': '1', 21:54:59 INFO - 'PAGER': '/bin/cat', 21:54:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:59 INFO - 'PWD': '/builds/slave/test', 21:54:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:54:59 INFO - 'SHELL': '/bin/bash', 21:54:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:54:59 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:54:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:54:59 INFO - 'USER': 'cltbld', 21:54:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:54:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:59 INFO - 'XPC_FLAGS': '0x0', 21:54:59 INFO - 'XPC_SERVICE_NAME': '0', 21:54:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:55:00 INFO - Cleaning up... 21:55:00 INFO - Return code: 0 21:55:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:00 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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:55:00 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 21:55:00 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 21:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:00 INFO - 'HOME': '/Users/cltbld', 21:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:00 INFO - 'LOGNAME': 'cltbld', 21:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:00 INFO - 'MOZ_NO_REMOTE': '1', 21:55:00 INFO - 'NO_EM_RESTART': '1', 21:55:00 INFO - 'PAGER': '/bin/cat', 21:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:00 INFO - 'PWD': '/builds/slave/test', 21:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:00 INFO - 'SHELL': '/bin/bash', 21:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:55:00 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:00 INFO - 'USER': 'cltbld', 21:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:00 INFO - 'XPC_FLAGS': '0x0', 21:55:00 INFO - 'XPC_SERVICE_NAME': '0', 21:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:00 INFO - Downloading/unpacking blobuploader==1.2.4 21:55:00 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 21:55:00 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 21:55:00 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 21:55:00 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 21:55:00 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 21:55:00 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 21:55:02 INFO - Downloading blobuploader-1.2.4.tar.gz 21:55:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:55:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:02 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 21:55:02 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 21:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:55:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:55:03 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 21:55:03 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 21:55:03 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 21:55:03 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 21:55:03 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 21:55:03 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 21:55:03 INFO - Downloading docopt-0.6.1.tar.gz 21:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:55:04 INFO - Installing collected packages: blobuploader, requests, docopt 21:55:04 INFO - Running setup.py install for blobuploader 21:55:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:55:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:55:04 INFO - Running setup.py install for requests 21:55:04 INFO - Running setup.py install for docopt 21:55:04 INFO - Successfully installed blobuploader requests docopt 21:55:04 INFO - Cleaning up... 21:55:04 INFO - Return code: 0 21:55:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:55:04 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')]} 21:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:04 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')]} 21:55:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:04 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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:55:04 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 21:55:04 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 21:55:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:55:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:04 INFO - 'HOME': '/Users/cltbld', 21:55:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:04 INFO - 'LOGNAME': 'cltbld', 21:55:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:04 INFO - 'MOZ_NO_REMOTE': '1', 21:55:04 INFO - 'NO_EM_RESTART': '1', 21:55:04 INFO - 'PAGER': '/bin/cat', 21:55:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:04 INFO - 'PWD': '/builds/slave/test', 21:55:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:04 INFO - 'SHELL': '/bin/bash', 21:55:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:55:04 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:55:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:04 INFO - 'USER': 'cltbld', 21:55:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:04 INFO - 'XPC_FLAGS': '0x0', 21:55:04 INFO - 'XPC_SERVICE_NAME': '0', 21:55:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-VwRuUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-tvrAIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-BDSzOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-3sZhAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-wBeeDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-btoFsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-F3ryhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:55:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:55:05 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-1kiNY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-0DJ1U4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-9PWTbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-rcNJ3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-75LYsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-W6ny62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-_EzUNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-v5n9O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-NQbIqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:55:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:55:06 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-yeohzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:55:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:55:07 INFO - Running setup.py install for manifestparser 21:55:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:55:07 INFO - Running setup.py install for mozcrash 21:55:07 INFO - Running setup.py install for mozdebug 21:55:07 INFO - Running setup.py install for mozdevice 21:55:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:55:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:55:07 INFO - Running setup.py install for mozfile 21:55:07 INFO - Running setup.py install for mozhttpd 21:55:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:55:07 INFO - Running setup.py install for mozinfo 21:55:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Running setup.py install for mozInstall 21:55:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Running setup.py install for mozleak 21:55:08 INFO - Running setup.py install for mozlog 21:55:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Running setup.py install for moznetwork 21:55:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Running setup.py install for mozprocess 21:55:08 INFO - Running setup.py install for mozprofile 21:55:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:55:08 INFO - Running setup.py install for mozrunner 21:55:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:55:09 INFO - Running setup.py install for mozscreenshot 21:55:09 INFO - Running setup.py install for moztest 21:55:09 INFO - Running setup.py install for mozversion 21:55:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:55:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:55:09 INFO - Cleaning up... 21:55:09 INFO - Return code: 0 21:55:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:09 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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:55:09 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 21:55:09 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 21:55:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:55:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:09 INFO - 'HOME': '/Users/cltbld', 21:55:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:09 INFO - 'LOGNAME': 'cltbld', 21:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:09 INFO - 'MOZ_NO_REMOTE': '1', 21:55:09 INFO - 'NO_EM_RESTART': '1', 21:55:09 INFO - 'PAGER': '/bin/cat', 21:55:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:09 INFO - 'PWD': '/builds/slave/test', 21:55:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:09 INFO - 'SHELL': '/bin/bash', 21:55:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:55:09 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:55:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:09 INFO - 'USER': 'cltbld', 21:55:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:09 INFO - 'XPC_FLAGS': '0x0', 21:55:09 INFO - 'XPC_SERVICE_NAME': '0', 21:55:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:55:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-hOUgH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:55:09 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)) 21:55:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:55:09 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-a9XIG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-EmYzl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-zFcR2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-BDD1ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-bDAPft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-9liXac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-jQ9CLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-f5Frvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-WE70kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:55:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:55:10 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-ectHuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:55:10 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)) 21:55:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:55:11 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-jPQixA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:55:11 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)) 21:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:55:11 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-s2Piil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:55:11 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)) 21:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:55:11 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-5HPIPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:55:11 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)) 21:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:11 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-3HI__S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:55:11 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)) 21:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:55:11 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-IWq8L2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:55:11 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)) 21:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:55:11 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-YYkHwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:55:11 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)) 21:55:11 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)) 21:55:11 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)) 21:55:11 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)) 21:55:11 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)) 21:55:11 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)) 21:55:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:55:11 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 21:55:11 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 21:55:11 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 21:55:11 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 21:55:11 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 21:55:11 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 21:55:13 INFO - Downloading blessings-1.6.tar.gz 21:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:55:14 INFO - Installing collected packages: blessings 21:55:14 INFO - Running setup.py install for blessings 21:55:14 INFO - Successfully installed blessings 21:55:14 INFO - Cleaning up... 21:55:14 INFO - Return code: 0 21:55:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:55:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:55:14 INFO - Reading from file tmpfile_stdout 21:55:14 INFO - Current package versions: 21:55:14 INFO - blessings == 1.6 21:55:14 INFO - blobuploader == 1.2.4 21:55:14 INFO - docopt == 0.6.1 21:55:14 INFO - functools32 == 3.2.3-2 21:55:14 INFO - jsonschema == 2.5.1 21:55:14 INFO - manifestparser == 1.1 21:55:14 INFO - mozInstall == 1.12 21:55:14 INFO - mozcrash == 0.17 21:55:14 INFO - mozdebug == 0.1 21:55:14 INFO - mozdevice == 0.48 21:55:14 INFO - mozfile == 1.2 21:55:14 INFO - mozhttpd == 0.7 21:55:14 INFO - mozinfo == 0.9 21:55:14 INFO - mozleak == 0.1 21:55:14 INFO - mozlog == 3.3 21:55:14 INFO - moznetwork == 0.27 21:55:14 INFO - mozprocess == 0.23 21:55:14 INFO - mozprofile == 0.28 21:55:14 INFO - mozrunner == 6.12 21:55:14 INFO - mozscreenshot == 0.1 21:55:14 INFO - mozsystemmonitor == 0.3 21:55:14 INFO - moztest == 0.7 21:55:14 INFO - mozversion == 1.4 21:55:14 INFO - psutil == 3.1.1 21:55:14 INFO - requests == 1.2.3 21:55:14 INFO - wsgiref == 0.1.2 21:55:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:55:14 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')]} 21:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:14 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')]} 21:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:14 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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:55:14 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 21:55:14 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 21:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:14 INFO - 'HOME': '/Users/cltbld', 21:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:14 INFO - 'LOGNAME': 'cltbld', 21:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:14 INFO - 'MOZ_NO_REMOTE': '1', 21:55:14 INFO - 'NO_EM_RESTART': '1', 21:55:14 INFO - 'PAGER': '/bin/cat', 21:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:14 INFO - 'PWD': '/builds/slave/test', 21:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:14 INFO - 'SHELL': '/bin/bash', 21:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:55:14 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:14 INFO - 'USER': 'cltbld', 21:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:14 INFO - 'XPC_FLAGS': '0x0', 21:55:14 INFO - 'XPC_SERVICE_NAME': '0', 21:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 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)) 21:55:14 INFO - Cleaning up... 21:55:14 INFO - Return code: 0 21:55:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:55:14 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')]} 21:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:14 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')]} 21:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:14 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 0x10bcc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa50956e700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 21:55:14 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 21:55:14 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 21:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:14 INFO - 'HOME': '/Users/cltbld', 21:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:14 INFO - 'LOGNAME': 'cltbld', 21:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:14 INFO - 'MOZ_NO_REMOTE': '1', 21:55:14 INFO - 'NO_EM_RESTART': '1', 21:55:14 INFO - 'PAGER': '/bin/cat', 21:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:14 INFO - 'PWD': '/builds/slave/test', 21:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:14 INFO - 'SHELL': '/bin/bash', 21:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:55:14 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:14 INFO - 'USER': 'cltbld', 21:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:14 INFO - 'XPC_FLAGS': '0x0', 21:55:14 INFO - 'XPC_SERVICE_NAME': '0', 21:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:55:15 INFO - Cleaning up... 21:55:15 INFO - Return code: 0 21:55:15 INFO - Running post-action listener: _resource_record_post_action 21:55:15 INFO - Running post-action listener: _start_resource_monitoring 21:55:15 INFO - Starting resource monitoring. 21:55:15 INFO - [mozharness: 2016-08-11 04:55:15.189876Z] Finished create-virtualenv step (success) 21:55:15 INFO - [mozharness: 2016-08-11 04:55:15.190824Z] Running install step. 21:55:15 INFO - Running pre-action listener: _resource_record_pre_action 21:55:15 INFO - Running main action method: install 21:55:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:55:15 INFO - Reading from file tmpfile_stdout 21:55:15 INFO - Detecting whether we're running mozinstall >=1.0... 21:55:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:55:15 INFO - Reading from file tmpfile_stdout 21:55:15 INFO - Output received: 21:55:15 INFO - Usage: mozinstall [options] installer 21:55:15 INFO - Options: 21:55:15 INFO - -h, --help show this help message and exit 21:55:15 INFO - -d DEST, --destination=DEST 21:55:15 INFO - Directory to install application into. [default: 21:55:15 INFO - "/builds/slave/test"] 21:55:15 INFO - --app=APP Application being installed. [default: firefox] 21:55:15 INFO - mkdir: /builds/slave/test/build/application 21:55:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:55:49 INFO - Reading from file tmpfile_stdout 21:55:49 INFO - Output received: 21:55:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:55:49 INFO - Running post-action listener: _resource_record_post_action 21:55:49 INFO - [mozharness: 2016-08-11 04:55:49.639729Z] Finished install step (success) 21:55:49 INFO - [mozharness: 2016-08-11 04:55:49.639879Z] Running run-tests step. 21:55:49 INFO - Running pre-action listener: _resource_record_pre_action 21:55:49 INFO - Running main action method: run_tests 21:55:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:55:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:55:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:55:49 INFO - 2016-08-10 21:55:49.654 screenresolution[1642:7387] starting screenresolution argv=screenresolution get 21:55:49 INFO - 2016-08-10 21:55:49.656 screenresolution[1642:7387] Display 0: 1600x1200x32@60 21:55:49 INFO - 2016-08-10 21:55:49.662 screenresolution[1643:7390] starting screenresolution argv=screenresolution list 21:55:49 INFO - Available Modes on Display 0 21:55:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:55:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:55:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:55:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:55:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:55:49 INFO - Intel Iris: 21:55:49 INFO - Chipset Model: Intel Iris 21:55:49 INFO - Type: GPU 21:55:49 INFO - Bus: Built-In 21:55:49 INFO - VRAM (Dynamic, Max): 1536 MB 21:55:49 INFO - Vendor: Intel (0x8086) 21:55:49 INFO - Device ID: 0x0a2e 21:55:49 INFO - Revision ID: 0x0009 21:55:49 INFO - Displays: 21:55:49 INFO - Display: 21:55:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:55:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:55:49 INFO - Display Serial Number: 1600x1200 60 21:55:49 INFO - Main Display: Yes 21:55:49 INFO - Mirror: Off 21:55:49 INFO - Online: Yes 21:55:49 INFO - Rotation: Supported 21:55:49 INFO - Return code: 0 21:55:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:55:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:55:49 INFO - Python 2.7.3 21:55:49 INFO - Return code: 0 21:55:49 INFO - grabbing minidump binary from tooltool 21:55: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')]} 21:55:49 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/macosx64/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 0x10bc2dd30>, '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 0x10b992db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bc25b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:55:49 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:55:49 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 21:55:49 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:55:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:55:50 INFO - Return code: 0 21:55:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:55:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:55:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:55:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:55:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:55:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0299 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:55:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render', 21:55:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:55:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:55:50 INFO - 'HOME': '/Users/cltbld', 21:55:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:55:50 INFO - 'LOGNAME': 'cltbld', 21:55:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:55:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:50 INFO - 'MOZ_NO_REMOTE': '1', 21:55:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:55:50 INFO - 'NO_EM_RESTART': '1', 21:55:50 INFO - 'PAGER': '/bin/cat', 21:55:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:50 INFO - 'PWD': '/builds/slave/test', 21:55:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:55:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:55:50 INFO - 'SHELL': '/bin/bash', 21:55:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners', 21:55:50 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 21:55:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:55:50 INFO - 'USER': 'cltbld', 21:55:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:55:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:50 INFO - 'XPC_FLAGS': '0x0', 21:55:50 INFO - 'XPC_SERVICE_NAME': '0', 21:55:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:55:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:55:50 INFO - mozversion application_buildid: 20160810194621 21:55:50 INFO - mozversion application_changeset: 517d1167728f4af8bad45cd679d80b509efad854 21:55:50 INFO - mozversion application_display_name: Nightly 21:55:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:55:50 INFO - mozversion application_name: Firefox 21:55:50 INFO - mozversion application_remotingname: firefox 21:55:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:55:50 INFO - mozversion application_vendor: Mozilla 21:55:50 INFO - mozversion application_version: 51.0a1 21:55:50 INFO - mozversion platform_buildid: 20160810194621 21:55:50 INFO - mozversion platform_changeset: 517d1167728f4af8bad45cd679d80b509efad854 21:55:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:55:50 INFO - mozversion platform_version: 51.0a1 21:55:50 INFO - using testdate: 1470891350 21:55:50 INFO - actual date: 1470891350 21:55:50 INFO - starting webserver on 'localhost:49271' 21:55:50 INFO - SUITE-START | Running 2 tests 21:55:50 INFO - TEST-START | dromaeo_css 21:55:50 INFO - operating with platform_type : mac_ 21:55:50 INFO - Initialising browser for dromaeo_css test... 21:55:50 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpCIEQO_/profile http://localhost:49271/getInfo.html) 21:55:53 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 21:55:53 INFO - PROCESS | 1653 | colorDepth:24 21:55:53 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 21:55:53 INFO - PROCESS | 1653 | __metrics 21:55:53 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 21:55:53 INFO - TEST-INFO | 1653: exit 0 21:55:53 INFO - Browser initialized. 21:55:53 INFO - Running cycle 1/1 for dromaeo_css test... 21:55:53 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpCIEQO_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:05 INFO - PROCESS | 1656 | RSS: Main: 254603264 21:56:05 INFO - PROCESS | 1656 | 21:56:55 INFO - PROCESS | 1656 | 1470891415371 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] 21:59:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 21:59:08 INFO - PROCESS | 1656 | RSS: Main: 267628544 21:59:08 INFO - PROCESS | 1656 | 22:02:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 22:02:11 INFO - PROCESS | 1656 | RSS: Main: 275746816 22:02:11 INFO - PROCESS | 1656 | 22:05:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 22:05:14 INFO - PROCESS | 1656 | RSS: Main: 280551424 22:05:14 INFO - PROCESS | 1656 | 22:08:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 22:08:16 INFO - PROCESS | 1656 | RSS: Main: 278921216 22:08:16 INFO - PROCESS | 1656 | 22:11:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 22:11:19 INFO - PROCESS | 1656 | RSS: Main: 283299840 22:11:19 INFO - PROCESS | 1656 | 22:14:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 22:14:23 INFO - PROCESS | 1656 | RSS: Main: 284844032 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | __start_tp_report 22:14:23 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 22:14:23 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 22:14:23 INFO - PROCESS | 1656 | |i|pagename|runs| 22:14:23 INFO - PROCESS | 1656 | |0;dojo.html;3350.8827191048304;3350.949735753964;3377.8986630401096;3411.2324726936445;3448.689617934386;5224.529792318695;5257.737113144337;5408.810691625794;5690;5947.137665038566;5758.798442054529;5769.221155144054;5785.652860828353;5795;5917.964356237662;5168.457311982234;5242.318498595197;5250.6587071840395;5322.121849894782;5378.139497680372;5318.25544423781;5400.756965936523;5416.972915135439;5429.0227759003365;5440.374356948962;4219.346001369792;5017.799288028493;5143.996920600484;5197;5323.893522129555;5229.5293423591675;5235.895282094337;5249.737513124367;5259.86850328741;5298.947010529884;5220.451852555465;5247.422783493813;5278.340207474066;5300.257963885059;5324.787008519655;5699.715014249313;5777.711114444261;5877.764889404419;5902.114682797572;5916.408359164092;5503.229547863302;6172.197614310156;6195.721192546346;6220.253569571635;6231.189945307126;5076.416212135615;5279.577633789288;6293.527985401113;6304;6338.176037115146;5763.884722305573;5847.853803654943;5897.911531327033;5911.9113213301835;5993.460588547052;5254.290670759475;6195.721192546346;6263.248410190807;6274.937250627527;6278.434940855253;5700.315962084494;6158.322584515699;6167.938320616826;6195.938040619626;6200.255969283625;5191.506806853343;5716.88520738452;5956.136360227742;6124.014786914908;6161.075838624152;6134.35589263121;6180.907286390708;6252.343503932113;6252.406021427957;6255.093011513396;5464.562834973192;5900.793472228537;6135.07973803933;6172.5679202456295;6214.502839772807;4684.461286951976;4943.209086546135;5027.547520723152;5040.747962601855;5057.974710126426;4384.430024096847;4407.537208593116;4447.666425018137;4464.687471877002;4518.706284091523;5579.581531385163;5601;5711.628744131701;5727.541796656257;5735.741891614887;6149.831532008904;6188.628682278988;6208.751649933952;6218.720157592919;6240.875182496325;5856.795012174639;5899.941000589939;5926.822195334147;5931.940680593225;5938.703064846741;5513.724313784295;5513.924784667031;5520.227168196456;5521.337439507285;5546.417626149197;6209.720562574605;6263.749450021948;6351.174347334817;6410.775622853178;6421;5190.532852043334;6207.937920620736;6226.750929962751;6231.096491008869;6233.750649974132;6354.301026886947;6372.0760489729655;6386.712597933011;6400.55196136266;6428.357164283534;6094.268687757498;6111.07520520229;6208.565400422108;6214.098955651496;6239.875202496016;6146.877062458636;6185.134081228542;6212.968935155295;6225.968870155621;6229.065640154013;5153.896922061463;6168.475679567287;6199.225096862892;6232.189815323979;6236.906446403398;6070.2412198475195;6125.388069147376;6198.411150940653;6216.720247588779;6255.499560035095;5245.344331958505;5593.804216852391;6050.334463209131;6104.908426373519;6141.263048434216;6057.575969682256;6160.229971253593;6205.069239614094;6220.7511699531515;6231.1276421300145;5943.910841337297;6064.939350606437;6094.11635312886;6164.137020816999;6181.443670069535 22:14:23 INFO - PROCESS | 1656 | |1;ext.html;485.15582885778747;528.986989914314;538.5906710899715;540.2571464236675;610.0300522169755;28663.713362866365;41889;41968.79015604922;41997.37003944943;42035.738927832135;42672.35991460131;43312.78343608278;43325.70022899318;43377;43414;8090.838183236332;8881.245094167003;9604.759881002961;9767.853482197772;9818.11636952674;9028.8645670315;9587.616495340179;9600.1359877611;9626.75933101671;9700.078492543233;9663.661771837988;9707;9709.61161553537;9718.56266468007;9738.853917191247;8013.479123856989;8077.7576672699915;8532.658693652247;9650.986646402167;9686.75783105421;8171.387145964077;8359.83280334396;9677.12905838471;9734;9760.072793084646;40374;40632.39051414231;40723.778286651446;40744;40819.97950051272;2514.861682607456;3164.829013265107;3215.7266632336045;3314.784539004957;3552.875649352286;29321.41357172866;30447.847760761055;31036.844815775778;31064.689353106405;31091;3102.348506813549;3112.25305926576;3155.4477966356026;3182.236263296815;3203.6315823680343;387734;446060;450601.74699126295;450773;451163.4883651154;34348.48477272843;34491.655083448844;34563.308733825186;34580.13549661228;34596;34802.82598586991;35164.12089697778;35206.11984700403;35687;36739.816300918326;16395.508134755975;16538;16799.99200048001;16897.662046758996;17058.829411705974;12868;13015.349232538336;13035.543755968496;13043.478260869459;13080.018998574955;16120;19818.20727170926;19874.50313742139;19999.60000800005;20198.697019544426;6849.349311815369;8114.43198976066;8225.917740822635;8229.917700823034;8279.461834980708;881.2685471059006;922.7324076017879;951.7192428233687;989.302541708094;1028.9382637041801;30879.536806947915;31071.533926991113;31128.53307200394;31847;32383.67616323854;1040.5213601743133;1080.9027187553158;1127.8026345389524;1153.8211577205548;1233.8457692788402;15829.287682054335;16241.106739129462;16319.020858748512;16500.4224852132;16574.66850663004;689076;773476.1326193445;788981;793424;1022977;62243;63001;63236.367636323965;63272;63431.04853427202;1172.60717659585;1325.827642406481;1333.6665833541615;1353.9052266341262;1387.5559820857432;31679;32616;32672.83663581762;33094.50358244676;33276.66723332833;54127.72936135294;54810.62973425696;54850;55006;55184.172237417275;640433;641090;646657;647765;648909;28079;28390.290242744097;28499;28512.144635660963;28517.714822851507;49380.25929610987;49407.01185976332;49609.50390496049;50831;50834.98330033305;40524.79737601293;40749.79625101974;41227;41779.58220417757;42331.3650295252;32935.50596741003;34388.82805585956;36562.7203047892;40442.39336410015;41966;34106.31787364288;38675.6132438672;39208.80395598004;39389.80305098456;40439.1912161761;31340.216494587818;35058.64941350656;35471;36024.459633106046;36088;21007.949602519508;25338.74661253364;26592.734072659026;26737.465250694488;26962.865185673945 22:14:23 INFO - PROCESS | 1656 | |2;jquery.html;326.53305772744966;367.5167155190924;369.123332086295;385.8109526332098;396.14630471334283;14771.852281477182;18905.527361815944;19114.04429778511;20362.083706233218;20557.280495182633;19757.901210493965;19842.30551930683;20307.59384812303;20738.792612073914;20776;4517.977410112945;5916.408359164081;6121.479674227674;6139.201903752506;6388.840278993016;5946.702664866761;5977.551683623724;6025.487833534155;6097.054956481752;6108.694565271719;5918.704064796764;5922.170896074532;6053.455189032986;6056.394360563931;6058.1518587397795;5887.499562537189;5938;5984.790532331369;5999.730012149442;6108.633481991051;5140.126178549655;5203.505666961632;5229.947700523022;5244.60665450089;5267.631265811395;25370.985160593555;25462.490750184894;25483.362915927064;25827.35431614206;25850.482990340275;2258.5482903419384;2445.193086281523;2768.1557125076843;2846.3453405716596;2919.7518210952094;13836.723265534632;13843.377048032862;14919.731822795075;15325.23373831304;15837.0497770133;1860.57206842427;1866.5333666583354;1868.962620747591;1899.7530321058312;1933.4006457998066;114516.42741786237;119698;121651.17523237246;121905;123912;7860.5676687782725;8963.745075689409;8976.730698079067;11047;11070.169737269576;13601.659958500919;13623.046386752832;13836.031477796458;13853.792193117111;13856.960727945445;8404.747857564284;8555.358348123791;8591.226789588967;8605.784855378666;8698.347623928104;8577.614007369682;8584.613692383859;8705.47767133974;8831.24934380572;8954.104589541124;5527.419620939824;6558.376954189344;6564.80305590833;6659.001149827564;6769.39075483199;1822;1844.9354272600394;1847.6674198644373;1847.9466703978603;1858.1823997441084;882.4661080046479;940.6472572785206;947.8009617980164;1011.2920955331298;1025.3386565665253;20722.274720384714;21570.56858862814;21873;21897.562048759253;21958.560828783335;946.0823001688351;979.6081567373108;982.9557669904872;995.382862625177;1016.2733645443543;9556.23550115989;11151.832722509169;11633.59282425128;11686.532538698357;11690.532378704756;620926;623299;634501;639693;641519;86922;107866;114102.85897141088;114706;118555.8144418562;1015.4364327798143;1056.6777132974516;1095.7863216672672;1096.0792933935531;1099.1261946752352;18539.81460185381;19013.71479427782;19171.904140479208;19174.520636984187;19281.614367712285;49252.507474924794;50631.24053139132;50755.74622126866;51253.74373128259;52682;67028.32429189114;73850.26149738434;75067.99456043394;75829.6208518954;78340.60829695815;6155.35368786271;6373.330800266091;7719.228077192236;8149.410864881292;8391.706290280055;11841.703957400789;13027.804582931074;13042.347882606022;13250.072494925264;13314.800277996032;8408.87386689188;11485.598004069818;11504.309741415542;11554;11596.884031159918;10823.458827058457;11081.889181108085;11224.382658953837;11273.098152147972;11595.652130436101;11055.391953442317;11670.416479175838;11853.229540079867;11918.702032449259;11962.58130965412;8217.917820821714;8296.460730052528;8381.53901535397;8387.370947178864;8401.789955250923;9765.560549775135;9993.400395976265;10701.357918524913;11456.541738330374;11612.187146899636 22:14:23 INFO - PROCESS | 1656 | |3;mootools.html;1515.5680631020161;1542.87656987441;1655.271680460598;1749.3177660712324;1864;7874.803129921755;8413.579321033947;8686.348523860719;8976.73069807905;9001.549922503873;8630.95684521578;8766.34252431067;8867.60095795689;8881.955590222056;8893.555322233879;2334.801541868939;2346.765323467657;2378.726446458662;2379.2981070584133;2386.761323867608;2162.4593851537115;2328.1502251678185;2356.9410764730846;2361.95276094478;2367.6922000139957;2317.1310758465575;2340.6840076589697;2350.2949115265437;2373.5134297469062;2384.153625462962;2122.5224324526935;2205.007746514067;2335.065973610552;2345.3198572413894;2369.644553316999;2344.2146880794953;2354.376090336062;2355.128602417117;2356.446235134742;2366.148186652804;8803.987541432689;8887.15572020657;8990.730278091667;8992.280617550581;9013.278937685034;5061.569766569846;5156.252343410185;5225.608079394023;5240.97800928819;5247.527722504993;3395.7962522248745;3469.271452994874;3533.3463309287863;3566.5185199997927;3607.963920360789;936.180841763457;1030.6959446963165;1067.3489171605315;1093.8085834978847;1094.3543309447464;11654;11690.766184676258;11733.650630177553;11784.528618855236;11801.704957376049;4557.225271703818;4696.225122854759;4805.3512775775525;4816.20532612115;4820.975895120502;9812.705618831447;9959.651412200537;9966.053224943618;9966.40201587907;9970.900290997142;5236.371635403776;5345.305110335631;5350.1439769637445;5361.115415956401;5374.758135883894;4341.978290108529;4755.239161734106;4872.6589138760655;4881.365422495053;4924.384451943507;3832.616738326145;3886.8833934981994;3959.584243654375;3963.9405408918888;3996.660283875845;1143.0683992546049;1145.438735019851;1150.4707834396272;1153.7173392518887;1156.9479373428217;859.703402326209;875.7547886591765;883.5052370672444;913.1735779119907;942.4157022646003;5358.5981051421295;5499.367572729107;5971.940280597225;6074.119252708332;6113.877722445615;955.2644463762864;968.1045033344128;971.3055165556661;973.634886917406;1025.2105878473683;3101.8138911665374;3278.721308688788;3872.6901847852105;4067.3085575452246;4108.137291168829;10633.893661063445;10908.181886358554;11043;11056.336619802838;11086.501107450184;10656.57373705043;10984;10998.395088270221;11005.284656497302;11020.283681560833;7786.922130778733;8186.222308880766;8273.1726827318;8404.28563572091;8421;5805.332386775575;6034.456898879122;6061.484773794101;6168.136460895388;6214.533909956862;9787.412755234709;10064.798704026025;10093.243006774668;10130.885602583858;10133.949330253301;9899.65351212704;10168.593256269667;10169;10230.181585473294;10248.282620216512;7000.404965577983;7410.5183163094225;7622.8094297643;7626.923730762844;7681.807954801175;8423.368247381348;8491.23578877904;8508.787280318042;8544.35917306217;8575.442596231225;5479.3972663007835;7449.180590134973;7600.467967242461;7686.3466605336935;7711.421643376882;7206.855862882818;7306.305900939402;7403.1116266046265;7447.255274472509;7529.736459223901;6984.720611175496;7015.36861682451;7096.467764917755;7100.7869763907165;7111.7866464006165;5036.420811606712;5074.010567939216;5115.897682046412;5117.104506711236;5182.637215394886;4327.567243275647;4960.305557222064;5030.647854650139;5052.216906379517;5247.081760691787 22:14:23 INFO - PROCESS | 1656 | |4;prototype.html;277.2984349595523;300.6557491672037;315.14124012067134;315.8219839996812;317.5744502366828;5330.095098171496;5412.323459567554;5532.972335138324;5548.778048878045;5671.901396483302;4799.37608110946;5296.708681022542;5427.158790387486;5495.697736624484;5507.256520369749;3636.763610365331;3815.4849095372188;4174.895627609304;4183.686223533232;4193.804765641793;3861.864834730785;4166.166766646667;4202.117555313388;4210.968312763362;4226.590843219366;3418.7521554632503;3863.362545180056;4097.651699605537;4114.341705327149;4118.135191609766;3588.7308451866083;4136.524299705542;4137.586241375839;4155.896102597429;4165.063245590784;3146.05618314507;3425.520427140202;3569.4824250483794;3599.1541987632886;3608.8376023079027;4259.76571288579;5282.3925248596515;5346.812861549866;5383.496674046587;5397.948779463818;4008.498937632796;4457.576530229623;4701.75403518068;4766.666333356667;4775.832845850413;4574.9542504574865;4623.86128416148;5617.157426386075;5897.380775018647;5994.070919007517;3271.6401195868434;3826.521684789401;3917.6278253565865;3926.371780515104;3932;4586.01400698848;5954.5534084943365;6105.267367915879;6144.692765361714;6159.568830181889;3345.6988871001727;3958.940615890764;3984.7248880358006;3993.7004724645767;4003.4795476587856;3875.147467557133;3958.802059897051;4010.5588385277597;4045.4943132108488;4059.91880162401;4438.823711716398;4709.152352576501;4749.073930583572;4756.93073513023;4771.5228477152;5346.278252435949;5767.798127065533;5798.072308430716;5854.677992710442;5875;1166.6091859227083;1207.8465065862256;1288.6262983734612;1295.727897141592;1361.6731984323692;887;895.9372843900865;912.7586482383953;919.3334832246568;922.8615707643772;4569.748663823521;5352.5548102012235;5437.972810135924;5447.455254474521;5469.425710300441;5022.162478199805;5173.5602473789395;5506.256655351556;5967.31375891778;5980.979605299296;5214.93093915754;5223.64670238647;5260.421353651095;5386.407495175527;5402.324709411324;2207.6864265761847;2224.6329355656458;2479.256223133053;2491;2680.852553109559;4837.540433658797;5136.43499215086;5174.861530463292;5175.45657705943;5180.585553155738;4498.548291181314;4699.684088455238;4805.05395314898;4942.937268487292;5160.8967820643375;4541.206223541682;4587.816487340469;5140.717260550631;5166;5177.663451875616;4310.698251122517;4626.75077729018;4727.621790256702;5032.370953630796;5101.8724531887;4057.4928133983253;4142.502899652061;4436.245838207448;4752.952470475251;5008.749562521933;4812.735299558418;4959.504049595012;5150;5202.791888324425;5258.763355648928;3368.2253081791814;3380.695737383647;3443.1220038889924;3448.0690213641674;3448.2241495663275;4336.50130235027;4638.776050644769;4650.906981860277;4710.92933606003;5049.9495005049475;4253.574642535722;4380.846670366522;4381.649468042613;4808.763924774997;4909.471622114543;4192;4217.746935183899;4333.761643109659;4502.527234640315;4545.522720114341;4039.2123535910214;4439.202123140133;4590.472095709036;4837.7097374157665;4901.235555200054;4456.96363109684;4839.548135559388;4852.587530059985;4862.41651001882;4886.267059941038;4159.667226621803;4346.673999450117;4410.4199043559875;4522.502524722212;4820.517948205152 22:14:23 INFO - PROCESS | 1656 | |5;yui.html;685.8011176758737;703.9190493093296;743.6430513353587;770.114368476252;796.0248695348198;14988.5503434897;15558.844411555909;15783.447579334726;15783.605409864758;15784;14623.268836558156;14652.560423187322;14664.340104695288;14695.632609184775;14708.705825883502;670.6620292516412;813.3290035720554;846.4032856835946;858.6264974735979;872.1670804381832;844.4975239732349;861.6854847980504;863.7063398444527;864.2999170671744;866.7486428935632;883.9646414143426;927.9582418791136;928.7956649537125;932.202966463674;932.244881645869;899.4423457456386;912.2701838529216;922.7923717163652;924.2005665099674;927.4110939553365;678.9287124851918;695.1102588686489;695.3706895259797;696.8397268628194;697.2086681616352;15015.924920375328;15017.7747333789;15021.69956600862;15031.323590438456;15041.774373384407;14808.703825923421;14849.628759280997;14851.183184924825;14865;15036.473723419627;12018.639440816702;12337.876621233852;12555.121141520098;12563.937180314131;12697.746045079046;3829.3681542545446;3829.7127715421457;4029.8388064477385;4057.1277175407135;4058.8376464941366;117400;118571;119975;120249;121816.78183218231;11407.600733974272;11445.427728613537;11521.884781152248;11581.189316747916;11834.644960651194;22952.540949180922;23652.290431286743;23659.881700591388;23667.644985325234;23749.762502374757;7283.599402032939;8242.340612751086;8259.587020649064;8278.503289802511;8351.331893448509;4534.410526631583;4584.770761461914;4606.95393046072;4634.814607415666;4768.070226305837;3601.1177261571083;3666.9633303667156;3684.9078773030888;3735.551733791908;3903.902402439962;708.1891234536427;719.4747834081151;726.3390314813495;730.5434103685196;738.5974643819131;604.8457643300931;631.7220423013952;660.5376236634376;678.9083473730983;694.1669996004814;9438.433693978383;9543.188828949478;9668.806623867484;9816.65641702537;9842.163416109712;665.7436886798617;675.7060678604822;682.8292926768308;692.2800287700835;704.7744721689011;4561.407017087758;4704.988427488107;4712.505186955389;4739.478657347758;4758.928616070762;97966.5305020411;118809;119580;152478;162612;140065;145712;146110.26944865208;148056;148758;118301.40849295698;122771.77228227782;122828.38585807192;126246.36876815557;131052.79209643371;8662.224244030085;9022.909770902206;9115.863262050942;9123.224525915371;9136.682422995495;36638.8168059158;47338.633068345756;50158;68616.313836861;70163;72507.91238131537;80919;87264.1273587256;89588.5520572393;92887.53556232176;13177.670558236121;13755.79366309526;13895.930520347334;13938.651533711738;14027.719445610826;14314.355853986805;15419.383224670888;15575.454859079879;16032.11823349727;16124;13706.38321275525;14025.3688584016;14313.64215894611;14316.498922537661;14335.92832035833;10441;14399.136051836504;14423.567292981228;14504.492342767953;15026.323815428113;13700.3834827435;13835.377408016462;14041.438342466186;14293.857061429668;14345.569632911029;11189.328640281608;11343.20597558163;11380;11866.940665296963;11948.76102477963;8436.409451338593;9713;10727.19546034035;11437.771244575228;11457.885421145682 22:14:23 INFO - PROCESS | 1656 | __end_tp_report 22:14:23 INFO - PROCESS | 1656 | __start_cc_report 22:14:23 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,414 22:14:23 INFO - PROCESS | 1656 | __end_cc_report 22:14:23 INFO - PROCESS | 1656 | __startTimestamp1470892463025__endTimestamp 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | ------- Summary: start ------- 22:14:23 INFO - PROCESS | 1656 | Number of tests: 6 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5719.97 Median:5917.96 stddev:643.78 (10.9%) stddev-sans-first:618.35 22:14:23 INFO - PROCESS | 1656 | Values: 3350.9 3350.9 3377.9 3411.2 3448.7 5224.5 5257.7 5408.8 5690.0 5947.1 5758.8 5769.2 5785.7 5795.0 5918.0 5168.5 5242.3 5250.7 5322.1 5378.1 5318.3 5400.8 5417.0 5429.0 5440.4 4219.3 5017.8 5144.0 5197.0 5323.9 5229.5 5235.9 5249.7 5259.9 5298.9 5220.5 5247.4 5278.3 5300.3 5324.8 5699.7 5777.7 5877.8 5902.1 5916.4 5503.2 6172.2 6195.7 6220.3 6231.2 5076.4 5279.6 6293.5 6304.0 6338.2 5763.9 5847.9 5897.9 5911.9 5993.5 5254.3 6195.7 6263.2 6274.9 6278.4 5700.3 6158.3 6167.9 6195.9 6200.3 5191.5 5716.9 5956.1 6124.0 6161.1 6134.4 6180.9 6252.3 6252.4 6255.1 5464.6 5900.8 6135.1 6172.6 6214.5 4684.5 4943.2 5027.5 5040.7 5058.0 4384.4 4407.5 4447.7 4464.7 4518.7 5579.6 5601.0 5711.6 5727.5 5735.7 6149.8 6188.6 6208.8 6218.7 6240.9 5856.8 5899.9 5926.8 5931.9 5938.7 5513.7 5513.9 5520.2 5521.3 5546.4 6209.7 6263.7 6351.2 6410.8 6421.0 5190.5 6207.9 6226.8 6231.1 6233.8 6354.3 6372.1 6386.7 6400.6 6428.4 6094.3 6111.1 6208.6 6214.1 6239.9 6146.9 6185.1 6213.0 6226.0 6229.1 5153.9 6168.5 6199.2 6232.2 6236.9 6070.2 6125.4 6198.4 6216.7 6255.5 5245.3 5593.8 6050.3 6104.9 6141.3 6057.6 6160.2 6205.1 6220.8 6231.1 5943.9 6064.9 6094.1 6164.1 6181.4 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:74635.86 Median:39881.90 stddev:176121.75 (441.6%) stddev-sans-first:176527.82 22:14:23 INFO - PROCESS | 1656 | Values: 485.2 529.0 538.6 540.3 610.0 28663.7 41889.0 41968.8 41997.4 42035.7 42672.4 43312.8 43325.7 43377.0 43414.0 8090.8 8881.2 9604.8 9767.9 9818.1 9028.9 9587.6 9600.1 9626.8 9700.1 9663.7 9707.0 9709.6 9718.6 9738.9 8013.5 8077.8 8532.7 9651.0 9686.8 8171.4 8359.8 9677.1 9734.0 9760.1 40374.0 40632.4 40723.8 40744.0 40820.0 2514.9 3164.8 3215.7 3314.8 3552.9 29321.4 30447.8 31036.8 31064.7 31091.0 3102.3 3112.3 3155.4 3182.2 3203.6 387734.0 446060.0 450601.7 450773.0 451163.5 34348.5 34491.7 34563.3 34580.1 34596.0 34802.8 35164.1 35206.1 35687.0 36739.8 16395.5 16538.0 16800.0 16897.7 17058.8 12868.0 13015.3 13035.5 13043.5 13080.0 16120.0 19818.2 19874.5 19999.6 20198.7 6849.3 8114.4 8225.9 8229.9 8279.5 881.3 922.7 951.7 989.3 1028.9 30879.5 31071.5 31128.5 31847.0 32383.7 1040.5 1080.9 1127.8 1153.8 1233.8 15829.3 16241.1 16319.0 16500.4 16574.7 689076.0 773476.1 788981.0 793424.0 1022977.0 62243.0 63001.0 63236.4 63272.0 63431.0 1172.6 1325.8 1333.7 1353.9 1387.6 31679.0 32616.0 32672.8 33094.5 33276.7 54127.7 54810.6 54850.0 55006.0 55184.2 640433.0 641090.0 646657.0 647765.0 648909.0 28079.0 28390.3 28499.0 28512.1 28517.7 49380.3 49407.0 49609.5 50831.0 50835.0 40524.8 40749.8 41227.0 41779.6 42331.4 32935.5 34388.8 36562.7 40442.4 41966.0 34106.3 38675.6 39208.8 39389.8 40439.2 31340.2 35058.6 35471.0 36024.5 36088.0 21007.9 25338.7 26592.7 26737.5 26962.9 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:35439.56 Median:25838.92 stddev:104743.99 (405.4%) stddev-sans-first:105004.64 22:14:23 INFO - PROCESS | 1656 | Values: 326.5 367.5 369.1 385.8 396.1 14771.9 18905.5 19114.0 20362.1 20557.3 19757.9 19842.3 20307.6 20738.8 20776.0 4518.0 5916.4 6121.5 6139.2 6388.8 5946.7 5977.6 6025.5 6097.1 6108.7 5918.7 5922.2 6053.5 6056.4 6058.2 5887.5 5938.0 5984.8 5999.7 6108.6 5140.1 5203.5 5229.9 5244.6 5267.6 25371.0 25462.5 25483.4 25827.4 25850.5 2258.5 2445.2 2768.2 2846.3 2919.8 13836.7 13843.4 14919.7 15325.2 15837.0 1860.6 1866.5 1869.0 1899.8 1933.4 114516.4 119698.0 121651.2 121905.0 123912.0 7860.6 8963.7 8976.7 11047.0 11070.2 13601.7 13623.0 13836.0 13853.8 13857.0 8404.7 8555.4 8591.2 8605.8 8698.3 8577.6 8584.6 8705.5 8831.2 8954.1 5527.4 6558.4 6564.8 6659.0 6769.4 1822.0 1844.9 1847.7 1847.9 1858.2 882.5 940.6 947.8 1011.3 1025.3 20722.3 21570.6 21873.0 21897.6 21958.6 946.1 979.6 983.0 995.4 1016.3 9556.2 11151.8 11633.6 11686.5 11690.5 620926.0 623299.0 634501.0 639693.0 641519.0 86922.0 107866.0 114102.9 114706.0 118555.8 1015.4 1056.7 1095.8 1096.1 1099.1 18539.8 19013.7 19171.9 19174.5 19281.6 49252.5 50631.2 50755.7 51253.7 52682.0 67028.3 73850.3 75068.0 75829.6 78340.6 6155.4 6373.3 7719.2 8149.4 8391.7 11841.7 13027.8 13042.3 13250.1 13314.8 8408.9 11485.6 11504.3 11554.0 11596.9 10823.5 11081.9 11224.4 11273.1 11595.7 11055.4 11670.4 11853.2 11918.7 11962.6 8217.9 8296.5 8381.5 8387.4 8401.8 9765.6 9993.4 10701.4 11456.5 11612.2 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5730.97 Median:5033.53 stddev:3250.11 (64.6%) stddev-sans-first:3243.79 22:14:23 INFO - PROCESS | 1656 | Values: 1515.6 1542.9 1655.3 1749.3 1864.0 7874.8 8413.6 8686.3 8976.7 9001.5 8631.0 8766.3 8867.6 8882.0 8893.6 2334.8 2346.8 2378.7 2379.3 2386.8 2162.5 2328.2 2356.9 2362.0 2367.7 2317.1 2340.7 2350.3 2373.5 2384.2 2122.5 2205.0 2335.1 2345.3 2369.6 2344.2 2354.4 2355.1 2356.4 2366.1 8804.0 8887.2 8990.7 8992.3 9013.3 5061.6 5156.3 5225.6 5241.0 5247.5 3395.8 3469.3 3533.3 3566.5 3608.0 936.2 1030.7 1067.3 1093.8 1094.4 11654.0 11690.8 11733.7 11784.5 11801.7 4557.2 4696.2 4805.4 4816.2 4821.0 9812.7 9959.7 9966.1 9966.4 9970.9 5236.4 5345.3 5350.1 5361.1 5374.8 4342.0 4755.2 4872.7 4881.4 4924.4 3832.6 3886.9 3959.6 3963.9 3996.7 1143.1 1145.4 1150.5 1153.7 1156.9 859.7 875.8 883.5 913.2 942.4 5358.6 5499.4 5971.9 6074.1 6113.9 955.3 968.1 971.3 973.6 1025.2 3101.8 3278.7 3872.7 4067.3 4108.1 10633.9 10908.2 11043.0 11056.3 11086.5 10656.6 10984.0 10998.4 11005.3 11020.3 7786.9 8186.2 8273.2 8404.3 8421.0 5805.3 6034.5 6061.5 6168.1 6214.5 9787.4 10064.8 10093.2 10130.9 10133.9 9899.7 10168.6 10169.0 10230.2 10248.3 7000.4 7410.5 7622.8 7626.9 7681.8 8423.4 8491.2 8508.8 8544.4 8575.4 5479.4 7449.2 7600.5 7686.3 7711.4 7206.9 7306.3 7403.1 7447.3 7529.7 6984.7 7015.4 7096.5 7100.8 7111.8 5036.4 5074.0 5115.9 5117.1 5182.6 4327.6 4960.3 5030.6 5052.2 5247.1 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4281.74 Median:4607.17 stddev:1293.79 (28.1%) stddev-sans-first:1262.02 22:14:23 INFO - PROCESS | 1656 | Values: 277.3 300.7 315.1 315.8 317.6 5330.1 5412.3 5533.0 5548.8 5671.9 4799.4 5296.7 5427.2 5495.7 5507.3 3636.8 3815.5 4174.9 4183.7 4193.8 3861.9 4166.2 4202.1 4211.0 4226.6 3418.8 3863.4 4097.7 4114.3 4118.1 3588.7 4136.5 4137.6 4155.9 4165.1 3146.1 3425.5 3569.5 3599.2 3608.8 4259.8 5282.4 5346.8 5383.5 5397.9 4008.5 4457.6 4701.8 4766.7 4775.8 4575.0 4623.9 5617.2 5897.4 5994.1 3271.6 3826.5 3917.6 3926.4 3932.0 4586.0 5954.6 6105.3 6144.7 6159.6 3345.7 3958.9 3984.7 3993.7 4003.5 3875.1 3958.8 4010.6 4045.5 4059.9 4438.8 4709.2 4749.1 4756.9 4771.5 5346.3 5767.8 5798.1 5854.7 5875.0 1166.6 1207.8 1288.6 1295.7 1361.7 887.0 895.9 912.8 919.3 922.9 4569.7 5352.6 5438.0 5447.5 5469.4 5022.2 5173.6 5506.3 5967.3 5981.0 5214.9 5223.6 5260.4 5386.4 5402.3 2207.7 2224.6 2479.3 2491.0 2680.9 4837.5 5136.4 5174.9 5175.5 5180.6 4498.5 4699.7 4805.1 4942.9 5160.9 4541.2 4587.8 5140.7 5166.0 5177.7 4310.7 4626.8 4727.6 5032.4 5101.9 4057.5 4142.5 4436.2 4753.0 5008.7 4812.7 4959.5 5150.0 5202.8 5258.8 3368.2 3380.7 3443.1 3448.1 3448.2 4336.5 4638.8 4650.9 4710.9 5049.9 4253.6 4380.8 4381.6 4808.8 4909.5 4192.0 4217.7 4333.8 4502.5 4545.5 4039.2 4439.2 4590.5 4837.7 4901.2 4457.0 4839.5 4852.6 4862.4 4886.3 4159.7 4346.7 4410.4 4522.5 4820.5 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25295.34 Median:3710.23 stddev:40666.49 (1096.1%) stddev-sans-first:40738.59 22:14:23 INFO - PROCESS | 1656 | Values: 685.8 703.9 743.6 770.1 796.0 14988.6 15558.8 15783.4 15783.6 15784.0 14623.3 14652.6 14664.3 14695.6 14708.7 670.7 813.3 846.4 858.6 872.2 844.5 861.7 863.7 864.3 866.7 884.0 928.0 928.8 932.2 932.2 899.4 912.3 922.8 924.2 927.4 678.9 695.1 695.4 696.8 697.2 15015.9 15017.8 15021.7 15031.3 15041.8 14808.7 14849.6 14851.2 14865.0 15036.5 12018.6 12337.9 12555.1 12563.9 12697.7 3829.4 3829.7 4029.8 4057.1 4058.8 117400.0 118571.0 119975.0 120249.0 121816.8 11407.6 11445.4 11521.9 11581.2 11834.6 22952.5 23652.3 23659.9 23667.6 23749.8 7283.6 8242.3 8259.6 8278.5 8351.3 4534.4 4584.8 4607.0 4634.8 4768.1 3601.1 3667.0 3684.9 3735.6 3903.9 708.2 719.5 726.3 730.5 738.6 604.8 631.7 660.5 678.9 694.2 9438.4 9543.2 9668.8 9816.7 9842.2 665.7 675.7 682.8 692.3 704.8 4561.4 4705.0 4712.5 4739.5 4758.9 97966.5 118809.0 119580.0 152478.0 162612.0 140065.0 145712.0 146110.3 148056.0 148758.0 118301.4 122771.8 122828.4 126246.4 131052.8 8662.2 9022.9 9115.9 9123.2 9136.7 36638.8 47338.6 50158.0 68616.3 70163.0 72507.9 80919.0 87264.1 89588.6 92887.5 13177.7 13755.8 13895.9 13938.7 14027.7 14314.4 15419.4 15575.5 16032.1 16124.0 13706.4 14025.4 14313.6 14316.5 14335.9 10441.0 14399.1 14423.6 14504.5 15026.3 13700.4 13835.4 14041.4 14293.9 14345.6 11189.3 11343.2 11380.0 11866.9 11948.8 8436.4 9713.0 10727.2 11437.8 11457.9 22:14:23 INFO - PROCESS | 1656 | -------- Summary: end -------- 22:14:23 INFO - PROCESS | 1656 | 22:14:23 INFO - TEST-INFO | 1656: exit 0 22:14:23 INFO - TEST-OK | dromaeo_css | took 1113512ms 22:14:23 INFO - TEST-START | kraken 22:14:23 INFO - operating with platform_type : mac_ 22:14:23 INFO - Initialising browser for kraken test... 22:14:23 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmp_ROJL_/profile http://localhost:49271/getInfo.html) 22:14:26 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 22:14:26 INFO - PROCESS | 1667 | colorDepth:24 22:14:26 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 22:14:26 INFO - PROCESS | 1667 | __metrics 22:14:26 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2983: TypeError: this.tabs is undefined 22:14:27 INFO - TEST-INFO | 1667: exit 0 22:14:27 INFO - Browser initialized. 22:14:27 INFO - Running cycle 1/1 for kraken test... 22:14:27 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmp_ROJL_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:39 INFO - PROCESS | 1669 | RSS: Main: 254787584 22:14:39 INFO - PROCESS | 1669 | 22:14:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 22:14:42 INFO - PROCESS | 1669 | RSS: Main: 267632640 22:14:42 INFO - PROCESS | 1669 | 22:14:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 22:14:46 INFO - PROCESS | 1669 | RSS: Main: 276373504 22:14:46 INFO - PROCESS | 1669 | 22:14:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 22:14:50 INFO - PROCESS | 1669 | RSS: Main: 279183360 22:14:50 INFO - PROCESS | 1669 | 22:14:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 22:14:53 INFO - PROCESS | 1669 | RSS: Main: 280473600 22:14:53 INFO - PROCESS | 1669 | 22:14:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:14:55 INFO - PROCESS | 1669 | RSS: Main: 282128384 22:14:55 INFO - PROCESS | 1669 | 22:14:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 22:14:59 INFO - PROCESS | 1669 | RSS: Main: 282185728 22:14:59 INFO - PROCESS | 1669 | 22:15:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 22:15:03 INFO - PROCESS | 1669 | RSS: Main: 282411008 22:15:03 INFO - PROCESS | 1669 | 22:15:05 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 22:15:06 INFO - PROCESS | 1669 | RSS: Main: 282460160 22:15:06 INFO - PROCESS | 1669 | 22:15:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:15:08 INFO - PROCESS | 1669 | RSS: Main: 282570752 22:15:08 INFO - PROCESS | 1669 | 22:15:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:15:11 INFO - PROCESS | 1669 | RSS: Main: 282939392 22:15:11 INFO - PROCESS | 1669 | 22:15:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:15:14 INFO - PROCESS | 1669 | RSS: Main: 282845184 22:15:14 INFO - PROCESS | 1669 | 22:15:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:15:18 INFO - PROCESS | 1669 | RSS: Main: 283058176 22:15:18 INFO - PROCESS | 1669 | 22:15:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:15:21 INFO - PROCESS | 1669 | RSS: Main: 282910720 22:15:21 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 22:15:23 INFO - PROCESS | 1669 | RSS: Main: 283803648 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | __start_tp_report 22:15:23 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 22:15:23 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 22:15:23 INFO - PROCESS | 1669 | |i|pagename|runs| 22:15:23 INFO - PROCESS | 1669 | |0;ai-astar;108;118;108;117;118;117;113;119;116;116 22:15:23 INFO - PROCESS | 1669 | |1;audio-beat-detection;142;114;133;157;109;135;126;110;152;107 22:15:23 INFO - PROCESS | 1669 | |2;audio-dft;200;201;210;236;268;205;177;182;264;283 22:15:23 INFO - PROCESS | 1669 | |3;audio-fft;69;83;77;137;62;85;82;90;151;61 22:15:23 INFO - PROCESS | 1669 | |4;audio-oscillator;81;73;115;55;67;94;151;89;104;149 22:15:23 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;88;87;92;125;89;85;89;86;112;85 22:15:23 INFO - PROCESS | 1669 | |6;imaging-darkroom;145;107;140;120;131;121;118;112;89;99 22:15:23 INFO - PROCESS | 1669 | |7;imaging-desaturate;89;95;93;92;90;90;91;95;88;108 22:15:23 INFO - PROCESS | 1669 | |8;json-parse-financial;57;56;57;56;56;56;57;55;62;53 22:15:23 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;68;74;69;62;102;116;92;72;75;74 22:15:23 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;74;85;95;103;104;83;85;86;85;75 22:15:23 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;152;156;129;157;141;136;162;138;165;162 22:15:23 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;165;169;168;164;166;166;167;170;166;168 22:15:23 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;77;69;67;66;68;65;80;89;87;91 22:15:23 INFO - PROCESS | 1669 | __end_tp_report 22:15:23 INFO - PROCESS | 1669 | __start_cc_report 22:15:23 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1067 22:15:23 INFO - PROCESS | 1669 | __end_cc_report 22:15:23 INFO - PROCESS | 1669 | __startTimestamp1470892523614__endTimestamp 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | ------- Summary: start ------- 22:15:23 INFO - PROCESS | 1669 | Number of tests: 14 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:115.00 Median:117.00 stddev:4.03 (3.4%) stddev-sans-first:3.38 22:15:23 INFO - PROCESS | 1669 | Values: 108.0 118.0 108.0 117.0 118.0 117.0 113.0 119.0 116.0 116.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:134.00 stddev:18.29 (13.6%) stddev-sans-first:18.73 22:15:23 INFO - PROCESS | 1669 | Values: 142.0 114.0 133.0 157.0 109.0 135.0 126.0 110.0 152.0 107.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:222.60 Median:223.00 stddev:37.68 (16.9%) stddev-sans-first:39.07 22:15:23 INFO - PROCESS | 1669 | Values: 200.0 201.0 210.0 236.0 268.0 205.0 177.0 182.0 264.0 283.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:89.70 Median:79.50 stddev:30.37 (38.2%) stddev-sans-first:31.28 22:15:23 INFO - PROCESS | 1669 | Values: 69.0 83.0 77.0 137.0 62.0 85.0 82.0 90.0 151.0 61.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:70.00 stddev:32.58 (46.5%) stddev-sans-first:33.99 22:15:23 INFO - PROCESS | 1669 | Values: 81.0 73.0 115.0 55.0 67.0 94.0 151.0 89.0 104.0 149.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:87.50 stddev:13.54 (15.5%) stddev-sans-first:14.20 22:15:23 INFO - PROCESS | 1669 | Values: 88.0 87.0 92.0 125.0 89.0 85.0 89.0 86.0 112.0 85.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:118.20 Median:135.50 stddev:17.49 (12.9%) stddev-sans-first:15.63 22:15:23 INFO - PROCESS | 1669 | Values: 145.0 107.0 140.0 120.0 131.0 121.0 118.0 112.0 89.0 99.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:91.50 stddev:5.74 (6.3%) stddev-sans-first:5.90 22:15:23 INFO - PROCESS | 1669 | Values: 89.0 95.0 93.0 92.0 90.0 90.0 91.0 95.0 88.0 108.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.50 stddev:2.27 (4.0%) stddev-sans-first:2.40 22:15:23 INFO - PROCESS | 1669 | Values: 57.0 56.0 57.0 56.0 56.0 56.0 57.0 55.0 62.0 53.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:80.40 Median:73.00 stddev:17.23 (23.6%) stddev-sans-first:17.68 22:15:23 INFO - PROCESS | 1669 | Values: 68.0 74.0 69.0 62.0 102.0 116.0 92.0 72.0 75.0 74.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:85.00 stddev:10.27 (12.1%) stddev-sans-first:9.66 22:15:23 INFO - PROCESS | 1669 | Values: 74.0 85.0 95.0 103.0 104.0 83.0 85.0 86.0 85.0 75.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:156.50 stddev:12.75 (8.1%) stddev-sans-first:13.50 22:15:23 INFO - PROCESS | 1669 | Values: 152.0 156.0 129.0 157.0 141.0 136.0 162.0 138.0 165.0 162.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.50 stddev:1.85 (1.1%) stddev-sans-first:1.83 22:15:23 INFO - PROCESS | 1669 | Values: 165.0 169.0 168.0 164.0 166.0 166.0 167.0 170.0 166.0 168.0 22:15:23 INFO - PROCESS | 1669 | 22:15:23 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.90 Median:78.50 stddev:10.26 (13.1%) stddev-sans-first:10.87 22:15:23 INFO - PROCESS | 1669 | Values: 77.0 69.0 67.0 66.0 68.0 65.0 80.0 89.0 87.0 91.0 22:15:23 INFO - PROCESS | 1669 | -------- Summary: end -------- 22:15:23 INFO - PROCESS | 1669 | 22:15:24 INFO - TEST-INFO | 1669: exit 0 22:15:24 INFO - TEST-OK | kraken | took 60669ms 22:15:24 INFO - SUITE-END | took 1174s 22:15:24 INFO - Completed test suite (00:19:34) 22:15:24 INFO - javascript benchmark 22:15:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7333.416169125126, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.8827191048304, 3350.949735753964, 3377.8986630401096, 3411.2324726936445, 3448.689617934386, 5224.529792318695, 5257.737113144337, 5408.810691625794, 5690.0, 5947.137665038566, 5758.798442054529, 5769.221155144054, 5785.652860828353, 5795.0, 5917.964356237662, 5168.457311982234, 5242.318498595197, 5250.6587071840395, 5322.121849894782, 5378.139497680372, 5318.25544423781, 5400.756965936523, 5416.972915135439, 5429.0227759003365, 5440.374356948962, 4219.346001369792, 5017.799288028493, 5143.996920600484, 5197.0, 5323.893522129555, 5229.5293423591675, 5235.895282094337, 5249.737513124367, 5259.86850328741, 5298.947010529884, 5220.451852555465, 5247.422783493813, 5278.340207474066, 5300.257963885059, 5324.787008519655, 5699.715014249313, 5777.711114444261, 5877.764889404419, 5902.114682797572, 5916.408359164092, 5503.229547863302, 6172.197614310156, 6195.721192546346, 6220.253569571635, 6231.189945307126, 5076.416212135615, 5279.577633789288, 6293.527985401113, 6304.0, 6338.176037115146, 5763.884722305573, 5847.853803654943, 5897.911531327033, 5911.9113213301835, 5993.460588547052, 5254.290670759475, 6195.721192546346, 6263.248410190807, 6274.937250627527, 6278.434940855253, 5700.315962084494, 6158.322584515699, 6167.938320616826, 6195.938040619626, 6200.255969283625, 5191.506806853343, 5716.88520738452, 5956.136360227742, 6124.014786914908, 6161.075838624152, 6134.35589263121, 6180.907286390708, 6252.343503932113, 6252.406021427957, 6255.093011513396, 5464.562834973192, 5900.793472228537, 6135.07973803933, 6172.5679202456295, 6214.502839772807, 4684.461286951976, 4943.209086546135, 5027.547520723152, 5040.747962601855, 5057.974710126426, 4384.430024096847, 4407.537208593116, 4447.666425018137, 4464.687471877002, 4518.706284091523, 5579.581531385163, 5601.0, 5711.628744131701, 5727.541796656257, 5735.741891614887, 6149.831532008904, 6188.628682278988, 6208.751649933952, 6218.720157592919, 6240.875182496325, 5856.795012174639, 5899.941000589939, 5926.822195334147, 5931.940680593225, 5938.703064846741, 5513.724313784295, 5513.924784667031, 5520.227168196456, 5521.337439507285, 5546.417626149197, 6209.720562574605, 6263.749450021948, 6351.174347334817, 6410.775622853178, 6421.0, 5190.532852043334, 6207.937920620736, 6226.750929962751, 6231.096491008869, 6233.750649974132, 6354.301026886947, 6372.0760489729655, 6386.712597933011, 6400.55196136266, 6428.357164283534, 6094.268687757498, 6111.07520520229, 6208.565400422108, 6214.098955651496, 6239.875202496016, 6146.877062458636, 6185.134081228542, 6212.968935155295, 6225.968870155621, 6229.065640154013, 5153.896922061463, 6168.475679567287, 6199.225096862892, 6232.189815323979, 6236.906446403398, 6070.2412198475195, 6125.388069147376, 6198.411150940653, 6216.720247588779, 6255.499560035095, 5245.344331958505, 5593.804216852391, 6050.334463209131, 6104.908426373519, 6141.263048434216, 6057.575969682256, 6160.229971253593, 6205.069239614094, 6220.7511699531515, 6231.1276421300145, 5943.910841337297, 6064.939350606437, 6094.11635312886, 6164.137020816999, 6181.443670069535], "value": 5681.814345475428, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.15582885778747, 528.986989914314, 538.5906710899715, 540.2571464236675, 610.0300522169755, 28663.713362866365, 41889.0, 41968.79015604922, 41997.37003944943, 42035.738927832135, 42672.35991460131, 43312.78343608278, 43325.70022899318, 43377.0, 43414.0, 8090.838183236332, 8881.245094167003, 9604.759881002961, 9767.853482197772, 9818.11636952674, 9028.8645670315, 9587.616495340179, 9600.1359877611, 9626.75933101671, 9700.078492543233, 9663.661771837988, 9707.0, 9709.61161553537, 9718.56266468007, 9738.853917191247, 8013.479123856989, 8077.7576672699915, 8532.658693652247, 9650.986646402167, 9686.75783105421, 8171.387145964077, 8359.83280334396, 9677.12905838471, 9734.0, 9760.072793084646, 40374.0, 40632.39051414231, 40723.778286651446, 40744.0, 40819.97950051272, 2514.861682607456, 3164.829013265107, 3215.7266632336045, 3314.784539004957, 3552.875649352286, 29321.41357172866, 30447.847760761055, 31036.844815775778, 31064.689353106405, 31091.0, 3102.348506813549, 3112.25305926576, 3155.4477966356026, 3182.236263296815, 3203.6315823680343, 387734.0, 446060.0, 450601.74699126295, 450773.0, 451163.4883651154, 34348.48477272843, 34491.655083448844, 34563.308733825186, 34580.13549661228, 34596.0, 34802.82598586991, 35164.12089697778, 35206.11984700403, 35687.0, 36739.816300918326, 16395.508134755975, 16538.0, 16799.99200048001, 16897.662046758996, 17058.829411705974, 12868.0, 13015.349232538336, 13035.543755968496, 13043.478260869459, 13080.018998574955, 16120.0, 19818.20727170926, 19874.50313742139, 19999.60000800005, 20198.697019544426, 6849.349311815369, 8114.43198976066, 8225.917740822635, 8229.917700823034, 8279.461834980708, 881.2685471059006, 922.7324076017879, 951.7192428233687, 989.302541708094, 1028.9382637041801, 30879.536806947915, 31071.533926991113, 31128.53307200394, 31847.0, 32383.67616323854, 1040.5213601743133, 1080.9027187553158, 1127.8026345389524, 1153.8211577205548, 1233.8457692788402, 15829.287682054335, 16241.106739129462, 16319.020858748512, 16500.4224852132, 16574.66850663004, 689076.0, 773476.1326193445, 788981.0, 793424.0, 1022977.0, 62243.0, 63001.0, 63236.367636323965, 63272.0, 63431.04853427202, 1172.60717659585, 1325.827642406481, 1333.6665833541615, 1353.9052266341262, 1387.5559820857432, 31679.0, 32616.0, 32672.83663581762, 33094.50358244676, 33276.66723332833, 54127.72936135294, 54810.62973425696, 54850.0, 55006.0, 55184.172237417275, 640433.0, 641090.0, 646657.0, 647765.0, 648909.0, 28079.0, 28390.290242744097, 28499.0, 28512.144635660963, 28517.714822851507, 49380.25929610987, 49407.01185976332, 49609.50390496049, 50831.0, 50834.98330033305, 40524.79737601293, 40749.79625101974, 41227.0, 41779.58220417757, 42331.3650295252, 32935.50596741003, 34388.82805585956, 36562.7203047892, 40442.39336410015, 41966.0, 34106.31787364288, 38675.6132438672, 39208.80395598004, 39389.80305098456, 40439.1912161761, 31340.216494587818, 35058.64941350656, 35471.0, 36024.459633106046, 36088.0, 21007.949602519508, 25338.74661253364, 26592.734072659026, 26737.465250694488, 26962.865185673945], "value": 19755.630998647608, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.53305772744966, 367.5167155190924, 369.123332086295, 385.8109526332098, 396.14630471334283, 14771.852281477182, 18905.527361815944, 19114.04429778511, 20362.083706233218, 20557.280495182633, 19757.901210493965, 19842.30551930683, 20307.59384812303, 20738.792612073914, 20776.0, 4517.977410112945, 5916.408359164081, 6121.479674227674, 6139.201903752506, 6388.840278993016, 5946.702664866761, 5977.551683623724, 6025.487833534155, 6097.054956481752, 6108.694565271719, 5918.704064796764, 5922.170896074532, 6053.455189032986, 6056.394360563931, 6058.1518587397795, 5887.499562537189, 5938.0, 5984.790532331369, 5999.730012149442, 6108.633481991051, 5140.126178549655, 5203.505666961632, 5229.947700523022, 5244.60665450089, 5267.631265811395, 25370.985160593555, 25462.490750184894, 25483.362915927064, 25827.35431614206, 25850.482990340275, 2258.5482903419384, 2445.193086281523, 2768.1557125076843, 2846.3453405716596, 2919.7518210952094, 13836.723265534632, 13843.377048032862, 14919.731822795075, 15325.23373831304, 15837.0497770133, 1860.57206842427, 1866.5333666583354, 1868.962620747591, 1899.7530321058312, 1933.4006457998066, 114516.42741786237, 119698.0, 121651.17523237246, 121905.0, 123912.0, 7860.5676687782725, 8963.745075689409, 8976.730698079067, 11047.0, 11070.169737269576, 13601.659958500919, 13623.046386752832, 13836.031477796458, 13853.792193117111, 13856.960727945445, 8404.747857564284, 8555.358348123791, 8591.226789588967, 8605.784855378666, 8698.347623928104, 8577.614007369682, 8584.613692383859, 8705.47767133974, 8831.24934380572, 8954.104589541124, 5527.419620939824, 6558.376954189344, 6564.80305590833, 6659.001149827564, 6769.39075483199, 1822.0, 1844.9354272600394, 1847.6674198644373, 1847.9466703978603, 1858.1823997441084, 882.4661080046479, 940.6472572785206, 947.8009617980164, 1011.2920955331298, 1025.3386565665253, 20722.274720384714, 21570.56858862814, 21873.0, 21897.562048759253, 21958.560828783335, 946.0823001688351, 979.6081567373108, 982.9557669904872, 995.382862625177, 1016.2733645443543, 9556.23550115989, 11151.832722509169, 11633.59282425128, 11686.532538698357, 11690.532378704756, 620926.0, 623299.0, 634501.0, 639693.0, 641519.0, 86922.0, 107866.0, 114102.85897141088, 114706.0, 118555.8144418562, 1015.4364327798143, 1056.6777132974516, 1095.7863216672672, 1096.0792933935531, 1099.1261946752352, 18539.81460185381, 19013.71479427782, 19171.904140479208, 19174.520636984187, 19281.614367712285, 49252.507474924794, 50631.24053139132, 50755.74622126866, 51253.74373128259, 52682.0, 67028.32429189114, 73850.26149738434, 75067.99456043394, 75829.6208518954, 78340.60829695815, 6155.35368786271, 6373.330800266091, 7719.228077192236, 8149.410864881292, 8391.706290280055, 11841.703957400789, 13027.804582931074, 13042.347882606022, 13250.072494925264, 13314.800277996032, 8408.87386689188, 11485.598004069818, 11504.309741415542, 11554.0, 11596.884031159918, 10823.458827058457, 11081.889181108085, 11224.382658953837, 11273.098152147972, 11595.652130436101, 11055.391953442317, 11670.416479175838, 11853.229540079867, 11918.702032449259, 11962.58130965412, 8217.917820821714, 8296.460730052528, 8381.53901535397, 8387.370947178864, 8401.789955250923, 9765.560549775135, 9993.400395976265, 10701.357918524913, 11456.541738330374, 11612.187146899636], "value": 9717.980671473711, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1515.5680631020161, 1542.87656987441, 1655.271680460598, 1749.3177660712324, 1864.0, 7874.803129921755, 8413.579321033947, 8686.348523860719, 8976.73069807905, 9001.549922503873, 8630.95684521578, 8766.34252431067, 8867.60095795689, 8881.955590222056, 8893.555322233879, 2334.801541868939, 2346.765323467657, 2378.726446458662, 2379.2981070584133, 2386.761323867608, 2162.4593851537115, 2328.1502251678185, 2356.9410764730846, 2361.95276094478, 2367.6922000139957, 2317.1310758465575, 2340.6840076589697, 2350.2949115265437, 2373.5134297469062, 2384.153625462962, 2122.5224324526935, 2205.007746514067, 2335.065973610552, 2345.3198572413894, 2369.644553316999, 2344.2146880794953, 2354.376090336062, 2355.128602417117, 2356.446235134742, 2366.148186652804, 8803.987541432689, 8887.15572020657, 8990.730278091667, 8992.280617550581, 9013.278937685034, 5061.569766569846, 5156.252343410185, 5225.608079394023, 5240.97800928819, 5247.527722504993, 3395.7962522248745, 3469.271452994874, 3533.3463309287863, 3566.5185199997927, 3607.963920360789, 936.180841763457, 1030.6959446963165, 1067.3489171605315, 1093.8085834978847, 1094.3543309447464, 11654.0, 11690.766184676258, 11733.650630177553, 11784.528618855236, 11801.704957376049, 4557.225271703818, 4696.225122854759, 4805.3512775775525, 4816.20532612115, 4820.975895120502, 9812.705618831447, 9959.651412200537, 9966.053224943618, 9966.40201587907, 9970.900290997142, 5236.371635403776, 5345.305110335631, 5350.1439769637445, 5361.115415956401, 5374.758135883894, 4341.978290108529, 4755.239161734106, 4872.6589138760655, 4881.365422495053, 4924.384451943507, 3832.616738326145, 3886.8833934981994, 3959.584243654375, 3963.9405408918888, 3996.660283875845, 1143.0683992546049, 1145.438735019851, 1150.4707834396272, 1153.7173392518887, 1156.9479373428217, 859.703402326209, 875.7547886591765, 883.5052370672444, 913.1735779119907, 942.4157022646003, 5358.5981051421295, 5499.367572729107, 5971.940280597225, 6074.119252708332, 6113.877722445615, 955.2644463762864, 968.1045033344128, 971.3055165556661, 973.634886917406, 1025.2105878473683, 3101.8138911665374, 3278.721308688788, 3872.6901847852105, 4067.3085575452246, 4108.137291168829, 10633.893661063445, 10908.181886358554, 11043.0, 11056.336619802838, 11086.501107450184, 10656.57373705043, 10984.0, 10998.395088270221, 11005.284656497302, 11020.283681560833, 7786.922130778733, 8186.222308880766, 8273.1726827318, 8404.28563572091, 8421.0, 5805.332386775575, 6034.456898879122, 6061.484773794101, 6168.136460895388, 6214.533909956862, 9787.412755234709, 10064.798704026025, 10093.243006774668, 10130.885602583858, 10133.949330253301, 9899.65351212704, 10168.593256269667, 10169.0, 10230.181585473294, 10248.282620216512, 7000.404965577983, 7410.5183163094225, 7622.8094297643, 7626.923730762844, 7681.807954801175, 8423.368247381348, 8491.23578877904, 8508.787280318042, 8544.35917306217, 8575.442596231225, 5479.3972663007835, 7449.180590134973, 7600.467967242461, 7686.3466605336935, 7711.421643376882, 7206.855862882818, 7306.305900939402, 7403.1116266046265, 7447.255274472509, 7529.736459223901, 6984.720611175496, 7015.36861682451, 7096.467764917755, 7100.7869763907165, 7111.7866464006165, 5036.420811606712, 5074.010567939216, 5115.897682046412, 5117.104506711236, 5182.637215394886, 4327.567243275647, 4960.305557222064, 5030.647854650139, 5052.216906379517, 5247.081760691787], "value": 4584.140206802644, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.2984349595523, 300.6557491672037, 315.14124012067134, 315.8219839996812, 317.5744502366828, 5330.095098171496, 5412.323459567554, 5532.972335138324, 5548.778048878045, 5671.901396483302, 4799.37608110946, 5296.708681022542, 5427.158790387486, 5495.697736624484, 5507.256520369749, 3636.763610365331, 3815.4849095372188, 4174.895627609304, 4183.686223533232, 4193.804765641793, 3861.864834730785, 4166.166766646667, 4202.117555313388, 4210.968312763362, 4226.590843219366, 3418.7521554632503, 3863.362545180056, 4097.651699605537, 4114.341705327149, 4118.135191609766, 3588.7308451866083, 4136.524299705542, 4137.586241375839, 4155.896102597429, 4165.063245590784, 3146.05618314507, 3425.520427140202, 3569.4824250483794, 3599.1541987632886, 3608.8376023079027, 4259.76571288579, 5282.3925248596515, 5346.812861549866, 5383.496674046587, 5397.948779463818, 4008.498937632796, 4457.576530229623, 4701.75403518068, 4766.666333356667, 4775.832845850413, 4574.9542504574865, 4623.86128416148, 5617.157426386075, 5897.380775018647, 5994.070919007517, 3271.6401195868434, 3826.521684789401, 3917.6278253565865, 3926.371780515104, 3932.0, 4586.01400698848, 5954.5534084943365, 6105.267367915879, 6144.692765361714, 6159.568830181889, 3345.6988871001727, 3958.940615890764, 3984.7248880358006, 3993.7004724645767, 4003.4795476587856, 3875.147467557133, 3958.802059897051, 4010.5588385277597, 4045.4943132108488, 4059.91880162401, 4438.823711716398, 4709.152352576501, 4749.073930583572, 4756.93073513023, 4771.5228477152, 5346.278252435949, 5767.798127065533, 5798.072308430716, 5854.677992710442, 5875.0, 1166.6091859227083, 1207.8465065862256, 1288.6262983734612, 1295.727897141592, 1361.6731984323692, 887.0, 895.9372843900865, 912.7586482383953, 919.3334832246568, 922.8615707643772, 4569.748663823521, 5352.5548102012235, 5437.972810135924, 5447.455254474521, 5469.425710300441, 5022.162478199805, 5173.5602473789395, 5506.256655351556, 5967.31375891778, 5980.979605299296, 5214.93093915754, 5223.64670238647, 5260.421353651095, 5386.407495175527, 5402.324709411324, 2207.6864265761847, 2224.6329355656458, 2479.256223133053, 2491.0, 2680.852553109559, 4837.540433658797, 5136.43499215086, 5174.861530463292, 5175.45657705943, 5180.585553155738, 4498.548291181314, 4699.684088455238, 4805.05395314898, 4942.937268487292, 5160.8967820643375, 4541.206223541682, 4587.816487340469, 5140.717260550631, 5166.0, 5177.663451875616, 4310.698251122517, 4626.75077729018, 4727.621790256702, 5032.370953630796, 5101.8724531887, 4057.4928133983253, 4142.502899652061, 4436.245838207448, 4752.952470475251, 5008.749562521933, 4812.735299558418, 4959.504049595012, 5150.0, 5202.791888324425, 5258.763355648928, 3368.2253081791814, 3380.695737383647, 3443.1220038889924, 3448.0690213641674, 3448.2241495663275, 4336.50130235027, 4638.776050644769, 4650.906981860277, 4710.92933606003, 5049.9495005049475, 4253.574642535722, 4380.846670366522, 4381.649468042613, 4808.763924774997, 4909.471622114543, 4192.0, 4217.746935183899, 4333.761643109659, 4502.527234640315, 4545.522720114341, 4039.2123535910214, 4439.202123140133, 4590.472095709036, 4837.7097374157665, 4901.235555200054, 4456.96363109684, 4839.548135559388, 4852.587530059985, 4862.41651001882, 4886.267059941038, 4159.667226621803, 4346.673999450117, 4410.4199043559875, 4522.502524722212, 4820.517948205152], "value": 3879.5341346257846, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.8011176758737, 703.9190493093296, 743.6430513353587, 770.114368476252, 796.0248695348198, 14988.5503434897, 15558.844411555909, 15783.447579334726, 15783.605409864758, 15784.0, 14623.268836558156, 14652.560423187322, 14664.340104695288, 14695.632609184775, 14708.705825883502, 670.6620292516412, 813.3290035720554, 846.4032856835946, 858.6264974735979, 872.1670804381832, 844.4975239732349, 861.6854847980504, 863.7063398444527, 864.2999170671744, 866.7486428935632, 883.9646414143426, 927.9582418791136, 928.7956649537125, 932.202966463674, 932.244881645869, 899.4423457456386, 912.2701838529216, 922.7923717163652, 924.2005665099674, 927.4110939553365, 678.9287124851918, 695.1102588686489, 695.3706895259797, 696.8397268628194, 697.2086681616352, 15015.924920375328, 15017.7747333789, 15021.69956600862, 15031.323590438456, 15041.774373384407, 14808.703825923421, 14849.628759280997, 14851.183184924825, 14865.0, 15036.473723419627, 12018.639440816702, 12337.876621233852, 12555.121141520098, 12563.937180314131, 12697.746045079046, 3829.3681542545446, 3829.7127715421457, 4029.8388064477385, 4057.1277175407135, 4058.8376464941366, 117400.0, 118571.0, 119975.0, 120249.0, 121816.78183218231, 11407.600733974272, 11445.427728613537, 11521.884781152248, 11581.189316747916, 11834.644960651194, 22952.540949180922, 23652.290431286743, 23659.881700591388, 23667.644985325234, 23749.762502374757, 7283.599402032939, 8242.340612751086, 8259.587020649064, 8278.503289802511, 8351.331893448509, 4534.410526631583, 4584.770761461914, 4606.95393046072, 4634.814607415666, 4768.070226305837, 3601.1177261571083, 3666.9633303667156, 3684.9078773030888, 3735.551733791908, 3903.902402439962, 708.1891234536427, 719.4747834081151, 726.3390314813495, 730.5434103685196, 738.5974643819131, 604.8457643300931, 631.7220423013952, 660.5376236634376, 678.9083473730983, 694.1669996004814, 9438.433693978383, 9543.188828949478, 9668.806623867484, 9816.65641702537, 9842.163416109712, 665.7436886798617, 675.7060678604822, 682.8292926768308, 692.2800287700835, 704.7744721689011, 4561.407017087758, 4704.988427488107, 4712.505186955389, 4739.478657347758, 4758.928616070762, 97966.5305020411, 118809.0, 119580.0, 152478.0, 162612.0, 140065.0, 145712.0, 146110.26944865208, 148056.0, 148758.0, 118301.40849295698, 122771.77228227782, 122828.38585807192, 126246.36876815557, 131052.79209643371, 8662.224244030085, 9022.909770902206, 9115.863262050942, 9123.224525915371, 9136.682422995495, 36638.8168059158, 47338.633068345756, 50158.0, 68616.313836861, 70163.0, 72507.91238131537, 80919.0, 87264.1273587256, 89588.5520572393, 92887.53556232176, 13177.670558236121, 13755.79366309526, 13895.930520347334, 13938.651533711738, 14027.719445610826, 14314.355853986805, 15419.383224670888, 15575.454859079879, 16032.11823349727, 16124.0, 13706.38321275525, 14025.3688584016, 14313.64215894611, 14316.498922537661, 14335.92832035833, 10441.0, 14399.136051836504, 14423.567292981228, 14504.492342767953, 15026.323815428113, 13700.3834827435, 13835.377408016462, 14041.438342466186, 14293.857061429668, 14345.569632911029, 11189.328640281608, 11343.20597558163, 11380.0, 11866.940665296963, 11948.76102477963, 8436.409451338593, 9713.0, 10727.19546034035, 11437.771244575228, 11457.885421145682], "value": 8016.742255328445, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 108.0, 117.0, 118.0, 117.0, 113.0, 119.0, 116.0, 116.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 133.0, 157.0, 109.0, 135.0, 126.0, 110.0, 152.0, 107.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 201.0, 210.0, 236.0, 268.0, 205.0, 177.0, 182.0, 264.0, 283.0], "value": 222.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 83.0, 77.0, 137.0, 62.0, 85.0, 82.0, 90.0, 151.0, 61.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 115.0, 55.0, 67.0, 94.0, 151.0, 89.0, 104.0, 149.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 92.0, 125.0, 89.0, 85.0, 89.0, 86.0, 112.0, 85.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 107.0, 140.0, 120.0, 131.0, 121.0, 118.0, 112.0, 89.0, 99.0], "value": 118.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 93.0, 92.0, 90.0, 90.0, 91.0, 95.0, 88.0, 108.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 56.0, 56.0, 56.0, 57.0, 55.0, 62.0, 53.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 69.0, 62.0, 102.0, 116.0, 92.0, 72.0, 75.0, 74.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 85.0, 95.0, 103.0, 104.0, 83.0, 85.0, 86.0, 85.0, 75.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 156.0, 129.0, 157.0, 141.0, 136.0, 162.0, 138.0, 165.0, 162.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 168.0, 164.0, 166.0, 166.0, 167.0, 170.0, 166.0, 168.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 69.0, 67.0, 66.0, 68.0, 65.0, 80.0, 89.0, 87.0, 91.0], "value": 75.9, "unit": "score"}]}]} 22:15:24 INFO - Return code: 0 22:15:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:15:24 ERROR - # TBPL SUCCESS # 22:15:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:15:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:15:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:15:24 INFO - 2016-08-10 22:15:24.921 screenresolution[1674:10269] starting screenresolution argv=screenresolution get 22:15:24 INFO - 2016-08-10 22:15:24.924 screenresolution[1674:10269] Display 0: 1600x1200x32@60 22:15:24 INFO - 2016-08-10 22:15:24.929 screenresolution[1675:10272] starting screenresolution argv=screenresolution list 22:15:24 INFO - Available Modes on Display 0 22:15:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:15:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:15:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:15:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:15:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:15:25 INFO - Intel Iris: 22:15:25 INFO - Chipset Model: Intel Iris 22:15:25 INFO - Type: GPU 22:15:25 INFO - Bus: Built-In 22:15:25 INFO - VRAM (Dynamic, Max): 1536 MB 22:15:25 INFO - Vendor: Intel (0x8086) 22:15:25 INFO - Device ID: 0x0a2e 22:15:25 INFO - Revision ID: 0x0009 22:15:25 INFO - Displays: 22:15:25 INFO - Display: 22:15:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:15:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:15:25 INFO - Display Serial Number: 1600x1200 60 22:15:25 INFO - Main Display: Yes 22:15:25 INFO - Mirror: Off 22:15:25 INFO - Online: Yes 22:15:25 INFO - Rotation: Supported 22:15:25 INFO - Return code: 0 22:15:25 INFO - Running post-action listener: _resource_record_post_action 22:15:25 INFO - [mozharness: 2016-08-11 05:15:25.164518Z] Finished run-tests step (success) 22:15:25 INFO - Running post-run listener: _resource_record_post_run 22:15:25 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 36824064; Write bytes: 703222272; Read time: 244; Write time: 1989 22:15:25 INFO - TinderboxPrint: CPU usage
54.4% 22:15:25 INFO - TinderboxPrint: I/O read bytes / time
36,824,064 / 244 22:15:25 INFO - TinderboxPrint: I/O write bytes / time
703,222,272 / 1,989 22:15:25 INFO - TinderboxPrint: CPU idle
2,203.7 (45.6%) 22:15:25 INFO - TinderboxPrint: CPU system
976.4 (20.2%) 22:15:25 INFO - TinderboxPrint: CPU user
1,657.5 (34.3%) 22:15:25 INFO - TinderboxPrint: Swap in / out
440,848,384 / 0 22:15:25 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397675008; Write bytes: 391751168; Read time: 29412; Write time: 833 22:15:25 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34604032; Write bytes: 295693312; Read time: 214; Write time: 1115 22:15:25 INFO - Running post-run listener: _upload_blobber_files 22:15:25 INFO - Blob upload gear active. 22:15:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:15:25 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 22:15:25 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:15:25 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:15:26 INFO - (blobuploader) - INFO - Open directory for files ... 22:15:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:15:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:15:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:27 INFO - (blobuploader) - INFO - Done attempting. 22:15:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:15:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:15:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:28 INFO - (blobuploader) - INFO - Done attempting. 22:15:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:15:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:15:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:15:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:15:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:15:30 INFO - (blobuploader) - INFO - Done attempting. 22:15:30 INFO - (blobuploader) - INFO - Iteration through files over. 22:15:30 INFO - Return code: 0 22:15:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:15:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:15:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3d54e5c17502bb3e37552a611061aab80aa62acb1dbd6b5c3937accbad592aa8356cbe335c4ebdf77d1c1ed55713ab83bc114af3c22f0015e636a4fb3d1900b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e74e7b741f53fb5180be82def96515a55dc80a7b3c6baa3543aba4adf41b9db9f4d2f564f8d41c550f3c0ed92c75b619a4d7221f968e965e3bb9c6895a2e5aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7eee75baa39e1be32d11ee4cb49a7a79e4015ec8b4a55f811ceeba628c597a50a4ea1d046cfa35cdd94821640ec45b1c83ddf993a1b233b3138495b5a267c60d"} 22:15:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:15:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:15:30 INFO - Contents: 22:15:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3d54e5c17502bb3e37552a611061aab80aa62acb1dbd6b5c3937accbad592aa8356cbe335c4ebdf77d1c1ed55713ab83bc114af3c22f0015e636a4fb3d1900b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e74e7b741f53fb5180be82def96515a55dc80a7b3c6baa3543aba4adf41b9db9f4d2f564f8d41c550f3c0ed92c75b619a4d7221f968e965e3bb9c6895a2e5aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7eee75baa39e1be32d11ee4cb49a7a79e4015ec8b4a55f811ceeba628c597a50a4ea1d046cfa35cdd94821640ec45b1c83ddf993a1b233b3138495b5a267c60d"} 22:15:30 INFO - Running post-run listener: copy_logs_to_upload_dir 22:15:30 INFO - Copying logs to upload dir... 22:15:30 INFO - mkdir: /builds/slave/test/build/upload/logs 22:15:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.940042 ========= master_lag: 2.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-08-10 22:15:32.795915) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 22:15:32.800990) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3d54e5c17502bb3e37552a611061aab80aa62acb1dbd6b5c3937accbad592aa8356cbe335c4ebdf77d1c1ed55713ab83bc114af3c22f0015e636a4fb3d1900b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e74e7b741f53fb5180be82def96515a55dc80a7b3c6baa3543aba4adf41b9db9f4d2f564f8d41c550f3c0ed92c75b619a4d7221f968e965e3bb9c6895a2e5aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7eee75baa39e1be32d11ee4cb49a7a79e4015ec8b4a55f811ceeba628c597a50a4ea1d046cfa35cdd94821640ec45b1c83ddf993a1b233b3138495b5a267c60d"} build_url:https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010994 build_url: 'https://queue.taskcluster.net/v1/task/Rge957_bTMa8qtmC-x4fMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3d54e5c17502bb3e37552a611061aab80aa62acb1dbd6b5c3937accbad592aa8356cbe335c4ebdf77d1c1ed55713ab83bc114af3c22f0015e636a4fb3d1900b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2e74e7b741f53fb5180be82def96515a55dc80a7b3c6baa3543aba4adf41b9db9f4d2f564f8d41c550f3c0ed92c75b619a4d7221f968e965e3bb9c6895a2e5aa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7eee75baa39e1be32d11ee4cb49a7a79e4015ec8b4a55f811ceeba628c597a50a4ea1d046cfa35cdd94821640ec45b1c83ddf993a1b233b3138495b5a267c60d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 22:15:32.844884) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 22:15:32.845221) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Jiuj4eM5fk/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Ih8DCW7w5U/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005182 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 22:15:32.893489) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 22:15:32.896841) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 22:15:32.897184) ========= ========= Total master_lag: 2.45 =========