builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0284 starttime: 1470872599.69 results: success (0) buildid: 20160810154121 builduid: bfb45519fa674bb2a3a18f5e9990881c revision: 30c27c9a8365307b596a0d2a79570e9737ca4106 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:19.690843) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:19.691266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:19.691583) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:19.716014) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:19.716382) ========= 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.WzMUl1h6ps/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.QYCE31XvkA/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:19-- 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% 18.2M=0.001s 2016-08-10 16:43:20 (18.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.379375 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.117835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.118148) ========= 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.WzMUl1h6ps/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.QYCE31XvkA/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.025214 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.166120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.166468) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 30c27c9a8365307b596a0d2a79570e9737ca4106 --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 30c27c9a8365307b596a0d2a79570e9737ca4106 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WzMUl1h6ps/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.QYCE31XvkA/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:20,248 truncating revision to first 12 chars 2016-08-10 16:43:20,248 Setting DEBUG logging. 2016-08-10 16:43:20,248 attempt 1/10 2016-08-10 16:43:20,248 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/30c27c9a8365?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 16:43:20,630 unpacking tar archive at: autoland-30c27c9a8365/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651572 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.839406) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.839737) ========= script_repo_revision: 30c27c9a8365307b596a0d2a79570e9737ca4106 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.840115) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.840380) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 16:43:20.857882) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-10 16:43:20.858158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.WzMUl1h6ps/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.QYCE31XvkA/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:21 INFO - MultiFileLogger online at 20160810 16:43:21 in /builds/slave/test 16:43:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 16:43:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:43:21 INFO - {'append_to_log': False, 16:43:21 INFO - 'base_work_dir': '/builds/slave/test', 16:43:21 INFO - 'blob_upload_branch': 'Autoland', 16:43:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:43:21 INFO - 'branch': 'Autoland', 16:43:21 INFO - 'buildbot_json_path': 'buildprops.json', 16:43:21 INFO - 'config_files': ('talos/mac_config.py',), 16:43:21 INFO - 'default_actions': ('clobber', 16:43:21 INFO - 'read-buildbot-config', 16:43:21 INFO - 'download-and-extract', 16:43:21 INFO - 'populate-webroot', 16:43:21 INFO - 'create-virtualenv', 16:43:21 INFO - 'install', 16:43:21 INFO - 'run-tests'), 16:43:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:43:21 INFO - 'download_minidump_stackwalk': True, 16:43:21 INFO - 'download_symbols': 'ondemand', 16:43:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:43:21 INFO - 'tooltool.py': '/tools/tooltool.py', 16:43:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:43:21 INFO - '/tools/misc-python/virtualenv.py')}, 16:43:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:43:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:43:21 INFO - 'installer_path': 'installer.exe', 16:43:21 INFO - 'log_level': 'info', 16:43:21 INFO - 'log_name': 'talos', 16:43:21 INFO - 'log_to_console': True, 16:43:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:43:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:43:21 INFO - 'opt_config_files': (), 16:43:21 INFO - 'pip_index': False, 16:43:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:43:21 INFO - 'cmd': ('bash', 16:43:21 INFO - '-c', 16:43:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:43:21 INFO - 'enabled': True, 16:43:21 INFO - 'halt_on_failure': False, 16:43:21 INFO - 'name': 'check_screen_resolution'},), 16:43:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:43:21 INFO - 'cmd': ('bash', 16:43:21 INFO - '-c', 16:43:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:43:21 INFO - 'enabled': True, 16:43:21 INFO - 'halt_on_failure': False, 16:43:21 INFO - 'name': 'check_screen_resolution'},), 16:43:21 INFO - 'run_cmd_checks_enabled': True, 16:43:21 INFO - 'sps_profile': False, 16:43:21 INFO - 'sps_profile_interval': 0, 16:43:21 INFO - 'suite': 'dromaeojs', 16:43:21 INFO - 'system_bits': '32', 16:43:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:43:21 INFO - 'title': 't-yosemite-r7-0284', 16:43:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:43:21 INFO - 'use_talos_json': True, 16:43:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:43:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:43:21 INFO - 'work_dir': 'build'} 16:43:21 INFO - [mozharness: 2016-08-10 23:43:21.045111Z] Running clobber step. 16:43:21 INFO - Running pre-action listener: _resource_record_pre_action 16:43:21 INFO - Running main action method: clobber 16:43:21 INFO - rmtree: /builds/slave/test/build 16:43:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:43:22 INFO - Running post-action listener: _resource_record_post_action 16:43:22 INFO - [mozharness: 2016-08-10 23:43:22.954622Z] Finished clobber step (success) 16:43:22 INFO - [mozharness: 2016-08-10 23:43:22.954772Z] Running read-buildbot-config step. 16:43:22 INFO - Running pre-action listener: _resource_record_pre_action 16:43:22 INFO - Running main action method: read_buildbot_config 16:43:22 INFO - Using buildbot properties: 16:43:22 INFO - { 16:43:22 INFO - "project": "", 16:43:22 INFO - "product": "firefox", 16:43:22 INFO - "script_repo_revision": "production", 16:43:22 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:43:22 INFO - "repository": "", 16:43:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:43:22 INFO - "buildid": "20160810154121", 16:43:22 INFO - "pgo_build": "False", 16:43:22 INFO - "basedir": "/builds/slave/test", 16:43:22 INFO - "buildnumber": 63, 16:43:22 INFO - "slavename": "t-yosemite-r7-0284", 16:43:22 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:43:22 INFO - "platform": "yosemite_r7", 16:43:22 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:43:22 INFO - "branch": "autoland", 16:43:22 INFO - "repo_path": "integration/autoland", 16:43:22 INFO - "revision": "30c27c9a8365307b596a0d2a79570e9737ca4106", 16:43:22 INFO - "stage_platform": "macosx64", 16:43:22 INFO - "builduid": "bfb45519fa674bb2a3a18f5e9990881c", 16:43:22 INFO - "slavebuilddir": "test" 16:43:22 INFO - } 16:43:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:43:22 INFO - Running post-action listener: _resource_record_post_action 16:43:22 INFO - [mozharness: 2016-08-10 23:43:22.958119Z] Finished read-buildbot-config step (success) 16:43:22 INFO - [mozharness: 2016-08-10 23:43:22.958249Z] Running download-and-extract step. 16:43:22 INFO - Running pre-action listener: _resource_record_pre_action 16:43:22 INFO - Running main action method: download_and_extract 16:43:22 INFO - mkdir: /builds/slave/test/build/tests 16:43:22 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')]} 16:43:22 INFO - trying https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:43:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:24 INFO - Downloaded 1199 bytes. 16:43:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:43:24 INFO - Using the following test package requirements: 16:43:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:43:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:43:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:43:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'jsshell-mac.zip'], 16:43:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:43:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:43:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:43:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:43:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:43:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:43:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:43:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:43:24 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')]} 16:43:24 INFO - trying https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:43:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:25 INFO - Downloaded 16325892 bytes. 16:43:25 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'] 16:43:25 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 16:43:28 INFO - Return code: 0 16:43:28 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 16:43:28 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')]} 16:43:28 INFO - trying https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:43:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:29 INFO - Downloaded 16325892 bytes. 16:43:29 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'] 16:43:29 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 16:43:32 INFO - Return code: 0 16:43:32 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')]} 16:43:32 INFO - trying https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:43:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:33 INFO - Downloaded 14413719 bytes. 16:43:33 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'] 16:43:33 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 16:43:33 INFO - Return code: 0 16:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:33 INFO - trying https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:43:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:43:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/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 16:43:37 INFO - Downloaded 110932397 bytes. 16:43:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:43:37 INFO - mkdir: /builds/slave/test/properties 16:43:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:43:37 INFO - Writing to file /builds/slave/test/properties/build_url 16:43:37 INFO - Contents: 16:43:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:43:38 INFO - Running post-action listener: _resource_record_post_action 16:43:38 INFO - Running post-action listener: set_extra_try_arguments 16:43:38 INFO - [mozharness: 2016-08-10 23:43:38.670701Z] Finished download-and-extract step (success) 16:43:38 INFO - [mozharness: 2016-08-10 23:43:38.670836Z] Running populate-webroot step. 16:43:38 INFO - Running pre-action listener: _resource_record_pre_action 16:43:38 INFO - Running main action method: populate_webroot 16:43:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:43:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:43:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:43:38 INFO - '--tppagecycles', 16:43:38 INFO - '7'], 16:43:38 INFO - 'tests': ['tsvgm']}}, 16:43:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:43:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:43:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:43:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:43:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:43:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:43:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['damp', 'tps']}, 16:43:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'tests': ['damp', 'tps']}, 16:43:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['dromaeo_dom']}, 16:43:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:43:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['basic_compositor_video']}, 16:43:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:43:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['a11yr', 16:43:38 INFO - 'ts_paint', 16:43:38 INFO - 'tpaint', 16:43:38 INFO - 'sessionrestore', 16:43:38 INFO - 'sessionrestore_no_auto_restore', 16:43:38 INFO - 'tabpaint']}, 16:43:38 INFO - 'other-e10s': {'tests': ['a11yr', 16:43:38 INFO - 'ts_paint', 16:43:38 INFO - 'tpaint', 16:43:38 INFO - 'sessionrestore', 16:43:38 INFO - 'sessionrestore_no_auto_restore', 16:43:38 INFO - 'tabpaint']}, 16:43:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:43:38 INFO - 'ts_paint', 16:43:38 INFO - 'tpaint', 16:43:38 INFO - 'sessionrestore', 16:43:38 INFO - 'sessionrestore_no_auto_restore', 16:43:38 INFO - 'tabpaint']}, 16:43:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:43:38 INFO - 'ts_paint', 16:43:38 INFO - 'tpaint', 16:43:38 INFO - 'sessionrestore', 16:43:38 INFO - 'sessionrestore_no_auto_restore', 16:43:38 INFO - 'tabpaint']}, 16:43:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['a11yr', 16:43:38 INFO - 'ts_paint', 16:43:38 INFO - 'tpaint', 16:43:38 INFO - 'sessionrestore', 16:43:38 INFO - 'sessionrestore_no_auto_restore', 16:43:38 INFO - 'tabpaint']}, 16:43:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['a11yr', 16:43:38 INFO - 'ts_paint', 16:43:38 INFO - 'tpaint', 16:43:38 INFO - 'sessionrestore', 16:43:38 INFO - 'sessionrestore_no_auto_restore', 16:43:38 INFO - 'tabpaint']}, 16:43:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['tsvgx', 16:43:38 INFO - 'tsvgr_opacity', 16:43:38 INFO - 'tart', 16:43:38 INFO - 'tscrollx', 16:43:38 INFO - 'cart']}, 16:43:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:43:38 INFO - 'tsvgr_opacity', 16:43:38 INFO - 'tart', 16:43:38 INFO - 'tscrollx', 16:43:38 INFO - 'cart']}, 16:43:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'talos_options': ['--disable-e10s'], 16:43:38 INFO - 'tests': ['tp5o']}, 16:43:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'tests': ['tp5o']}, 16:43:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'talos_options': ['--disable-e10s', 16:43:38 INFO - '--xperf_path', 16:43:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:43:38 INFO - 'tests': ['tp5n']}, 16:43:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:43:38 INFO - 'talos_options': ['--xperf_path', 16:43:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:43:38 INFO - 'tests': ['tp5n']}}, 16:43:38 INFO - 'talos.zip': {'path': '', 16:43:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:43:38 INFO - Running post-action listener: _resource_record_post_action 16:43:38 INFO - [mozharness: 2016-08-10 23:43:38.689931Z] Finished populate-webroot step (success) 16:43:38 INFO - [mozharness: 2016-08-10 23:43:38.690069Z] Running create-virtualenv step. 16:43:38 INFO - Running pre-action listener: _resource_record_pre_action 16:43:38 INFO - Running main action method: create_virtualenv 16:43:38 INFO - Creating virtualenv /builds/slave/test/build/venv 16:43:38 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 16:43:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:43:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:43:38 INFO - Using real prefix '/tools/python27' 16:43:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:43:39 INFO - Installing distribute.............................................................................................................................................................................................done. 16:43:42 INFO - Installing pip.................done. 16:43:42 INFO - Return code: 0 16:43:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:42 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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:42 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 16:43:42 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 16:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:42 INFO - 'HOME': '/Users/cltbld', 16:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:42 INFO - 'LOGNAME': 'cltbld', 16:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:42 INFO - 'MOZ_NO_REMOTE': '1', 16:43:42 INFO - 'NO_EM_RESTART': '1', 16:43:42 INFO - 'PAGER': '/bin/cat', 16:43:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:42 INFO - 'PWD': '/builds/slave/test', 16:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:42 INFO - 'SHELL': '/bin/bash', 16:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:43:42 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:42 INFO - 'USER': 'cltbld', 16:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:42 INFO - 'XPC_FLAGS': '0x0', 16:43:42 INFO - 'XPC_SERVICE_NAME': '0', 16:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:42 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 16:43:42 INFO - Cleaning up... 16:43:42 INFO - Return code: 0 16:43:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:42 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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:42 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 16:43:42 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 16:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:42 INFO - 'HOME': '/Users/cltbld', 16:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:42 INFO - 'LOGNAME': 'cltbld', 16:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:42 INFO - 'MOZ_NO_REMOTE': '1', 16:43:42 INFO - 'NO_EM_RESTART': '1', 16:43:42 INFO - 'PAGER': '/bin/cat', 16:43:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:42 INFO - 'PWD': '/builds/slave/test', 16:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:42 INFO - 'SHELL': '/bin/bash', 16:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:43:42 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:42 INFO - 'USER': 'cltbld', 16:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:42 INFO - 'XPC_FLAGS': '0x0', 16:43:42 INFO - 'XPC_SERVICE_NAME': '0', 16:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:43 INFO - Downloading/unpacking psutil>=3.1.1 16:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:43 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 16:43:43 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 16:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:43:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:46 INFO - Installing collected packages: psutil 16:43:46 INFO - Running setup.py install for psutil 16:43:46 INFO - building 'psutil._psutil_osx' extension 16:43:46 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 16:43:47 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 16:43:47 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 16:43:47 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 16:43:47 INFO - building 'psutil._psutil_posix' extension 16:43:47 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 16:43:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:43:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:43:47 INFO - ^ 16:43:47 INFO - 1 warning generated. 16:43:47 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 16:43:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:43:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:43:47 INFO - Successfully installed psutil 16:43:47 INFO - Cleaning up... 16:43:47 INFO - Return code: 0 16:43:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:43:47 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')]} 16:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:47 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')]} 16:43:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:47 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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:47 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 16:43:47 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 16:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:47 INFO - 'HOME': '/Users/cltbld', 16:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:47 INFO - 'LOGNAME': 'cltbld', 16:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:47 INFO - 'MOZ_NO_REMOTE': '1', 16:43:47 INFO - 'NO_EM_RESTART': '1', 16:43:47 INFO - 'PAGER': '/bin/cat', 16:43:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:47 INFO - 'PWD': '/builds/slave/test', 16:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:47 INFO - 'SHELL': '/bin/bash', 16:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:43:47 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:47 INFO - 'USER': 'cltbld', 16:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:47 INFO - 'XPC_FLAGS': '0x0', 16:43:47 INFO - 'XPC_SERVICE_NAME': '0', 16:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:43:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:43:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:43:50 INFO - Installing collected packages: mozsystemmonitor 16:43:50 INFO - Running setup.py install for mozsystemmonitor 16:43:50 INFO - Successfully installed mozsystemmonitor 16:43:50 INFO - Cleaning up... 16:43:50 INFO - Return code: 0 16:43:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:43:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:43:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:43:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:43:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:50 INFO - 'HOME': '/Users/cltbld', 16:43:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:50 INFO - 'LOGNAME': 'cltbld', 16:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:50 INFO - 'MOZ_NO_REMOTE': '1', 16:43:50 INFO - 'NO_EM_RESTART': '1', 16:43:50 INFO - 'PAGER': '/bin/cat', 16:43:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:50 INFO - 'PWD': '/builds/slave/test', 16:43:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:50 INFO - 'SHELL': '/bin/bash', 16:43:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:43:50 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:43:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:50 INFO - 'USER': 'cltbld', 16:43:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:50 INFO - 'XPC_FLAGS': '0x0', 16:43:50 INFO - 'XPC_SERVICE_NAME': '0', 16:43:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:50 INFO - Downloading/unpacking jsonschema==2.5.1 16:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:43:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:43:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43: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 16:43:53 INFO - Downloading functools32-3.2.3-2.tar.gz 16:43:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:43:53 INFO - warning: no files found matching '*.txt' 16:43:53 INFO - no previously-included directories found matching 'build' 16:43:53 INFO - no previously-included directories found matching 'dist' 16:43:53 INFO - no previously-included directories found matching '.git*' 16:43:53 INFO - Installing collected packages: jsonschema, functools32 16:43:53 INFO - Running setup.py install for functools32 16:43:53 INFO - warning: no files found matching '*.txt' 16:43:53 INFO - no previously-included directories found matching 'build' 16:43:53 INFO - no previously-included directories found matching 'dist' 16:43:53 INFO - no previously-included directories found matching '.git*' 16:43:53 INFO - Successfully installed jsonschema functools32 16:43:53 INFO - Cleaning up... 16:43:53 INFO - Return code: 0 16:43:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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')]} 16:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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', '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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:43: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 functools32==3.2.3-2 16:43:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:43:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:53 INFO - 'HOME': '/Users/cltbld', 16:43:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:53 INFO - 'LOGNAME': 'cltbld', 16:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:53 INFO - 'MOZ_NO_REMOTE': '1', 16:43:53 INFO - 'NO_EM_RESTART': '1', 16:43:53 INFO - 'PAGER': '/bin/cat', 16:43:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:53 INFO - 'PWD': '/builds/slave/test', 16:43:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:53 INFO - 'SHELL': '/bin/bash', 16:43:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:43:53 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:43:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:53 INFO - 'USER': 'cltbld', 16:43:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:53 INFO - 'XPC_FLAGS': '0x0', 16:43:53 INFO - 'XPC_SERVICE_NAME': '0', 16:43:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:43:54 INFO - Cleaning up... 16:43:54 INFO - Return code: 0 16:43:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:43:54 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')]} 16:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:54 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')]} 16:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:54 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 16:43:54 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 16:43:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:43:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:54 INFO - 'HOME': '/Users/cltbld', 16:43:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:54 INFO - 'LOGNAME': 'cltbld', 16:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:54 INFO - 'MOZ_NO_REMOTE': '1', 16:43:54 INFO - 'NO_EM_RESTART': '1', 16:43:54 INFO - 'PAGER': '/bin/cat', 16:43:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:54 INFO - 'PWD': '/builds/slave/test', 16:43:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:54 INFO - 'SHELL': '/bin/bash', 16:43:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:43:54 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:43:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:54 INFO - 'USER': 'cltbld', 16:43:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:54 INFO - 'XPC_FLAGS': '0x0', 16:43:54 INFO - 'XPC_SERVICE_NAME': '0', 16:43:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:54 INFO - Downloading/unpacking blobuploader==1.2.4 16:43:54 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 16:43:54 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 16:43:54 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 16:43:54 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 16:43:54 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 16:43:54 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 16:43:56 INFO - Downloading blobuploader-1.2.4.tar.gz 16:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:43:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:43:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 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 16:43:57 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 16:43:58 INFO - Downloading docopt-0.6.1.tar.gz 16:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:43:58 INFO - Installing collected packages: blobuploader, requests, docopt 16:43:58 INFO - Running setup.py install for blobuploader 16:43:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:43:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:43:58 INFO - Running setup.py install for requests 16:43:58 INFO - Running setup.py install for docopt 16:43:59 INFO - Successfully installed blobuploader requests docopt 16:43:59 INFO - Cleaning up... 16:43:59 INFO - Return code: 0 16:43:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:43: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')]} 16:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:43:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43: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')]} 16:43:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:43:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:43:59 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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:43:59 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 16:43:59 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 16:43:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:43:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:59 INFO - 'HOME': '/Users/cltbld', 16:43:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:59 INFO - 'LOGNAME': 'cltbld', 16:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:59 INFO - 'MOZ_NO_REMOTE': '1', 16:43:59 INFO - 'NO_EM_RESTART': '1', 16:43:59 INFO - 'PAGER': '/bin/cat', 16:43:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:59 INFO - 'PWD': '/builds/slave/test', 16:43:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:59 INFO - 'SHELL': '/bin/bash', 16:43:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:43:59 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:43:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:59 INFO - 'USER': 'cltbld', 16:43:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:59 INFO - 'XPC_FLAGS': '0x0', 16:43:59 INFO - 'XPC_SERVICE_NAME': '0', 16:43:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:43:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-4ocOHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:43:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-ORZ3QB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:43:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-I29BWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:43:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-uyWA53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:43:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-stxYoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:43:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:43:59 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-gK4cvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-i1P1SY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-VaT925-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-UmJdFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-3XVXwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-wxUYa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-b0a468-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-cJnCAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Jv98Ec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:00 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-BN5jx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:01 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-tKTbzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:01 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-GVERIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:44:01 INFO - Running setup.py install for manifestparser 16:44:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Running setup.py install for mozcrash 16:44:01 INFO - Running setup.py install for mozdebug 16:44:01 INFO - Running setup.py install for mozdevice 16:44:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:44:01 INFO - Running setup.py install for mozfile 16:44:01 INFO - Running setup.py install for mozhttpd 16:44:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for mozinfo 16:44:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for mozInstall 16:44:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for mozleak 16:44:02 INFO - Running setup.py install for mozlog 16:44:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for moznetwork 16:44:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:44:02 INFO - Running setup.py install for mozprocess 16:44:03 INFO - Running setup.py install for mozprofile 16:44:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:44:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:44:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:44:03 INFO - Running setup.py install for mozrunner 16:44:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:44:03 INFO - Running setup.py install for mozscreenshot 16:44:03 INFO - Running setup.py install for moztest 16:44:03 INFO - Running setup.py install for mozversion 16:44:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:44:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:44:03 INFO - Cleaning up... 16:44:03 INFO - Return code: 0 16:44:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:03 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')]} 16:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:03 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')]} 16:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:03 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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:44:03 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 16:44:03 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 16:44:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:44:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:03 INFO - 'HOME': '/Users/cltbld', 16:44:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:03 INFO - 'LOGNAME': 'cltbld', 16:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:03 INFO - 'MOZ_NO_REMOTE': '1', 16:44:03 INFO - 'NO_EM_RESTART': '1', 16:44:03 INFO - 'PAGER': '/bin/cat', 16:44:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:03 INFO - 'PWD': '/builds/slave/test', 16:44:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:03 INFO - 'SHELL': '/bin/bash', 16:44:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:44:03 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:44:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:03 INFO - 'USER': 'cltbld', 16:44:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:03 INFO - 'XPC_FLAGS': '0x0', 16:44:03 INFO - 'XPC_SERVICE_NAME': '0', 16:44:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-mxtQar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-rsOdZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-7PjaGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-_AY7la-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-RB09W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-pXFajm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-ZtoQMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-vogklc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:44:04 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)) 16:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:44:04 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-n1etNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-jq3or4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-ytYEtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-hpQBvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-CU0XZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-9AvEhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-E8ZxCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-vdnptq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:44:05 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)) 16:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:44:05 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-cNWojc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:44:05 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)) 16:44:05 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)) 16:44:05 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)) 16:44:05 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)) 16:44:05 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)) 16:44:05 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)) 16:44:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:44:05 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 16:44:05 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 16:44:05 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 16:44:05 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 16:44:05 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 16:44:05 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 16:44:08 INFO - Downloading blessings-1.6.tar.gz 16:44:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:44:08 INFO - Installing collected packages: blessings 16:44:08 INFO - Running setup.py install for blessings 16:44:08 INFO - Successfully installed blessings 16:44:08 INFO - Cleaning up... 16:44:08 INFO - Return code: 0 16:44:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:08 INFO - Reading from file tmpfile_stdout 16:44:08 INFO - Current package versions: 16:44:08 INFO - blessings == 1.6 16:44:08 INFO - blobuploader == 1.2.4 16:44:08 INFO - docopt == 0.6.1 16:44:08 INFO - functools32 == 3.2.3-2 16:44:08 INFO - jsonschema == 2.5.1 16:44:08 INFO - manifestparser == 1.1 16:44:08 INFO - mozInstall == 1.12 16:44:08 INFO - mozcrash == 0.17 16:44:08 INFO - mozdebug == 0.1 16:44:08 INFO - mozdevice == 0.48 16:44:08 INFO - mozfile == 1.2 16:44:08 INFO - mozhttpd == 0.7 16:44:08 INFO - mozinfo == 0.9 16:44:08 INFO - mozleak == 0.1 16:44:08 INFO - mozlog == 3.3 16:44:08 INFO - moznetwork == 0.27 16:44:08 INFO - mozprocess == 0.23 16:44:08 INFO - mozprofile == 0.28 16:44:08 INFO - mozrunner == 6.12 16:44:08 INFO - mozscreenshot == 0.1 16:44:08 INFO - mozsystemmonitor == 0.3 16:44:08 INFO - moztest == 0.7 16:44:08 INFO - mozversion == 1.4 16:44:08 INFO - psutil == 3.1.1 16:44:08 INFO - requests == 1.2.3 16:44:08 INFO - wsgiref == 0.1.2 16:44:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:44:08 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')]} 16:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:08 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')]} 16:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:08 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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:44:08 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 16:44:08 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 16:44:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:44:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:08 INFO - 'HOME': '/Users/cltbld', 16:44:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:08 INFO - 'LOGNAME': 'cltbld', 16:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:08 INFO - 'MOZ_NO_REMOTE': '1', 16:44:08 INFO - 'NO_EM_RESTART': '1', 16:44:08 INFO - 'PAGER': '/bin/cat', 16:44:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:08 INFO - 'PWD': '/builds/slave/test', 16:44:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:08 INFO - 'SHELL': '/bin/bash', 16:44:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:44:08 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:44:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:08 INFO - 'USER': 'cltbld', 16:44:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:08 INFO - 'XPC_FLAGS': '0x0', 16:44:08 INFO - 'XPC_SERVICE_NAME': '0', 16:44:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 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)) 16:44:09 INFO - Cleaning up... 16:44:09 INFO - Return code: 0 16:44:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:44: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')]} 16:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:44:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44: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')]} 16:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:44:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:44:09 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 0x10d7caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d85f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffbe0d6e170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, '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.WzMUl1h6ps/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.QYCE31XvkA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 16:44:09 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 16:44:09 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 16:44:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:44:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:09 INFO - 'HOME': '/Users/cltbld', 16:44:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:09 INFO - 'LOGNAME': 'cltbld', 16:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:09 INFO - 'MOZ_NO_REMOTE': '1', 16:44:09 INFO - 'NO_EM_RESTART': '1', 16:44:09 INFO - 'PAGER': '/bin/cat', 16:44:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:09 INFO - 'PWD': '/builds/slave/test', 16:44:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:09 INFO - 'SHELL': '/bin/bash', 16:44:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:44:09 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:44:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:09 INFO - 'USER': 'cltbld', 16:44:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:09 INFO - 'XPC_FLAGS': '0x0', 16:44:09 INFO - 'XPC_SERVICE_NAME': '0', 16:44:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:44:09 INFO - Cleaning up... 16:44:09 INFO - Return code: 0 16:44:09 INFO - Running post-action listener: _resource_record_post_action 16:44:09 INFO - Running post-action listener: _start_resource_monitoring 16:44:09 INFO - Starting resource monitoring. 16:44:09 INFO - [mozharness: 2016-08-10 23:44:09.470440Z] Finished create-virtualenv step (success) 16:44:09 INFO - [mozharness: 2016-08-10 23:44:09.471366Z] Running install step. 16:44:09 INFO - Running pre-action listener: _resource_record_pre_action 16:44:09 INFO - Running main action method: install 16:44:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:44:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:44:09 INFO - Reading from file tmpfile_stdout 16:44:09 INFO - Detecting whether we're running mozinstall >=1.0... 16:44:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:44:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:44:09 INFO - Reading from file tmpfile_stdout 16:44:09 INFO - Output received: 16:44:09 INFO - Usage: mozinstall [options] installer 16:44:09 INFO - Options: 16:44:09 INFO - -h, --help show this help message and exit 16:44:09 INFO - -d DEST, --destination=DEST 16:44:09 INFO - Directory to install application into. [default: 16:44:09 INFO - "/builds/slave/test"] 16:44:09 INFO - --app=APP Application being installed. [default: firefox] 16:44:09 INFO - mkdir: /builds/slave/test/build/application 16:44:09 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'] 16:44:09 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 16:44:43 INFO - Reading from file tmpfile_stdout 16:44:43 INFO - Output received: 16:44:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:44:43 INFO - Running post-action listener: _resource_record_post_action 16:44:43 INFO - [mozharness: 2016-08-10 23:44:43.384668Z] Finished install step (success) 16:44:43 INFO - [mozharness: 2016-08-10 23:44:43.384813Z] Running run-tests step. 16:44:43 INFO - Running pre-action listener: _resource_record_pre_action 16:44:43 INFO - Running main action method: run_tests 16:44:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:44:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:44:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:44:43 INFO - 2016-08-10 16:44:43.398 screenresolution[1651:7607] starting screenresolution argv=screenresolution get 16:44:43 INFO - 2016-08-10 16:44:43.401 screenresolution[1651:7607] Display 0: 1600x1200x32@60 16:44:43 INFO - 2016-08-10 16:44:43.407 screenresolution[1652:7609] starting screenresolution argv=screenresolution list 16:44:43 INFO - Available Modes on Display 0 16:44:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:44:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:44:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:44:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:44:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:44:43 INFO - Intel Iris: 16:44:43 INFO - Chipset Model: Intel Iris 16:44:43 INFO - Type: GPU 16:44:43 INFO - Bus: Built-In 16:44:43 INFO - VRAM (Dynamic, Max): 1536 MB 16:44:43 INFO - Vendor: Intel (0x8086) 16:44:43 INFO - Device ID: 0x0a2e 16:44:43 INFO - Revision ID: 0x0009 16:44:43 INFO - Displays: 16:44:43 INFO - Display: 16:44:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:44:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:44:43 INFO - Display Serial Number: 1600x1200 60 16:44:43 INFO - Main Display: Yes 16:44:43 INFO - Mirror: Off 16:44:43 INFO - Online: Yes 16:44:43 INFO - Rotation: Supported 16:44:43 INFO - Return code: 0 16:44:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:44:43 INFO - Python 2.7.3 16:44:43 INFO - Return code: 0 16:44:43 INFO - grabbing minidump binary from tooltool 16:44:43 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')]} 16:44:43 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 0x10d72ed30>, '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 0x10d493db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d726b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:44:43 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 16:44:43 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 16:44:43 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 16:44:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:44:43 INFO - Return code: 0 16:44:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:44:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:44:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:44:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:44:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:44:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0284 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:44:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WzMUl1h6ps/Render', 16:44:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:44:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:44:43 INFO - 'HOME': '/Users/cltbld', 16:44:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:44:43 INFO - 'LOGNAME': 'cltbld', 16:44:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:44:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:44:43 INFO - 'MOZ_NO_REMOTE': '1', 16:44:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:44:43 INFO - 'NO_EM_RESTART': '1', 16:44:43 INFO - 'PAGER': '/bin/cat', 16:44:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:44:43 INFO - 'PWD': '/builds/slave/test', 16:44:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:44:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:44:43 INFO - 'SHELL': '/bin/bash', 16:44:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYCE31XvkA/Listeners', 16:44:43 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 16:44:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:44:43 INFO - 'USER': 'cltbld', 16:44:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:44:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:44:43 INFO - 'XPC_FLAGS': '0x0', 16:44:43 INFO - 'XPC_SERVICE_NAME': '0', 16:44:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:44:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:44:43 INFO - mozversion application_buildid: 20160810154121 16:44:43 INFO - mozversion application_changeset: 30c27c9a8365307b596a0d2a79570e9737ca4106 16:44:43 INFO - mozversion application_display_name: Nightly 16:44:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:44:43 INFO - mozversion application_name: Firefox 16:44:43 INFO - mozversion application_remotingname: firefox 16:44:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:44:43 INFO - mozversion application_vendor: Mozilla 16:44:43 INFO - mozversion application_version: 51.0a1 16:44:43 INFO - mozversion platform_buildid: 20160810154121 16:44:43 INFO - mozversion platform_changeset: 30c27c9a8365307b596a0d2a79570e9737ca4106 16:44:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:44:43 INFO - mozversion platform_version: 51.0a1 16:44:43 INFO - using testdate: 1470872683 16:44:43 INFO - actual date: 1470872683 16:44:43 INFO - starting webserver on 'localhost:49279' 16:44:43 INFO - SUITE-START | Running 2 tests 16:44:43 INFO - TEST-START | dromaeo_css 16:44:43 INFO - operating with platform_type : mac_ 16:44:43 INFO - Initialising browser for dromaeo_css test... 16:44:43 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmp2Pk2C_/profile http://localhost:49279/getInfo.html) 16:44:46 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 16:44:46 INFO - PROCESS | 1662 | colorDepth:24 16:44:46 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 16:44:46 INFO - PROCESS | 1662 | __metrics 16:44:46 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:44:46 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:44:47 INFO - TEST-INFO | 1662: exit 0 16:44:47 INFO - Browser initialized. 16:44:47 INFO - Running cycle 1/1 for dromaeo_css test... 16:44:47 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmp2Pk2C_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:44:59 INFO - PROCESS | 1664 | RSS: Main: 243863552 16:44:59 INFO - PROCESS | 1664 | 16:45:50 INFO - PROCESS | 1664 | 1470872750473 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] 16:48:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 16:48:03 INFO - PROCESS | 1664 | RSS: Main: 343130112 16:48:03 INFO - PROCESS | 1664 | 16:51:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 16:51:06 INFO - PROCESS | 1664 | RSS: Main: 383619072 16:51:06 INFO - PROCESS | 1664 | 16:54:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 16:54:09 INFO - PROCESS | 1664 | RSS: Main: 342343680 16:54:09 INFO - PROCESS | 1664 | 16:57:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 16:57:12 INFO - PROCESS | 1664 | RSS: Main: 346427392 16:57:12 INFO - PROCESS | 1664 | 17:00:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 17:00:16 INFO - PROCESS | 1664 | RSS: Main: 344301568 17:00:16 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 17:03:20 INFO - PROCESS | 1664 | RSS: Main: 514056192 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | __start_tp_report 17:03:20 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 17:03:20 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 17:03:20 INFO - PROCESS | 1664 | |i|pagename|runs| 17:03:20 INFO - PROCESS | 1664 | |0;dojo.html;3357.2781851901836;3372.595288565374;3374.325134973006;3387.4749413840855;3437.4671925851485;5642.181883626871;5662.943370566293;5678.233438485803;5729.05470597352;5809.651420914748;5734.483896449318;5739.282589676291;5830.4169583041785;5854.268216472941;5865.76536938523;5188.247704082905;5192.221166824968;5270.789168433258;5274.182501712241;5369.194620806871;5318.734063296839;5355.946440535603;5369.114096174127;5384.326959130109;5406.053940560386;5163.8192663256605;5178.689278643294;5213;5220.817271395521;5250.396204436501;5223.268742376071;5239.476052394768;5263.736813159365;5329;5345.984262990059;5209.244659524347;5245.501677340647;5258.0535503609335;5271.130263506517;5328.174133009351;5468.890622187534;5791.044477661181;5791.102379131199;5832.445917637818;5859.150423188614;5970.283565972068;6166.290876549209;6230.688465576703;6234.657093859836;6305.180326557518;6245.188125543696;6255.843603909893;6313.494920406357;6322.367763223687;6330.525210609223;5682.40334764852;5885.234919460486;5889.087191485327;5891.998360278762;5897.380775018604;6206.844828879224;6239.469645080128;6268.686565671698;6290.339514351019;6336.461400780985;6194.039923811816;6207.844803879849;6216.222972128484;6226.875462490725;6235.314115447297;6252.343503932113;6298.842528936813;6327.683615819191;6333.746650134035;6342.016987366966;6098.190188918898;6128.655903792257;6186.907196392058;6188.907166392508;6212.409821066992;6255.280642726054;6278.529110316745;6292.1505596743855;6297.811065668037;6298.7795427160745;5002.174641184237;5015.724135172528;5019.29729837823;5022.723750193774;5054.545090941833;4419.801108950105;4447.755373454426;4488.708233964782;4515.932261016087;4555.521670224645;5740.56945729072;5747.453991870766;5762.769489220468;5764.884702305931;5821.417858214145;6174.783882564088;6295.779647712308;6320.905186422207;6327.209098862642;6365.777197798147;4789.44921334051;5636.379998200195;5673.687947162863;5676.489115979582;5726.055200891889;5460.372057213392;5472.534834539029;5478.45215478449;5480.588955828329;5501.477359650827;6318.336574659595;6327.683615819098;6341;6351.301356850743;6355.682215889279;6148.016317389196;6208.596441231305;6222.906656400067;6227.782027629192;6315.242170939517;6281.277653069956;6310.463610593151;6351.555391122577;6359.523035772243;6367.840803979938;6204.72078756448;6225.190725205875;6244.281907580505;6246.3441338660605;6251.249850018027;6101.359357267601;6292.182016337847;6298.433141017331;6314.747410103544;6318.842028949313;6067.514598832171;6101.51991978472;6103.974138059404;6173.135760993375;6185.8144255672405;6152.538559607958;6161.691915404302;6163.044728067157;6177.042558403455;6180.81457556274;5772.451617096369;5866.794662186803;5963.827438601201;6042.728077236447;6045.6070355426755;6109.480694141048;6118.4668989547035;6121.757838957599;6137.693115344304;6168.814935551941;5970.552208584287;5974;5988.401159883977;6008.098785182257;6014.278286605635 17:03:20 INFO - PROCESS | 1664 | |1;ext.html;505.0051398744473;522.9843104706857;530.8168681804776;534.201368953415;550.5595523581134;30393.392132157373;30831.537526937114;31906.72373105073;31958.72165113393;31975.040748777516;41117.38323925136;41968;42029.57970420295;44402.77798611002;44591;8234;9584.568694408734;9744.123028927395;9746.415215087118;9757.219422446222;8629.784255393603;9612.90387096127;9708.368956017835;9711.902880971205;9749.220062394992;8671.913280867206;9828.21374290055;11939.104567157454;11949;12222.63332100034;11937.34344611046;12003.519859205622;12015.819762703566;12021.519139234464;12022.819657705142;8598.742037738877;11559.711007224803;11800.996915262212;11900.166988310822;11924.940375298154;38159.427608585895;40297;40350.59649403498;40390;40426.79786601048;3497;3599.6580324869096;3815.637514436124;3947.842086316544;3979.8408063677416;30467.847660761778;32081.83959080213;32380.83809580937;32464.513032304298;32747.672523274938;3265.755068369882;3354.362671092509;3383.8477268522975;3413.9317213655586;3423.7432192585657;461217.71475996694;461371;462847;462994;463072.68463657465;56534;56688;56787.60517750807;57193.85612287731;57213.42786572164;59558;59611.80776384448;59642.10536841951;59646;59748.70125649344;13849.23829189384;16028.679426411407;16062;16347.509574712778;16356.263968121462;12744.235345879091;12785.552505662257;12802.80795788064;12814.42335094923;12826.61520154397;11524.481398337095;13583.864161358457;13951.930240348733;15097;15343.156126413154;6064.877292947522;6140.048292514667;6171.907421388682;6193.349698281706;6197.380261973766;913.1598928985217;1004.6232662751469;1038.3925403638805;1043.9791204176026;1077.9784404312027;29976.700232997828;30286.394272114434;30731.846340768152;30791.538126928113;30926.917557885365;1060.4697651174413;1074.387599068539;1099.230538622967;1107.9889201108047;1249.4252643783962;15362.539123826304;15553.655963967802;15610.78541639706;15961.44134955271;15963.920180399024;649561;651802;653658;654181;654946;57561.71219143961;57587.71206144026;57606.8478630425;57666.13500797492;57842.7107864458;1367.8290213723285;1406.880415164702;1426.892983026277;1427.0581416265215;1430.3563396471836;29205;29598.556021660122;30335.848320758254;30336.69663303339;30896.918607848616;49810;49817.003659925875;49843.25235121549;49876.00247995093;50029.74985125051;485233;485661;485764;486107;486828;40422.59577404188;40470.59529404786;40903.795481022404;41064.973375665846;41074;46176;47194.76402617965;47417.28874066823;47685.28472072852;47757.04485910193;37200.883973480835;38545.807270964586;38969.41545876757;39420.60579394284;39779.40330894981;33483.83258083694;38518;38792.80603596964;39104.2179156421;40004.399934001594;38353.80823095978;38417.84746457611;38467;38694.61305387023;38851.6114838848;27236.863815680794;33424.498632520044;34321;34331;34345.313093738485;24668.753312466644;25112.24663260105;25263.747362526876;25647.743522564535;25782.742172578033 17:03:20 INFO - PROCESS | 1664 | |2;jquery.html;352.4308242188866;367.783008025265;372.7167352811863;378.56276001218595;399.4687066201952;14045.36795844187;15774.68450630988;20614.587708245825;21393;21430.357089287307;20785.896070519662;21094.472638184016;21112.366629001117;21146.894265528652;21219.36341909741;5666.631668941516;5713.685747283897;5845.503132233765;5909.497692696126;5971.253593300838;5791.160281759142;5877.324107727602;5883.264591926009;5893.528517718593;5896.410358964091;5550.91673624894;5745.166950792132;5822;5825.271841019872;5846.356900740915;5796.797112101056;5845.327787304472;5845.3862344453855;5847.181394604759;5873.207117039187;4872.317875497433;5062.645614806965;5121.897562048738;5128.820491282787;5129.358830146231;24290.878545607335;24476;24684.506309873883;24810.379740506633;24915.377115572075;2239.507308392151;2298.367948814073;2393.198278576679;2407.8916448759846;3060.4338197433544;14829.925850370679;14886.70226595462;15105.47130850415;15261.61845953849;15455.690886182214;1800.8379245867934;1861.1159699142934;1862.3854128137818;1872.7939926608062;1874.4535757999704;88848;111811;113627.43186284098;114088;114392;10372.325798823053;10815.891841081646;10885.679867640287;10971.670849874517;11078;13736.145620341125;13763.518276860463;13766.793498097537;13774.793378099337;13794.379252933642;8386.622601982925;8511.659533618586;8525.488470691778;8538.786530336667;8661.437006594551;8579.099194584478;8581.742547723577;8588.8711669325;8730.345224108218;8804.075572064841;6593.835154121185;6594.439472644783;6612.206535215709;6643.900341494881;6675.065490831287;1582.4619629326085;1818.7544681467832;1844.6864033114402;1853.4069097888816;1861.8045105263752;956.3544607389983;972.5283237629744;981.7202097402348;1029.4441001858913;1044.7858189071221;19546.120424580928;21570.676439853414;21819.67270490912;22125;22136.77863221347;917.1653795046476;920.1120918313859;942.5758408716105;976.3653625143571;994.1947022911465;10990.395528245863;11319.943400282946;11418.143639227092;11424.028957538701;11426.428678566039;617037.914810432;619043.3163708576;621343;630996;641280;98447.89135738292;129094;144965;148076;152257;994.6170724271208;1019.4698756646502;1031.8194315994822;1053.5627714498398;1055.6569115037644;18213.54466138357;19349.51626209356;19427.028648567233;19427.70858437153;19498.61002779908;40301.387944481896;54485;60900.78198435918;61734.69132654308;63622.68188659027;74976;84525;86249.56875215584;86281.56859215663;87145.56427217824;7193.352598266181;7273.599952002691;7839.490433121829;8008.439409241297;8503.1921967413;9400.576974036048;13042.804357934448;13183;13605.727885442433;13872.653183670489;8977.461352318882;11479.655610331703;11768.818224490311;11811.995980341768;11901.642950711492;11171.72070698239;11344.092472602337;11448.026917711755;11699.941500292443;11902.285862848257;9078.5501509426;11778.116641252112;11965;12014.399280035788;12090;6535.248446428722;8064.55644939534;8264.925559677204;8681.262092721936;8725.825483490167;9626.507891276864;11378.601748938747;11605.361705106301;11835.822462662894;11901.166918315635 17:03:20 INFO - PROCESS | 1664 | |3;mootools.html;1531.2037740375006;1536.8924175307725;1559.734845076337;1635.63198280387;1852.4442667199837;8343.08226095129;8897.822043559134;8907.10928907109;8918.821623567525;8924.241439477644;7733.845323093535;7975.880361794578;8402.831943361129;8631.69789057383;8666.39335246533;1884.8869067855903;2024.3724445421967;2337.2170322941834;2337.801286890616;2340.192633541434;2298.6666933294664;2310.353101131686;2380.321608341623;2380.369202161429;2382.8808559571953;2338.6258198688215;2350.2831636350907;2358.0567772890895;2383.9046438142454;2388.164142550111;2321.6749655048307;2333.0784340185614;2336.8130549555995;2341.800946919514;2344.6248600223967;2331.988340058289;2338.75443078476;2339.1345202275097;2341.285907798121;2343.906243750248;8727.38908276421;8908.643654253821;8910.86633700495;8924.196822286025;8946.060663630291;4217.683673724483;5288.68267903927;5295.655782374133;5298.152295632719;5312.335958005249;3428.160100775326;3490.493878387645;3633.8909832705062;3637.4907512948207;3645.7994810285422;1040.3944004628383;1079.681493959276;1079.7678499122726;1096.7641956979287;1099.6810924831789;11573.710657233636;11665.241759285704;11689.064874809996;11696.356700381475;11711.121665875093;4456.821727130879;4543.704659197141;4724.929126063112;4728.621710263171;4734.455537613149;9914.652987145559;9940.10539051489;9957.701268961942;9970.800583988279;9991.500424978722;5263.184206448007;5270.8155214567305;5271.182966640177;5274.868128296746;5283.049051170826;4112.300908845504;4351.5430879757805;4500.347449619787;4842.176829938919;4856.975715121402;3892.9221415572097;3949.19041596472;3998.100427403811;4049.57479464652;4059.330210515232;1270.9491620335084;1280.0271793437055;1283.1274733181526;1292.4507084489242;1292.5992942187952;726.1939247435342;910.5811326789751;916.1205242966694;917.958691858868;944.0096088464792;5813.505852002542;5886.764529418775;5890.3815099414805;6046.637201767908;6073.878522429527;426.1966193724851;432.2737800495198;453.88425951382845;454.91356642237866;455.9612432943237;3862.4978752761963;3955.595763503972;3999.6200360965663;4090.795460226977;4091.754494730326;10248.38509689406;10766.515506802212;10768.569257229623;10771.784564308826;10777.515011824487;10170.694879153638;10671.146308295472;10675.412852293042;10736.73158171052;10753.516091775733;6839.658017099225;7971.282584567417;8155.877661835078;8174.264316211569;8187.058488273806;5842.094475356326;5894.557908156991;5896.616719913192;6099.054646529795;6236.6258024518675;9626.95186524063;9810.018998100133;9849.965753595769;10024.398536087858;10091.848622270818;9952.701418957442;10066.396016239049;10180.847287290844;10331.741706457398;10334.534945927602;7507.962460187664;7590.051243594551;7604.80987975305;7690.807729806799;7706.7687969361;8091.1099779025435;8269.95865020671;8277.461964972257;8284.585770711561;8290.129536398592;7368.852622947404;7393.889091663522;7436.219196984455;7444.255574442513;7626.275503827128;7375.299346562068;7416.555006699616;7440;7450.441216908645;7526.736564220226;6947.44420446353;6955.304469553206;7017.929820701727;7029.824254393477;7042.330978557234;4955.1576232039915;4956.454789973172;5037.823676171316;5101.158308879142;5136.152534831711;4310.612044915973;4389.868303950887;4408.9118217636105;4436.9334459983775;4485.663575231936 17:03:20 INFO - PROCESS | 1664 | |4;prototype.html;296.57885802160934;308.0281711201161;308.9243135431819;313.8932762860627;315.37713016791827;5349.358077030757;5349.679019258842;5360.383555891074;5372.516473517383;5428.294321738178;5176.195496310218;5306.814261500848;5326.493983071612;5350.30446042014;5354;2792.748652621264;3640.3447379471686;3927.3127202739547;4076.368162934735;4112.444819949298;3419.2135808764;3592.1917568546996;3878.437626544149;4125.443065186191;4140.378943158536;3523.3481805865867;3973.2053589282114;4062.7359221650645;4100.671946244293;4102.938455923164;3485.721142308622;3780.8676696315497;3925.390589858173;3961.0972568578554;4084.8774536763945;3122.266267427153;3364.932701345984;3551.8934431966823;3558.7864728116397;3575.606683264839;4320.589543993316;4516.412866327357;5139.897202055938;5305.522502974713;5323.361196656426;4080.6327430531396;4230.428892099558;4344.6524278058;4613.63090952723;4655.767211639404;5369.40936496985;5619.7471113799975;5649.260108847206;5658.151277308396;5663.4336566343445;3829.702178692762;3833.4058220975794;3863.4398012288343;3879.86420475282;3889.4165875118674;4713;5822.737976791054;5847.999290912854;5975.223220981245;6024.21685180928;3465.358908601917;3816.4084566892175;3901.4147877818555;3908.218356328689;3925.21495700861;3494.807785571767;3970.7617542946937;3980.3233450313514;4000.7799571023875;4003.7597744135446;4665.160271151158;4679.81280748773;4688.179568575486;4696.107739529479;4696.1546921554445;4360.672949528798;5355.410904800468;5364.81973965727;5678.432156784371;5792.681402522818;1139.5897476908306;1346.8181795457488;1349.5209200733796;1353.41126609925;1359.3814814259488;671.8924972004456;771.830676525065;795.7930937956057;820.2044017303207;820.2371794231303;4238.325861284837;4497.437820272466;5003.74981250936;5182.507661772125;5182.548886311839;5356.857257097192;5769.3652998610605;5774.220480235199;5800.651960882361;5818;4675.392199014106;5017.774200161001;5056.544910958031;5239.973800131052;5260.552853007537;2813.606095146681;2997.460457117696;3034.484137696597;3045.4365942300747;3047.8265867641117;5312.309399778082;6040.818775436744;6054.970654988662;6061.818145455643;6088.969555152196;5671.6597004179885;5973.462388384979;5989.610675306091;5994.760209591567;6008.699565021731;4591.897944493231;5382.4886635769535;5716.485516303469;5993.880122397528;6044.516438684895;5086.796528138833;5407.72961351923;5507.375324279427;5758.416435480276;5814.912776308443;4902.730349830651;5372;5490.972545137249;5643.181738647791;5699.694177377194;5360.946390536201;5577.721113944368;5788.189653448437;5946.226990491208;6008.969955150196;2938.882444702188;3135.8745650173737;3351.731861451127;3386.2211691311627;3575.5530558680166;4775.092732380836;5788.305403351625;5791.971040144772;5803.8549036274435;5814.970925145347;4665.2535594304745;5642.520385767256;5649.288341596139;5651.2935883014625;5665.620439415373;5306;5332.253484512094;5612.522935550361;5665.886682266414;5666.43851699386;4567.63458923292;5534.640248383842;5555.361238388862;5568.716826101279;5613.747381367766;3956.5074003377686;5396.730163491889;5459.679636154698;5608.579356548194;5613.719314034363;4218.093109981369;4914.533119353656;5130.794768209379;5489.835304940858;5551.333839939233 17:03:20 INFO - PROCESS | 1664 | |5;yui.html;718.3570704219724;770.9421793365495;771.4098714483421;798.249645333387;872.8821609082773;13314.400851961673;14934.925325373371;16355.83644163558;16379.83620163798;16404.589885252874;14764.409423623069;14915.627109322273;14923.85076149241;14925.701485970274;14973.550793476186;695.6174104242672;843.359047124187;851.4040171879693;875.8204568063563;879.2526352600303;877.33322674767;881;882.232457761745;882.4308321132866;882.7748924024397;884.107051877605;885.9778505537348;934.2806039349697;935.9251259899225;936.1200471556715;729.9854002919965;885.6723012485357;915.4873270968279;927.91648751612;929.4376901974276;687.15136806045;690.547691262224;691.325957191739;691.837418206721;692.629443247865;14774;14811.925940370229;14826.33281502335;14835.703285934329;14836.777448338284;14045.43818247269;14059.859401405958;14082;14310;14442.638934026734;13192.6701832454;13349.933250333686;13419.261940593264;13548.254845983467;13709.45162193511;3857.2092720992127;4754.643401744883;4783.115123702126;4809.158397280462;4844.539768721966;111911.44044279726;117046;117454.4127279358;117565;117648;10965.890341096647;11133.554657813596;11194.328340299446;11314.793615244947;11720.765584688259;22590.322290330987;22638.207662731726;22639.434014149778;22678.659820102712;22684;6393.7762178324465;7479.439042071866;7516.135644400854;7585.506942048749;7674.501157424749;4140.3375459926265;4333.133373325284;4426.867193984185;4474.26174681174;4693.81224751013;3533.1343820764077;3650.525431693916;3655.6527129922615;3658.7438879278193;3666.4133738602227;476.53299766229287;628.3936001758325;651.8109748172976;656.150285380424;678.3047376439091;627.5481653209682;660.8480049588621;667.7228949985798;704.859028194353;704.989425158623;8292.17078292178;8770.824583508294;9671.371360861522;9681.031896810262;10038.548265328078;619.3373090792899;683.931606839312;685.9725610975605;709.4537206351081;711.5872793779596;4636.736149992858;4752.928706069411;4849.151398505247;4912.557869791736;4961.578928997054;132493;156100;156750;157193;157987;114810;133171.66828331788;135173;136944;138256;114941;116174;116868.41565792117;118269.99999999828;118969;9391.624335026523;9396.57715402795;9478.24174066087;9508.144267016001;9561.452292047428;58242.92299539161;59083;60966;61177.694111529156;64673;71274;81468.37063258821;82859.51421457366;83747.58126209574;87594.12405876114;13419.135043058313;13585.252811095485;13612.251326177155;14217.289135543388;14226.430942762174;14915.701685966436;15063.47277845314;15295.235238237821;15421.306041227946;15692.372305107669;13273;13497.257650828906;13522.72954540884;14094.013419060568;14165.575032749033;14304.070235434663;14312.284385780878;14443.638909026939;14728.968972171846;14764.704705905608;14060.859391406364;14226.644333891736;14272.500462484179;14368.497102601359;14378.784318235445;11105;11261.324320540794;11471.827922581;11756.58851940178;12218.572349968064;11222.214444989098;11408.600698975828;11670.241434306743;11802.291862488279;11836.82244766312 17:03:20 INFO - PROCESS | 1664 | __end_tp_report 17:03:20 INFO - PROCESS | 1664 | __start_cc_report 17:03:20 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1409 17:03:20 INFO - PROCESS | 1664 | __end_cc_report 17:03:20 INFO - PROCESS | 1664 | __startTimestamp1470873800263__endTimestamp 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | ------- Summary: start ------- 17:03:20 INFO - PROCESS | 1664 | Number of tests: 6 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5784.46 Median:6008.10 stddev:634.42 (10.6%) stddev-sans-first:607.12 17:03:20 INFO - PROCESS | 1664 | Values: 3357.3 3372.6 3374.3 3387.5 3437.5 5642.2 5662.9 5678.2 5729.1 5809.7 5734.5 5739.3 5830.4 5854.3 5865.8 5188.2 5192.2 5270.8 5274.2 5369.2 5318.7 5355.9 5369.1 5384.3 5406.1 5163.8 5178.7 5213.0 5220.8 5250.4 5223.3 5239.5 5263.7 5329.0 5346.0 5209.2 5245.5 5258.1 5271.1 5328.2 5468.9 5791.0 5791.1 5832.4 5859.2 5970.3 6166.3 6230.7 6234.7 6305.2 6245.2 6255.8 6313.5 6322.4 6330.5 5682.4 5885.2 5889.1 5892.0 5897.4 6206.8 6239.5 6268.7 6290.3 6336.5 6194.0 6207.8 6216.2 6226.9 6235.3 6252.3 6298.8 6327.7 6333.7 6342.0 6098.2 6128.7 6186.9 6188.9 6212.4 6255.3 6278.5 6292.2 6297.8 6298.8 5002.2 5015.7 5019.3 5022.7 5054.5 4419.8 4447.8 4488.7 4515.9 4555.5 5740.6 5747.5 5762.8 5764.9 5821.4 6174.8 6295.8 6320.9 6327.2 6365.8 4789.4 5636.4 5673.7 5676.5 5726.1 5460.4 5472.5 5478.5 5480.6 5501.5 6318.3 6327.7 6341.0 6351.3 6355.7 6148.0 6208.6 6222.9 6227.8 6315.2 6281.3 6310.5 6351.6 6359.5 6367.8 6204.7 6225.2 6244.3 6246.3 6251.2 6101.4 6292.2 6298.4 6314.7 6318.8 6067.5 6101.5 6104.0 6173.1 6185.8 6152.5 6161.7 6163.0 6177.0 6180.8 5772.5 5866.8 5963.8 6042.7 6045.6 6109.5 6118.5 6121.8 6137.7 6168.8 5970.6 5974.0 5988.4 6008.1 6014.3 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:67270.59 Median:38531.90 stddev:144213.40 (374.3%) stddev-sans-first:144530.83 17:03:20 INFO - PROCESS | 1664 | Values: 505.0 523.0 530.8 534.2 550.6 30393.4 30831.5 31906.7 31958.7 31975.0 41117.4 41968.0 42029.6 44402.8 44591.0 8234.0 9584.6 9744.1 9746.4 9757.2 8629.8 9612.9 9708.4 9711.9 9749.2 8671.9 9828.2 11939.1 11949.0 12222.6 11937.3 12003.5 12015.8 12021.5 12022.8 8598.7 11559.7 11801.0 11900.2 11924.9 38159.4 40297.0 40350.6 40390.0 40426.8 3497.0 3599.7 3815.6 3947.8 3979.8 30467.8 32081.8 32380.8 32464.5 32747.7 3265.8 3354.4 3383.8 3413.9 3423.7 461217.7 461371.0 462847.0 462994.0 463072.7 56534.0 56688.0 56787.6 57193.9 57213.4 59558.0 59611.8 59642.1 59646.0 59748.7 13849.2 16028.7 16062.0 16347.5 16356.3 12744.2 12785.6 12802.8 12814.4 12826.6 11524.5 13583.9 13951.9 15097.0 15343.2 6064.9 6140.0 6171.9 6193.3 6197.4 913.2 1004.6 1038.4 1044.0 1078.0 29976.7 30286.4 30731.8 30791.5 30926.9 1060.5 1074.4 1099.2 1108.0 1249.4 15362.5 15553.7 15610.8 15961.4 15963.9 649561.0 651802.0 653658.0 654181.0 654946.0 57561.7 57587.7 57606.8 57666.1 57842.7 1367.8 1406.9 1426.9 1427.1 1430.4 29205.0 29598.6 30335.8 30336.7 30896.9 49810.0 49817.0 49843.3 49876.0 50029.7 485233.0 485661.0 485764.0 486107.0 486828.0 40422.6 40470.6 40903.8 41065.0 41074.0 46176.0 47194.8 47417.3 47685.3 47757.0 37200.9 38545.8 38969.4 39420.6 39779.4 33483.8 38518.0 38792.8 39104.2 40004.4 38353.8 38417.8 38467.0 38694.6 38851.6 27236.9 33424.5 34321.0 34331.0 34345.3 24668.8 25112.2 25263.7 25647.7 25782.7 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:36125.95 Median:24580.25 stddev:104324.57 (424.4%) stddev-sans-first:104582.65 17:03:20 INFO - PROCESS | 1664 | Values: 352.4 367.8 372.7 378.6 399.5 14045.4 15774.7 20614.6 21393.0 21430.4 20785.9 21094.5 21112.4 21146.9 21219.4 5666.6 5713.7 5845.5 5909.5 5971.3 5791.2 5877.3 5883.3 5893.5 5896.4 5550.9 5745.2 5822.0 5825.3 5846.4 5796.8 5845.3 5845.4 5847.2 5873.2 4872.3 5062.6 5121.9 5128.8 5129.4 24290.9 24476.0 24684.5 24810.4 24915.4 2239.5 2298.4 2393.2 2407.9 3060.4 14829.9 14886.7 15105.5 15261.6 15455.7 1800.8 1861.1 1862.4 1872.8 1874.5 88848.0 111811.0 113627.4 114088.0 114392.0 10372.3 10815.9 10885.7 10971.7 11078.0 13736.1 13763.5 13766.8 13774.8 13794.4 8386.6 8511.7 8525.5 8538.8 8661.4 8579.1 8581.7 8588.9 8730.3 8804.1 6593.8 6594.4 6612.2 6643.9 6675.1 1582.5 1818.8 1844.7 1853.4 1861.8 956.4 972.5 981.7 1029.4 1044.8 19546.1 21570.7 21819.7 22125.0 22136.8 917.2 920.1 942.6 976.4 994.2 10990.4 11319.9 11418.1 11424.0 11426.4 617037.9 619043.3 621343.0 630996.0 641280.0 98447.9 129094.0 144965.0 148076.0 152257.0 994.6 1019.5 1031.8 1053.6 1055.7 18213.5 19349.5 19427.0 19427.7 19498.6 40301.4 54485.0 60900.8 61734.7 63622.7 74976.0 84525.0 86249.6 86281.6 87145.6 7193.4 7273.6 7839.5 8008.4 8503.2 9400.6 13042.8 13183.0 13605.7 13872.7 8977.5 11479.7 11768.8 11812.0 11901.6 11171.7 11344.1 11448.0 11699.9 11902.3 9078.6 11778.1 11965.0 12014.4 12090.0 6535.2 8064.6 8264.9 8681.3 8725.8 9626.5 11378.6 11605.4 11835.8 11901.2 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5663.35 Median:2499.83 stddev:3220.08 (128.8%) stddev-sans-first:3214.15 17:03:20 INFO - PROCESS | 1664 | Values: 1531.2 1536.9 1559.7 1635.6 1852.4 8343.1 8897.8 8907.1 8918.8 8924.2 7733.8 7975.9 8402.8 8631.7 8666.4 1884.9 2024.4 2337.2 2337.8 2340.2 2298.7 2310.4 2380.3 2380.4 2382.9 2338.6 2350.3 2358.1 2383.9 2388.2 2321.7 2333.1 2336.8 2341.8 2344.6 2332.0 2338.8 2339.1 2341.3 2343.9 8727.4 8908.6 8910.9 8924.2 8946.1 4217.7 5288.7 5295.7 5298.2 5312.3 3428.2 3490.5 3633.9 3637.5 3645.8 1040.4 1079.7 1079.8 1096.8 1099.7 11573.7 11665.2 11689.1 11696.4 11711.1 4456.8 4543.7 4724.9 4728.6 4734.5 9914.7 9940.1 9957.7 9970.8 9991.5 5263.2 5270.8 5271.2 5274.9 5283.0 4112.3 4351.5 4500.3 4842.2 4857.0 3892.9 3949.2 3998.1 4049.6 4059.3 1270.9 1280.0 1283.1 1292.5 1292.6 726.2 910.6 916.1 918.0 944.0 5813.5 5886.8 5890.4 6046.6 6073.9 426.2 432.3 453.9 454.9 456.0 3862.5 3955.6 3999.6 4090.8 4091.8 10248.4 10766.5 10768.6 10771.8 10777.5 10170.7 10671.1 10675.4 10736.7 10753.5 6839.7 7971.3 8155.9 8174.3 8187.1 5842.1 5894.6 5896.6 6099.1 6236.6 9627.0 9810.0 9850.0 10024.4 10091.8 9952.7 10066.4 10180.8 10331.7 10334.5 7508.0 7590.1 7604.8 7690.8 7706.8 8091.1 8270.0 8277.5 8284.6 8290.1 7368.9 7393.9 7436.2 7444.3 7626.3 7375.3 7416.6 7440.0 7450.4 7526.7 6947.4 6955.3 7017.9 7029.8 7042.3 4955.2 4956.5 5037.8 5101.2 5136.2 4310.6 4389.9 4408.9 4436.9 4485.7 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4501.91 Median:5158.05 stddev:1426.33 (27.7%) stddev-sans-first:1394.97 17:03:20 INFO - PROCESS | 1664 | Values: 296.6 308.0 308.9 313.9 315.4 5349.4 5349.7 5360.4 5372.5 5428.3 5176.2 5306.8 5326.5 5350.3 5354.0 2792.7 3640.3 3927.3 4076.4 4112.4 3419.2 3592.2 3878.4 4125.4 4140.4 3523.3 3973.2 4062.7 4100.7 4102.9 3485.7 3780.9 3925.4 3961.1 4084.9 3122.3 3364.9 3551.9 3558.8 3575.6 4320.6 4516.4 5139.9 5305.5 5323.4 4080.6 4230.4 4344.7 4613.6 4655.8 5369.4 5619.7 5649.3 5658.2 5663.4 3829.7 3833.4 3863.4 3879.9 3889.4 4713.0 5822.7 5848.0 5975.2 6024.2 3465.4 3816.4 3901.4 3908.2 3925.2 3494.8 3970.8 3980.3 4000.8 4003.8 4665.2 4679.8 4688.2 4696.1 4696.2 4360.7 5355.4 5364.8 5678.4 5792.7 1139.6 1346.8 1349.5 1353.4 1359.4 671.9 771.8 795.8 820.2 820.2 4238.3 4497.4 5003.7 5182.5 5182.5 5356.9 5769.4 5774.2 5800.7 5818.0 4675.4 5017.8 5056.5 5240.0 5260.6 2813.6 2997.5 3034.5 3045.4 3047.8 5312.3 6040.8 6055.0 6061.8 6089.0 5671.7 5973.5 5989.6 5994.8 6008.7 4591.9 5382.5 5716.5 5993.9 6044.5 5086.8 5407.7 5507.4 5758.4 5814.9 4902.7 5372.0 5491.0 5643.2 5699.7 5360.9 5577.7 5788.2 5946.2 6009.0 2938.9 3135.9 3351.7 3386.2 3575.6 4775.1 5788.3 5792.0 5803.9 5815.0 4665.3 5642.5 5649.3 5651.3 5665.6 5306.0 5332.3 5612.5 5665.9 5666.4 4567.6 5534.6 5555.4 5568.7 5613.7 3956.5 5396.7 5459.7 5608.6 5613.7 4218.1 4914.5 5130.8 5489.8 5551.3 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:25315.15 Median:13108.57 stddev:40417.49 (308.3%) stddev-sans-first:40488.67 17:03:20 INFO - PROCESS | 1664 | Values: 718.4 770.9 771.4 798.2 872.9 13314.4 14934.9 16355.8 16379.8 16404.6 14764.4 14915.6 14923.9 14925.7 14973.6 695.6 843.4 851.4 875.8 879.3 877.3 881.0 882.2 882.4 882.8 884.1 886.0 934.3 935.9 936.1 730.0 885.7 915.5 927.9 929.4 687.2 690.5 691.3 691.8 692.6 14774.0 14811.9 14826.3 14835.7 14836.8 14045.4 14059.9 14082.0 14310.0 14442.6 13192.7 13349.9 13419.3 13548.3 13709.5 3857.2 4754.6 4783.1 4809.2 4844.5 111911.4 117046.0 117454.4 117565.0 117648.0 10965.9 11133.6 11194.3 11314.8 11720.8 22590.3 22638.2 22639.4 22678.7 22684.0 6393.8 7479.4 7516.1 7585.5 7674.5 4140.3 4333.1 4426.9 4474.3 4693.8 3533.1 3650.5 3655.7 3658.7 3666.4 476.5 628.4 651.8 656.2 678.3 627.5 660.8 667.7 704.9 705.0 8292.2 8770.8 9671.4 9681.0 10038.5 619.3 683.9 686.0 709.5 711.6 4636.7 4752.9 4849.2 4912.6 4961.6 132493.0 156100.0 156750.0 157193.0 157987.0 114810.0 133171.7 135173.0 136944.0 138256.0 114941.0 116174.0 116868.4 118270.0 118969.0 9391.6 9396.6 9478.2 9508.1 9561.5 58242.9 59083.0 60966.0 61177.7 64673.0 71274.0 81468.4 82859.5 83747.6 87594.1 13419.1 13585.3 13612.3 14217.3 14226.4 14915.7 15063.5 15295.2 15421.3 15692.4 13273.0 13497.3 13522.7 14094.0 14165.6 14304.1 14312.3 14443.6 14729.0 14764.7 14060.9 14226.6 14272.5 14368.5 14378.8 11105.0 11261.3 11471.8 11756.6 12218.6 11222.2 11408.6 11670.2 11802.3 11836.8 17:03:20 INFO - PROCESS | 1664 | -------- Summary: end -------- 17:03:20 INFO - PROCESS | 1664 | 17:03:20 INFO - TEST-INFO | 1664: exit 0 17:03:20 INFO - TEST-OK | dromaeo_css | took 1116965ms 17:03:20 INFO - TEST-START | kraken 17:03:20 INFO - operating with platform_type : mac_ 17:03:20 INFO - Initialising browser for kraken test... 17:03:20 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpV41jT3/profile http://localhost:49279/getInfo.html) 17:03:23 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 17:03:23 INFO - PROCESS | 1670 | colorDepth:24 17:03:23 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 17:03:23 INFO - PROCESS | 1670 | __metrics 17:03:23 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:03:23 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:03:23 INFO - TEST-INFO | 1670: exit 0 17:03:23 INFO - Browser initialized. 17:03:23 INFO - Running cycle 1/1 for kraken test... 17:03:23 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpV41jT3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:35 INFO - PROCESS | 1671 | RSS: Main: 243650560 17:03:35 INFO - PROCESS | 1671 | 17:03:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 17:03:39 INFO - PROCESS | 1671 | RSS: Main: 293036032 17:03:39 INFO - PROCESS | 1671 | 17:03:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 17:03:42 INFO - PROCESS | 1671 | RSS: Main: 305573888 17:03:42 INFO - PROCESS | 1671 | 17:03:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 17:03:47 INFO - PROCESS | 1671 | RSS: Main: 397922304 17:03:47 INFO - PROCESS | 1671 | 17:03:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 17:03:49 INFO - PROCESS | 1671 | RSS: Main: 298733568 17:03:49 INFO - PROCESS | 1671 | 17:03:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:03:52 INFO - PROCESS | 1671 | RSS: Main: 302596096 17:03:52 INFO - PROCESS | 1671 | 17:03:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 17:03:55 INFO - PROCESS | 1671 | RSS: Main: 339308544 17:03:55 INFO - PROCESS | 1671 | 17:03:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 17:03:59 INFO - PROCESS | 1671 | RSS: Main: 341508096 17:03:59 INFO - PROCESS | 1671 | 17:04:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 17:04:02 INFO - PROCESS | 1671 | RSS: Main: 339402752 17:04:02 INFO - PROCESS | 1671 | 17:04:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:04:04 INFO - PROCESS | 1671 | RSS: Main: 314089472 17:04:04 INFO - PROCESS | 1671 | 17:04:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:04:07 INFO - PROCESS | 1671 | RSS: Main: 320348160 17:04:07 INFO - PROCESS | 1671 | 17:04:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:04:10 INFO - PROCESS | 1671 | RSS: Main: 326692864 17:04:10 INFO - PROCESS | 1671 | 17:04:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:04:13 INFO - PROCESS | 1671 | RSS: Main: 330571776 17:04:13 INFO - PROCESS | 1671 | 17:04:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:04:17 INFO - PROCESS | 1671 | RSS: Main: 331603968 17:04:17 INFO - PROCESS | 1671 | 17:04:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 17:04:19 INFO - PROCESS | 1671 | RSS: Main: 375287808 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | __start_tp_report 17:04:19 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 17:04:19 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 17:04:19 INFO - PROCESS | 1671 | |i|pagename|runs| 17:04:19 INFO - PROCESS | 1671 | |0;ai-astar;102;112;113;112;111;123;163;152;112;112 17:04:19 INFO - PROCESS | 1671 | |1;audio-beat-detection;144;127;136;165;125;137;160;107;159;105 17:04:19 INFO - PROCESS | 1671 | |2;audio-dft;187;182;186;183;183;183;183;184;180;182 17:04:19 INFO - PROCESS | 1671 | |3;audio-fft;75;56;62;88;57;56;66;65;82;56 17:04:19 INFO - PROCESS | 1671 | |4;audio-oscillator;84;92;93;70;70;69;97;70;68;102 17:04:19 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;84;88;85;88;84;88;86;88;85 17:04:19 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;94;93;94;93;93;93;93;94;92 17:04:19 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;85;83;85;85;85;86;85;94;85 17:04:19 INFO - PROCESS | 1671 | |8;json-parse-financial;54;54;55;55;52;52;51;52;52;51 17:04:19 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;68;74;76;72;69;70;70;71;74 17:04:19 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;81;86;86;85;85;83;91;101;104;83 17:04:19 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;144;151;152;138;135;135;137;138;160;137 17:04:19 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;165;165;165;168;164;165;165;164;164;243 17:04:19 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;67;68;68;65;70;85;76;67;67 17:04:19 INFO - PROCESS | 1671 | __end_tp_report 17:04:19 INFO - PROCESS | 1671 | __start_cc_report 17:04:19 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2027 17:04:19 INFO - PROCESS | 1671 | __end_cc_report 17:04:19 INFO - PROCESS | 1671 | __startTimestamp1470873859231__endTimestamp 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | ------- Summary: start ------- 17:04:19 INFO - PROCESS | 1671 | Number of tests: 14 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:121.20 Median:112.50 stddev:19.94 (17.7%) stddev-sans-first:19.90 17:04:19 INFO - PROCESS | 1671 | Values: 102.0 112.0 113.0 112.0 111.0 123.0 163.0 152.0 112.0 112.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:140.50 stddev:21.11 (15.0%) stddev-sans-first:22.22 17:04:19 INFO - PROCESS | 1671 | Values: 144.0 127.0 136.0 165.0 125.0 137.0 160.0 107.0 159.0 105.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.00 stddev:2.00 (1.1%) stddev-sans-first:1.62 17:04:19 INFO - PROCESS | 1671 | Values: 187.0 182.0 186.0 183.0 183.0 183.0 183.0 184.0 180.0 182.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:66.30 Median:65.50 stddev:11.63 (17.8%) stddev-sans-first:11.91 17:04:19 INFO - PROCESS | 1671 | Values: 75.0 56.0 62.0 88.0 57.0 56.0 66.0 65.0 82.0 56.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:77.00 stddev:13.52 (17.6%) stddev-sans-first:14.31 17:04:19 INFO - PROCESS | 1671 | Values: 84.0 92.0 93.0 70.0 70.0 69.0 97.0 70.0 68.0 102.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.79 17:04:19 INFO - PROCESS | 1671 | Values: 88.0 84.0 88.0 85.0 88.0 84.0 88.0 86.0 88.0 85.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 17:04:19 INFO - PROCESS | 1671 | Values: 93.0 94.0 93.0 94.0 93.0 93.0 93.0 93.0 94.0 92.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:85.00 stddev:2.97 (3.5%) stddev-sans-first:3.14 17:04:19 INFO - PROCESS | 1671 | Values: 85.0 85.0 83.0 85.0 85.0 85.0 86.0 85.0 94.0 85.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.55 (2.9%) stddev-sans-first:1.58 17:04:19 INFO - PROCESS | 1671 | Values: 54.0 54.0 55.0 55.0 52.0 52.0 51.0 52.0 52.0 51.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:3.25 (4.5%) stddev-sans-first:2.65 17:04:19 INFO - PROCESS | 1671 | Values: 65.0 68.0 74.0 76.0 72.0 69.0 70.0 70.0 71.0 74.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:85.00 stddev:7.86 (9.3%) stddev-sans-first:7.86 17:04:19 INFO - PROCESS | 1671 | Values: 81.0 86.0 86.0 85.0 85.0 83.0 91.0 101.0 104.0 83.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:141.00 stddev:8.72 (6.2%) stddev-sans-first:9.23 17:04:19 INFO - PROCESS | 1671 | Values: 144.0 151.0 152.0 138.0 135.0 135.0 137.0 138.0 160.0 137.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:165.00 stddev:24.69 (15.0%) stddev-sans-first:26.03 17:04:19 INFO - PROCESS | 1671 | Values: 165.0 165.0 165.0 168.0 164.0 165.0 165.0 164.0 164.0 243.0 17:04:19 INFO - PROCESS | 1671 | 17:04:19 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:68.00 stddev:6.01 (8.8%) stddev-sans-first:6.32 17:04:19 INFO - PROCESS | 1671 | Values: 68.0 67.0 68.0 68.0 65.0 70.0 85.0 76.0 67.0 67.0 17:04:19 INFO - PROCESS | 1671 | -------- Summary: end -------- 17:04:19 INFO - PROCESS | 1671 | 17:04:20 INFO - TEST-INFO | 1671: exit 0 17:04:20 INFO - TEST-OK | kraken | took 59109ms 17:04:20 INFO - SUITE-END | took 1176s 17:04:20 INFO - Completed test suite (00:19:36) 17:04:20 INFO - javascript benchmark 17:04:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.2781851901836, 3372.595288565374, 3374.325134973006, 3387.4749413840855, 3437.4671925851485, 5642.181883626871, 5662.943370566293, 5678.233438485803, 5729.05470597352, 5809.651420914748, 5734.483896449318, 5739.282589676291, 5830.4169583041785, 5854.268216472941, 5865.76536938523, 5188.247704082905, 5192.221166824968, 5270.789168433258, 5274.182501712241, 5369.194620806871, 5318.734063296839, 5355.946440535603, 5369.114096174127, 5384.326959130109, 5406.053940560386, 5163.8192663256605, 5178.689278643294, 5213.0, 5220.817271395521, 5250.396204436501, 5223.268742376071, 5239.476052394768, 5263.736813159365, 5329.0, 5345.984262990059, 5209.244659524347, 5245.501677340647, 5258.0535503609335, 5271.130263506517, 5328.174133009351, 5468.890622187534, 5791.044477661181, 5791.102379131199, 5832.445917637818, 5859.150423188614, 5970.283565972068, 6166.290876549209, 6230.688465576703, 6234.657093859836, 6305.180326557518, 6245.188125543696, 6255.843603909893, 6313.494920406357, 6322.367763223687, 6330.525210609223, 5682.40334764852, 5885.234919460486, 5889.087191485327, 5891.998360278762, 5897.380775018604, 6206.844828879224, 6239.469645080128, 6268.686565671698, 6290.339514351019, 6336.461400780985, 6194.039923811816, 6207.844803879849, 6216.222972128484, 6226.875462490725, 6235.314115447297, 6252.343503932113, 6298.842528936813, 6327.683615819191, 6333.746650134035, 6342.016987366966, 6098.190188918898, 6128.655903792257, 6186.907196392058, 6188.907166392508, 6212.409821066992, 6255.280642726054, 6278.529110316745, 6292.1505596743855, 6297.811065668037, 6298.7795427160745, 5002.174641184237, 5015.724135172528, 5019.29729837823, 5022.723750193774, 5054.545090941833, 4419.801108950105, 4447.755373454426, 4488.708233964782, 4515.932261016087, 4555.521670224645, 5740.56945729072, 5747.453991870766, 5762.769489220468, 5764.884702305931, 5821.417858214145, 6174.783882564088, 6295.779647712308, 6320.905186422207, 6327.209098862642, 6365.777197798147, 4789.44921334051, 5636.379998200195, 5673.687947162863, 5676.489115979582, 5726.055200891889, 5460.372057213392, 5472.534834539029, 5478.45215478449, 5480.588955828329, 5501.477359650827, 6318.336574659595, 6327.683615819098, 6341.0, 6351.301356850743, 6355.682215889279, 6148.016317389196, 6208.596441231305, 6222.906656400067, 6227.782027629192, 6315.242170939517, 6281.277653069956, 6310.463610593151, 6351.555391122577, 6359.523035772243, 6367.840803979938, 6204.72078756448, 6225.190725205875, 6244.281907580505, 6246.3441338660605, 6251.249850018027, 6101.359357267601, 6292.182016337847, 6298.433141017331, 6314.747410103544, 6318.842028949313, 6067.514598832171, 6101.51991978472, 6103.974138059404, 6173.135760993375, 6185.8144255672405, 6152.538559607958, 6161.691915404302, 6163.044728067157, 6177.042558403455, 6180.81457556274, 5772.451617096369, 5866.794662186803, 5963.827438601201, 6042.728077236447, 6045.6070355426755, 6109.480694141048, 6118.4668989547035, 6121.757838957599, 6137.693115344304, 6168.814935551941, 5970.552208584287, 5974.0, 5988.401159883977, 6008.098785182257, 6014.278286605635], "value": 5743.776902826235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.0051398744473, 522.9843104706857, 530.8168681804776, 534.201368953415, 550.5595523581134, 30393.392132157373, 30831.537526937114, 31906.72373105073, 31958.72165113393, 31975.040748777516, 41117.38323925136, 41968.0, 42029.57970420295, 44402.77798611002, 44591.0, 8234.0, 9584.568694408734, 9744.123028927395, 9746.415215087118, 9757.219422446222, 8629.784255393603, 9612.90387096127, 9708.368956017835, 9711.902880971205, 9749.220062394992, 8671.913280867206, 9828.21374290055, 11939.104567157454, 11949.0, 12222.63332100034, 11937.34344611046, 12003.519859205622, 12015.819762703566, 12021.519139234464, 12022.819657705142, 8598.742037738877, 11559.711007224803, 11800.996915262212, 11900.166988310822, 11924.940375298154, 38159.427608585895, 40297.0, 40350.59649403498, 40390.0, 40426.79786601048, 3497.0, 3599.6580324869096, 3815.637514436124, 3947.842086316544, 3979.8408063677416, 30467.847660761778, 32081.83959080213, 32380.83809580937, 32464.513032304298, 32747.672523274938, 3265.755068369882, 3354.362671092509, 3383.8477268522975, 3413.9317213655586, 3423.7432192585657, 461217.71475996694, 461371.0, 462847.0, 462994.0, 463072.68463657465, 56534.0, 56688.0, 56787.60517750807, 57193.85612287731, 57213.42786572164, 59558.0, 59611.80776384448, 59642.10536841951, 59646.0, 59748.70125649344, 13849.23829189384, 16028.679426411407, 16062.0, 16347.509574712778, 16356.263968121462, 12744.235345879091, 12785.552505662257, 12802.80795788064, 12814.42335094923, 12826.61520154397, 11524.481398337095, 13583.864161358457, 13951.930240348733, 15097.0, 15343.156126413154, 6064.877292947522, 6140.048292514667, 6171.907421388682, 6193.349698281706, 6197.380261973766, 913.1598928985217, 1004.6232662751469, 1038.3925403638805, 1043.9791204176026, 1077.9784404312027, 29976.700232997828, 30286.394272114434, 30731.846340768152, 30791.538126928113, 30926.917557885365, 1060.4697651174413, 1074.387599068539, 1099.230538622967, 1107.9889201108047, 1249.4252643783962, 15362.539123826304, 15553.655963967802, 15610.78541639706, 15961.44134955271, 15963.920180399024, 649561.0, 651802.0, 653658.0, 654181.0, 654946.0, 57561.71219143961, 57587.71206144026, 57606.8478630425, 57666.13500797492, 57842.7107864458, 1367.8290213723285, 1406.880415164702, 1426.892983026277, 1427.0581416265215, 1430.3563396471836, 29205.0, 29598.556021660122, 30335.848320758254, 30336.69663303339, 30896.918607848616, 49810.0, 49817.003659925875, 49843.25235121549, 49876.00247995093, 50029.74985125051, 485233.0, 485661.0, 485764.0, 486107.0, 486828.0, 40422.59577404188, 40470.59529404786, 40903.795481022404, 41064.973375665846, 41074.0, 46176.0, 47194.76402617965, 47417.28874066823, 47685.28472072852, 47757.04485910193, 37200.883973480835, 38545.807270964586, 38969.41545876757, 39420.60579394284, 39779.40330894981, 33483.83258083694, 38518.0, 38792.80603596964, 39104.2179156421, 40004.399934001594, 38353.80823095978, 38417.84746457611, 38467.0, 38694.61305387023, 38851.6114838848, 27236.863815680794, 33424.498632520044, 34321.0, 34331.0, 34345.313093738485, 24668.753312466644, 25112.24663260105, 25263.747362526876, 25647.743522564535, 25782.742172578033], "value": 20070.903087907856, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.4308242188866, 367.783008025265, 372.7167352811863, 378.56276001218595, 399.4687066201952, 14045.36795844187, 15774.68450630988, 20614.587708245825, 21393.0, 21430.357089287307, 20785.896070519662, 21094.472638184016, 21112.366629001117, 21146.894265528652, 21219.36341909741, 5666.631668941516, 5713.685747283897, 5845.503132233765, 5909.497692696126, 5971.253593300838, 5791.160281759142, 5877.324107727602, 5883.264591926009, 5893.528517718593, 5896.410358964091, 5550.91673624894, 5745.166950792132, 5822.0, 5825.271841019872, 5846.356900740915, 5796.797112101056, 5845.327787304472, 5845.3862344453855, 5847.181394604759, 5873.207117039187, 4872.317875497433, 5062.645614806965, 5121.897562048738, 5128.820491282787, 5129.358830146231, 24290.878545607335, 24476.0, 24684.506309873883, 24810.379740506633, 24915.377115572075, 2239.507308392151, 2298.367948814073, 2393.198278576679, 2407.8916448759846, 3060.4338197433544, 14829.925850370679, 14886.70226595462, 15105.47130850415, 15261.61845953849, 15455.690886182214, 1800.8379245867934, 1861.1159699142934, 1862.3854128137818, 1872.7939926608062, 1874.4535757999704, 88848.0, 111811.0, 113627.43186284098, 114088.0, 114392.0, 10372.325798823053, 10815.891841081646, 10885.679867640287, 10971.670849874517, 11078.0, 13736.145620341125, 13763.518276860463, 13766.793498097537, 13774.793378099337, 13794.379252933642, 8386.622601982925, 8511.659533618586, 8525.488470691778, 8538.786530336667, 8661.437006594551, 8579.099194584478, 8581.742547723577, 8588.8711669325, 8730.345224108218, 8804.075572064841, 6593.835154121185, 6594.439472644783, 6612.206535215709, 6643.900341494881, 6675.065490831287, 1582.4619629326085, 1818.7544681467832, 1844.6864033114402, 1853.4069097888816, 1861.8045105263752, 956.3544607389983, 972.5283237629744, 981.7202097402348, 1029.4441001858913, 1044.7858189071221, 19546.120424580928, 21570.676439853414, 21819.67270490912, 22125.0, 22136.77863221347, 917.1653795046476, 920.1120918313859, 942.5758408716105, 976.3653625143571, 994.1947022911465, 10990.395528245863, 11319.943400282946, 11418.143639227092, 11424.028957538701, 11426.428678566039, 617037.914810432, 619043.3163708576, 621343.0, 630996.0, 641280.0, 98447.89135738292, 129094.0, 144965.0, 148076.0, 152257.0, 994.6170724271208, 1019.4698756646502, 1031.8194315994822, 1053.5627714498398, 1055.6569115037644, 18213.54466138357, 19349.51626209356, 19427.028648567233, 19427.70858437153, 19498.61002779908, 40301.387944481896, 54485.0, 60900.78198435918, 61734.69132654308, 63622.68188659027, 74976.0, 84525.0, 86249.56875215584, 86281.56859215663, 87145.56427217824, 7193.352598266181, 7273.599952002691, 7839.490433121829, 8008.439409241297, 8503.1921967413, 9400.576974036048, 13042.804357934448, 13183.0, 13605.727885442433, 13872.653183670489, 8977.461352318882, 11479.655610331703, 11768.818224490311, 11811.995980341768, 11901.642950711492, 11171.72070698239, 11344.092472602337, 11448.026917711755, 11699.941500292443, 11902.285862848257, 9078.5501509426, 11778.116641252112, 11965.0, 12014.399280035788, 12090.0, 6535.248446428722, 8064.55644939534, 8264.925559677204, 8681.262092721936, 8725.825483490167, 9626.507891276864, 11378.601748938747, 11605.361705106301, 11835.822462662894, 11901.166918315635], "value": 9804.520376253107, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1531.2037740375006, 1536.8924175307725, 1559.734845076337, 1635.63198280387, 1852.4442667199837, 8343.08226095129, 8897.822043559134, 8907.10928907109, 8918.821623567525, 8924.241439477644, 7733.845323093535, 7975.880361794578, 8402.831943361129, 8631.69789057383, 8666.39335246533, 1884.8869067855903, 2024.3724445421967, 2337.2170322941834, 2337.801286890616, 2340.192633541434, 2298.6666933294664, 2310.353101131686, 2380.321608341623, 2380.369202161429, 2382.8808559571953, 2338.6258198688215, 2350.2831636350907, 2358.0567772890895, 2383.9046438142454, 2388.164142550111, 2321.6749655048307, 2333.0784340185614, 2336.8130549555995, 2341.800946919514, 2344.6248600223967, 2331.988340058289, 2338.75443078476, 2339.1345202275097, 2341.285907798121, 2343.906243750248, 8727.38908276421, 8908.643654253821, 8910.86633700495, 8924.196822286025, 8946.060663630291, 4217.683673724483, 5288.68267903927, 5295.655782374133, 5298.152295632719, 5312.335958005249, 3428.160100775326, 3490.493878387645, 3633.8909832705062, 3637.4907512948207, 3645.7994810285422, 1040.3944004628383, 1079.681493959276, 1079.7678499122726, 1096.7641956979287, 1099.6810924831789, 11573.710657233636, 11665.241759285704, 11689.064874809996, 11696.356700381475, 11711.121665875093, 4456.821727130879, 4543.704659197141, 4724.929126063112, 4728.621710263171, 4734.455537613149, 9914.652987145559, 9940.10539051489, 9957.701268961942, 9970.800583988279, 9991.500424978722, 5263.184206448007, 5270.8155214567305, 5271.182966640177, 5274.868128296746, 5283.049051170826, 4112.300908845504, 4351.5430879757805, 4500.347449619787, 4842.176829938919, 4856.975715121402, 3892.9221415572097, 3949.19041596472, 3998.100427403811, 4049.57479464652, 4059.330210515232, 1270.9491620335084, 1280.0271793437055, 1283.1274733181526, 1292.4507084489242, 1292.5992942187952, 726.1939247435342, 910.5811326789751, 916.1205242966694, 917.958691858868, 944.0096088464792, 5813.505852002542, 5886.764529418775, 5890.3815099414805, 6046.637201767908, 6073.878522429527, 426.1966193724851, 432.2737800495198, 453.88425951382845, 454.91356642237866, 455.9612432943237, 3862.4978752761963, 3955.595763503972, 3999.6200360965663, 4090.795460226977, 4091.754494730326, 10248.38509689406, 10766.515506802212, 10768.569257229623, 10771.784564308826, 10777.515011824487, 10170.694879153638, 10671.146308295472, 10675.412852293042, 10736.73158171052, 10753.516091775733, 6839.658017099225, 7971.282584567417, 8155.877661835078, 8174.264316211569, 8187.058488273806, 5842.094475356326, 5894.557908156991, 5896.616719913192, 6099.054646529795, 6236.6258024518675, 9626.95186524063, 9810.018998100133, 9849.965753595769, 10024.398536087858, 10091.848622270818, 9952.701418957442, 10066.396016239049, 10180.847287290844, 10331.741706457398, 10334.534945927602, 7507.962460187664, 7590.051243594551, 7604.80987975305, 7690.807729806799, 7706.7687969361, 8091.1099779025435, 8269.95865020671, 8277.461964972257, 8284.585770711561, 8290.129536398592, 7368.852622947404, 7393.889091663522, 7436.219196984455, 7444.255574442513, 7626.275503827128, 7375.299346562068, 7416.555006699616, 7440.0, 7450.441216908645, 7526.736564220226, 6947.44420446353, 6955.304469553206, 7017.929820701727, 7029.824254393477, 7042.330978557234, 4955.1576232039915, 4956.454789973172, 5037.823676171316, 5101.158308879142, 5136.152534831711, 4310.612044915973, 4389.868303950887, 4408.9118217636105, 4436.9334459983775, 4485.663575231936], "value": 4463.382294140732, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.57885802160934, 308.0281711201161, 308.9243135431819, 313.8932762860627, 315.37713016791827, 5349.358077030757, 5349.679019258842, 5360.383555891074, 5372.516473517383, 5428.294321738178, 5176.195496310218, 5306.814261500848, 5326.493983071612, 5350.30446042014, 5354.0, 2792.748652621264, 3640.3447379471686, 3927.3127202739547, 4076.368162934735, 4112.444819949298, 3419.2135808764, 3592.1917568546996, 3878.437626544149, 4125.443065186191, 4140.378943158536, 3523.3481805865867, 3973.2053589282114, 4062.7359221650645, 4100.671946244293, 4102.938455923164, 3485.721142308622, 3780.8676696315497, 3925.390589858173, 3961.0972568578554, 4084.8774536763945, 3122.266267427153, 3364.932701345984, 3551.8934431966823, 3558.7864728116397, 3575.606683264839, 4320.589543993316, 4516.412866327357, 5139.897202055938, 5305.522502974713, 5323.361196656426, 4080.6327430531396, 4230.428892099558, 4344.6524278058, 4613.63090952723, 4655.767211639404, 5369.40936496985, 5619.7471113799975, 5649.260108847206, 5658.151277308396, 5663.4336566343445, 3829.702178692762, 3833.4058220975794, 3863.4398012288343, 3879.86420475282, 3889.4165875118674, 4713.0, 5822.737976791054, 5847.999290912854, 5975.223220981245, 6024.21685180928, 3465.358908601917, 3816.4084566892175, 3901.4147877818555, 3908.218356328689, 3925.21495700861, 3494.807785571767, 3970.7617542946937, 3980.3233450313514, 4000.7799571023875, 4003.7597744135446, 4665.160271151158, 4679.81280748773, 4688.179568575486, 4696.107739529479, 4696.1546921554445, 4360.672949528798, 5355.410904800468, 5364.81973965727, 5678.432156784371, 5792.681402522818, 1139.5897476908306, 1346.8181795457488, 1349.5209200733796, 1353.41126609925, 1359.3814814259488, 671.8924972004456, 771.830676525065, 795.7930937956057, 820.2044017303207, 820.2371794231303, 4238.325861284837, 4497.437820272466, 5003.74981250936, 5182.507661772125, 5182.548886311839, 5356.857257097192, 5769.3652998610605, 5774.220480235199, 5800.651960882361, 5818.0, 4675.392199014106, 5017.774200161001, 5056.544910958031, 5239.973800131052, 5260.552853007537, 2813.606095146681, 2997.460457117696, 3034.484137696597, 3045.4365942300747, 3047.8265867641117, 5312.309399778082, 6040.818775436744, 6054.970654988662, 6061.818145455643, 6088.969555152196, 5671.6597004179885, 5973.462388384979, 5989.610675306091, 5994.760209591567, 6008.699565021731, 4591.897944493231, 5382.4886635769535, 5716.485516303469, 5993.880122397528, 6044.516438684895, 5086.796528138833, 5407.72961351923, 5507.375324279427, 5758.416435480276, 5814.912776308443, 4902.730349830651, 5372.0, 5490.972545137249, 5643.181738647791, 5699.694177377194, 5360.946390536201, 5577.721113944368, 5788.189653448437, 5946.226990491208, 6008.969955150196, 2938.882444702188, 3135.8745650173737, 3351.731861451127, 3386.2211691311627, 3575.5530558680166, 4775.092732380836, 5788.305403351625, 5791.971040144772, 5803.8549036274435, 5814.970925145347, 4665.2535594304745, 5642.520385767256, 5649.288341596139, 5651.2935883014625, 5665.620439415373, 5306.0, 5332.253484512094, 5612.522935550361, 5665.886682266414, 5666.43851699386, 4567.63458923292, 5534.640248383842, 5555.361238388862, 5568.716826101279, 5613.747381367766, 3956.5074003377686, 5396.730163491889, 5459.679636154698, 5608.579356548194, 5613.719314034363, 4218.093109981369, 4914.533119353656, 5130.794768209379, 5489.835304940858, 5551.333839939233], "value": 4043.842987041225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.3570704219724, 770.9421793365495, 771.4098714483421, 798.249645333387, 872.8821609082773, 13314.400851961673, 14934.925325373371, 16355.83644163558, 16379.83620163798, 16404.589885252874, 14764.409423623069, 14915.627109322273, 14923.85076149241, 14925.701485970274, 14973.550793476186, 695.6174104242672, 843.359047124187, 851.4040171879693, 875.8204568063563, 879.2526352600303, 877.33322674767, 881.0, 882.232457761745, 882.4308321132866, 882.7748924024397, 884.107051877605, 885.9778505537348, 934.2806039349697, 935.9251259899225, 936.1200471556715, 729.9854002919965, 885.6723012485357, 915.4873270968279, 927.91648751612, 929.4376901974276, 687.15136806045, 690.547691262224, 691.325957191739, 691.837418206721, 692.629443247865, 14774.0, 14811.925940370229, 14826.33281502335, 14835.703285934329, 14836.777448338284, 14045.43818247269, 14059.859401405958, 14082.0, 14310.0, 14442.638934026734, 13192.6701832454, 13349.933250333686, 13419.261940593264, 13548.254845983467, 13709.45162193511, 3857.2092720992127, 4754.643401744883, 4783.115123702126, 4809.158397280462, 4844.539768721966, 111911.44044279726, 117046.0, 117454.4127279358, 117565.0, 117648.0, 10965.890341096647, 11133.554657813596, 11194.328340299446, 11314.793615244947, 11720.765584688259, 22590.322290330987, 22638.207662731726, 22639.434014149778, 22678.659820102712, 22684.0, 6393.7762178324465, 7479.439042071866, 7516.135644400854, 7585.506942048749, 7674.501157424749, 4140.3375459926265, 4333.133373325284, 4426.867193984185, 4474.26174681174, 4693.81224751013, 3533.1343820764077, 3650.525431693916, 3655.6527129922615, 3658.7438879278193, 3666.4133738602227, 476.53299766229287, 628.3936001758325, 651.8109748172976, 656.150285380424, 678.3047376439091, 627.5481653209682, 660.8480049588621, 667.7228949985798, 704.859028194353, 704.989425158623, 8292.17078292178, 8770.824583508294, 9671.371360861522, 9681.031896810262, 10038.548265328078, 619.3373090792899, 683.931606839312, 685.9725610975605, 709.4537206351081, 711.5872793779596, 4636.736149992858, 4752.928706069411, 4849.151398505247, 4912.557869791736, 4961.578928997054, 132493.0, 156100.0, 156750.0, 157193.0, 157987.0, 114810.0, 133171.66828331788, 135173.0, 136944.0, 138256.0, 114941.0, 116174.0, 116868.41565792117, 118269.99999999828, 118969.0, 9391.624335026523, 9396.57715402795, 9478.24174066087, 9508.144267016001, 9561.452292047428, 58242.92299539161, 59083.0, 60966.0, 61177.694111529156, 64673.0, 71274.0, 81468.37063258821, 82859.51421457366, 83747.58126209574, 87594.12405876114, 13419.135043058313, 13585.252811095485, 13612.251326177155, 14217.289135543388, 14226.430942762174, 14915.701685966436, 15063.47277845314, 15295.235238237821, 15421.306041227946, 15692.372305107669, 13273.0, 13497.257650828906, 13522.72954540884, 14094.013419060568, 14165.575032749033, 14304.070235434663, 14312.284385780878, 14443.638909026939, 14728.968972171846, 14764.704705905608, 14060.859391406364, 14226.644333891736, 14272.500462484179, 14368.497102601359, 14378.784318235445, 11105.0, 11261.324320540794, 11471.827922581, 11756.58851940178, 12218.572349968064, 11222.214444989098, 11408.600698975828, 11670.241434306743, 11802.291862488279, 11836.82244766312], "value": 8034.069211114324, "unit": "score"}], "name": "dromaeo_css", "value": 7397.849136001927, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 112.0, 111.0, 123.0, 163.0, 152.0, 112.0, 112.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 127.0, 136.0, 165.0, 125.0, 137.0, 160.0, 107.0, 159.0, 105.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 186.0, 183.0, 183.0, 183.0, 183.0, 184.0, 180.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 56.0, 62.0, 88.0, 57.0, 56.0, 66.0, 65.0, 82.0, 56.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 92.0, 93.0, 70.0, 70.0, 69.0, 97.0, 70.0, 68.0, 102.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 85.0, 88.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 94.0, 92.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 85.0, 85.0, 85.0, 86.0, 85.0, 94.0, 85.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 55.0, 52.0, 52.0, 51.0, 52.0, 52.0, 51.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 74.0, 76.0, 72.0, 69.0, 70.0, 70.0, 71.0, 74.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 86.0, 85.0, 85.0, 83.0, 91.0, 101.0, 104.0, 83.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 151.0, 152.0, 138.0, 135.0, 135.0, 137.0, 138.0, 160.0, 137.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 168.0, 164.0, 165.0, 165.0, 164.0, 164.0, 243.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 68.0, 65.0, 70.0, 85.0, 76.0, 67.0, 67.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1451.9999999999998, "alertThreshold": 2.0}]} 17:04:20 INFO - Return code: 0 17:04:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:04:20 INFO - # TBPL SUCCESS # 17:04:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:04:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:04:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:04:20 INFO - 2016-08-10 17:04:20.522 screenresolution[1675:9710] starting screenresolution argv=screenresolution get 17:04:20 INFO - 2016-08-10 17:04:20.525 screenresolution[1675:9710] Display 0: 1600x1200x32@60 17:04:20 INFO - 2016-08-10 17:04:20.531 screenresolution[1676:9713] starting screenresolution argv=screenresolution list 17:04:20 INFO - Available Modes on Display 0 17:04:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:04:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:04:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:04:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:04:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:04:20 INFO - Intel Iris: 17:04:20 INFO - Chipset Model: Intel Iris 17:04:20 INFO - Type: GPU 17:04:20 INFO - Bus: Built-In 17:04:20 INFO - VRAM (Dynamic, Max): 1536 MB 17:04:20 INFO - Vendor: Intel (0x8086) 17:04:20 INFO - Device ID: 0x0a2e 17:04:20 INFO - Revision ID: 0x0009 17:04:20 INFO - Displays: 17:04:20 INFO - Display: 17:04:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:04:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:04:20 INFO - Display Serial Number: 1600x1200 60 17:04:20 INFO - Main Display: Yes 17:04:20 INFO - Mirror: Off 17:04:20 INFO - Online: Yes 17:04:20 INFO - Rotation: Supported 17:04:20 INFO - Return code: 0 17:04:20 INFO - Running post-action listener: _resource_record_post_action 17:04:20 INFO - [mozharness: 2016-08-11 00:04:20.768156Z] Finished run-tests step (success) 17:04:20 INFO - Running post-run listener: _resource_record_post_run 17:04:20 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 36987904; Write bytes: 700530176; Read time: 232; Write time: 1911 17:04:20 INFO - TinderboxPrint: CPU usage
53.5% 17:04:20 INFO - TinderboxPrint: I/O read bytes / time
36,987,904 / 232 17:04:20 INFO - TinderboxPrint: I/O write bytes / time
700,530,176 / 1,911 17:04:20 INFO - TinderboxPrint: CPU idle
2,253.6 (46.5%) 17:04:20 INFO - TinderboxPrint: CPU system
965.1 (19.9%) 17:04:20 INFO - TinderboxPrint: CPU user
1,623.0 (33.5%) 17:04:20 INFO - TinderboxPrint: Swap in / out
440,229,888 / 0 17:04:20 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 389808640; Write bytes: 380790272; Read time: 28181; Write time: 768 17:04:20 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30727168; Write bytes: 292292608; Read time: 178; Write time: 1080 17:04:21 INFO - Running post-run listener: _upload_blobber_files 17:04:21 INFO - Blob upload gear active. 17:04:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:04:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:04:21 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'] 17:04:21 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 17:04:21 INFO - (blobuploader) - INFO - Open directory for files ... 17:04:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:04:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:04:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:22 INFO - (blobuploader) - INFO - Done attempting. 17:04:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:04:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:04:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:23 INFO - (blobuploader) - INFO - Done attempting. 17:04:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:04:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:04:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:04:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:04:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:04:26 INFO - (blobuploader) - INFO - Done attempting. 17:04:26 INFO - (blobuploader) - INFO - Iteration through files over. 17:04:26 INFO - Return code: 0 17:04:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:04:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:04:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b1facdd378c1004158b4b2a4ac2ea4ab8828e8fee93606bcc1cc5c0fe8fe9ef247a932cd49e43c61dd4bb214294971d9f42eac57eca30c0df8842d97ecd4d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/33bc5aef2b742a048d042b35e04cfcf2d9fc2a4f6222e404b656e4be3feb3a875aa703006590f377972795becef450f200c1dc91b74d8859f357e7af1d3cbfde", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69ba826ccfca5aa4805ea0b39bcb2dd8a3eda45f81c5a1eb957f8a637a96f79ec4b2147edc80222a9e09d31748bae13c4b4513572eac16fb8509b553a87a5fdc"} 17:04:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:04:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:04:26 INFO - Contents: 17:04:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b1facdd378c1004158b4b2a4ac2ea4ab8828e8fee93606bcc1cc5c0fe8fe9ef247a932cd49e43c61dd4bb214294971d9f42eac57eca30c0df8842d97ecd4d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/33bc5aef2b742a048d042b35e04cfcf2d9fc2a4f6222e404b656e4be3feb3a875aa703006590f377972795becef450f200c1dc91b74d8859f357e7af1d3cbfde", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69ba826ccfca5aa4805ea0b39bcb2dd8a3eda45f81c5a1eb957f8a637a96f79ec4b2147edc80222a9e09d31748bae13c4b4513572eac16fb8509b553a87a5fdc"} 17:04:26 INFO - Running post-run listener: copy_logs_to_upload_dir 17:04:26 INFO - Copying logs to upload dir... 17:04:26 INFO - mkdir: /builds/slave/test/build/upload/logs 17:04:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.275354 ========= master_lag: 5.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-10 17:04:31.509270) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 17:04:31.512956) ========= 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.WzMUl1h6ps/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.QYCE31XvkA/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b1facdd378c1004158b4b2a4ac2ea4ab8828e8fee93606bcc1cc5c0fe8fe9ef247a932cd49e43c61dd4bb214294971d9f42eac57eca30c0df8842d97ecd4d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/33bc5aef2b742a048d042b35e04cfcf2d9fc2a4f6222e404b656e4be3feb3a875aa703006590f377972795becef450f200c1dc91b74d8859f357e7af1d3cbfde", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69ba826ccfca5aa4805ea0b39bcb2dd8a3eda45f81c5a1eb957f8a637a96f79ec4b2147edc80222a9e09d31748bae13c4b4513572eac16fb8509b553a87a5fdc"} build_url:https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010225 build_url: 'https://queue.taskcluster.net/v1/task/Q_5ftDxmT06AJ0SGHzND0Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b1facdd378c1004158b4b2a4ac2ea4ab8828e8fee93606bcc1cc5c0fe8fe9ef247a932cd49e43c61dd4bb214294971d9f42eac57eca30c0df8842d97ecd4d19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/33bc5aef2b742a048d042b35e04cfcf2d9fc2a4f6222e404b656e4be3feb3a875aa703006590f377972795becef450f200c1dc91b74d8859f357e7af1d3cbfde", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69ba826ccfca5aa4805ea0b39bcb2dd8a3eda45f81c5a1eb957f8a637a96f79ec4b2147edc80222a9e09d31748bae13c4b4513572eac16fb8509b553a87a5fdc"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 17:04:31.776545) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 17:04:31.776861) ========= 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.WzMUl1h6ps/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.QYCE31XvkA/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.005184 ========= master_lag: 1.98 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-08-10 17:04:33.759058) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-08-10 17:04:33.759328) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-08-10 17:04:36.237659) ========= ========= Total master_lag: 7.67 =========