builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0178 starttime: 1471028893.08 results: success (0) buildid: 20160812085316 builduid: 0d161786348a46b79b19a781bca2e821 revision: 8725f14625e0c87776f9acf66e6712ceca301000 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.082189) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.082833) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.083131) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.108044) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.108325) ========= 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.udfnaAX7qD/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.8vAsk88nqh/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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-12 12:08:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 45.9M=0s 2016-08-12 12:08:13 (45.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130213 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.261953) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.262366) ========= 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.udfnaAX7qD/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.8vAsk88nqh/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.028420 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:13.311336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 12:08:13.311696) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8725f14625e0c87776f9acf66e6712ceca301000 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8725f14625e0c87776f9acf66e6712ceca301000 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.udfnaAX7qD/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.8vAsk88nqh/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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-12 12:08:13,388 truncating revision to first 12 chars 2016-08-12 12:08:13,388 Setting DEBUG logging. 2016-08-12 12:08:13,388 attempt 1/10 2016-08-12 12:08:13,388 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8725f14625e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 12:08:14,234 unpacking tar archive at: fx-team-8725f14625e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.103425 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 12:08:14.437605) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:14.437957) ========= script_repo_revision: 8725f14625e0c87776f9acf66e6712ceca301000 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:14.438373) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:14.438646) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 12:08:14.457802) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-08-12 12:08:14.458184) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.udfnaAX7qD/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.8vAsk88nqh/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:14 INFO - MultiFileLogger online at 20160812 12:08:14 in /builds/slave/test 12:08:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:08:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:14 INFO - {'append_to_log': False, 12:08:14 INFO - 'base_work_dir': '/builds/slave/test', 12:08:14 INFO - 'blob_upload_branch': 'Fx-Team', 12:08:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:14 INFO - 'branch': 'Fx-Team', 12:08:14 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:14 INFO - 'config_files': ('talos/mac_config.py',), 12:08:14 INFO - 'default_actions': ('clobber', 12:08:14 INFO - 'read-buildbot-config', 12:08:14 INFO - 'download-and-extract', 12:08:14 INFO - 'populate-webroot', 12:08:14 INFO - 'create-virtualenv', 12:08:14 INFO - 'install', 12:08:14 INFO - 'run-tests'), 12:08:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:14 INFO - 'download_minidump_stackwalk': True, 12:08:14 INFO - 'download_symbols': 'ondemand', 12:08:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:14 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:14 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:14 INFO - 'installer_path': 'installer.exe', 12:08:14 INFO - 'log_level': 'info', 12:08:14 INFO - 'log_name': 'talos', 12:08:14 INFO - 'log_to_console': True, 12:08:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:14 INFO - 'opt_config_files': (), 12:08:14 INFO - 'pip_index': False, 12:08:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:14 INFO - 'cmd': ('bash', 12:08:14 INFO - '-c', 12:08:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:14 INFO - 'enabled': True, 12:08:14 INFO - 'halt_on_failure': False, 12:08:14 INFO - 'name': 'check_screen_resolution'},), 12:08:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:14 INFO - 'cmd': ('bash', 12:08:14 INFO - '-c', 12:08:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:14 INFO - 'enabled': True, 12:08:14 INFO - 'halt_on_failure': False, 12:08:14 INFO - 'name': 'check_screen_resolution'},), 12:08:14 INFO - 'run_cmd_checks_enabled': True, 12:08:14 INFO - 'sps_profile': False, 12:08:14 INFO - 'sps_profile_interval': 0, 12:08:14 INFO - 'suite': 'dromaeojs', 12:08:14 INFO - 'system_bits': '32', 12:08:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:14 INFO - 'title': 't-yosemite-r7-0178', 12:08:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:14 INFO - 'use_talos_json': True, 12:08:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:14 INFO - 'work_dir': 'build'} 12:08:14 INFO - [mozharness: 2016-08-12 19:08:14.668177Z] Running clobber step. 12:08:14 INFO - Running pre-action listener: _resource_record_pre_action 12:08:14 INFO - Running main action method: clobber 12:08:14 INFO - rmtree: /builds/slave/test/build 12:08:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:16 INFO - Running post-action listener: _resource_record_post_action 12:08:16 INFO - [mozharness: 2016-08-12 19:08:16.801159Z] Finished clobber step (success) 12:08:16 INFO - [mozharness: 2016-08-12 19:08:16.801359Z] Running read-buildbot-config step. 12:08:16 INFO - Running pre-action listener: _resource_record_pre_action 12:08:16 INFO - Running main action method: read_buildbot_config 12:08:16 INFO - Using buildbot properties: 12:08:16 INFO - { 12:08:16 INFO - "project": "", 12:08:16 INFO - "product": "firefox", 12:08:16 INFO - "script_repo_revision": "production", 12:08:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:08:16 INFO - "repository": "", 12:08:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:08:16 INFO - "buildid": "20160812085316", 12:08:16 INFO - "pgo_build": "False", 12:08:16 INFO - "basedir": "/builds/slave/test", 12:08:16 INFO - "buildnumber": 125, 12:08:16 INFO - "slavename": "t-yosemite-r7-0178", 12:08:16 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:08:16 INFO - "platform": "yosemite_r7", 12:08:16 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 12:08:16 INFO - "branch": "fx-team", 12:08:16 INFO - "repo_path": "integration/fx-team", 12:08:16 INFO - "revision": "8725f14625e0c87776f9acf66e6712ceca301000", 12:08:16 INFO - "stage_platform": "macosx64", 12:08:16 INFO - "builduid": "0d161786348a46b79b19a781bca2e821", 12:08:16 INFO - "slavebuilddir": "test" 12:08:16 INFO - } 12:08:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:08:16 INFO - Running post-action listener: _resource_record_post_action 12:08:16 INFO - [mozharness: 2016-08-12 19:08:16.805524Z] Finished read-buildbot-config step (success) 12:08:16 INFO - [mozharness: 2016-08-12 19:08:16.805665Z] Running download-and-extract step. 12:08:16 INFO - Running pre-action listener: _resource_record_pre_action 12:08:16 INFO - Running main action method: download_and_extract 12:08:16 INFO - mkdir: /builds/slave/test/build/tests 12:08:16 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')]} 12:08:16 INFO - trying https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:08:16 INFO - Downloading https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:18 INFO - Downloaded 1199 bytes. 12:08:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:08:18 INFO - Using the following test package requirements: 12:08:18 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:08:18 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:08:18 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:08:18 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'jsshell-mac.zip'], 12:08:18 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:08:18 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:08:18 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:08:18 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:08:18 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:08:18 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:08:18 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:08:18 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:08:18 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')]} 12:08:18 INFO - trying https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:08:18 INFO - Downloading https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:19 INFO - Downloaded 16328362 bytes. 12:08:19 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'] 12:08:19 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 12:08:22 INFO - Return code: 0 12:08:22 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 12:08: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')]} 12:08:22 INFO - trying https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:08:22 INFO - Downloading https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:23 INFO - Downloaded 16328362 bytes. 12:08:23 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'] 12:08:23 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 12:08:26 INFO - Return code: 0 12:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:26 INFO - trying https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:08:26 INFO - Downloading https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:27 INFO - Downloaded 14413719 bytes. 12:08:27 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'] 12:08:27 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 12:08:28 INFO - Return code: 0 12:08: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')]} 12:08:28 INFO - trying https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:08:28 INFO - Downloading https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/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 12:08:30 INFO - Downloaded 110896218 bytes. 12:08:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:08:30 INFO - mkdir: /builds/slave/test/properties 12:08:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:08:30 INFO - Writing to file /builds/slave/test/properties/build_url 12:08:30 INFO - Contents: 12:08:30 INFO - build_url:https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:08:31 INFO - Running post-action listener: _resource_record_post_action 12:08:31 INFO - Running post-action listener: set_extra_try_arguments 12:08:31 INFO - [mozharness: 2016-08-12 19:08:31.563760Z] Finished download-and-extract step (success) 12:08:31 INFO - [mozharness: 2016-08-12 19:08:31.563882Z] Running populate-webroot step. 12:08:31 INFO - Running pre-action listener: _resource_record_pre_action 12:08:31 INFO - Running main action method: populate_webroot 12:08:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:08:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:08:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:08:31 INFO - '--tppagecycles', 12:08:31 INFO - '7'], 12:08:31 INFO - 'tests': ['tsvgm']}}, 12:08:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:08:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:08:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:08:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:08:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:08:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['damp', 'tps']}, 12:08:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'tests': ['damp', 'tps']}, 12:08:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['dromaeo_dom']}, 12:08:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:08:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['basic_compositor_video']}, 12:08:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:08:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['a11yr', 12:08:31 INFO - 'ts_paint', 12:08:31 INFO - 'tpaint', 12:08:31 INFO - 'sessionrestore', 12:08:31 INFO - 'sessionrestore_no_auto_restore', 12:08:31 INFO - 'tabpaint']}, 12:08:31 INFO - 'other-e10s': {'tests': ['a11yr', 12:08:31 INFO - 'ts_paint', 12:08:31 INFO - 'tpaint', 12:08:31 INFO - 'sessionrestore', 12:08:31 INFO - 'sessionrestore_no_auto_restore', 12:08:31 INFO - 'tabpaint']}, 12:08:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:08:31 INFO - 'ts_paint', 12:08:31 INFO - 'tpaint', 12:08:31 INFO - 'sessionrestore', 12:08:31 INFO - 'sessionrestore_no_auto_restore', 12:08:31 INFO - 'tabpaint']}, 12:08:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:08:31 INFO - 'ts_paint', 12:08:31 INFO - 'tpaint', 12:08:31 INFO - 'sessionrestore', 12:08:31 INFO - 'sessionrestore_no_auto_restore', 12:08:31 INFO - 'tabpaint']}, 12:08:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['a11yr', 12:08:31 INFO - 'ts_paint', 12:08:31 INFO - 'tpaint', 12:08:31 INFO - 'sessionrestore', 12:08:31 INFO - 'sessionrestore_no_auto_restore', 12:08:31 INFO - 'tabpaint']}, 12:08:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['a11yr', 12:08:31 INFO - 'ts_paint', 12:08:31 INFO - 'tpaint', 12:08:31 INFO - 'sessionrestore', 12:08:31 INFO - 'sessionrestore_no_auto_restore', 12:08:31 INFO - 'tabpaint']}, 12:08:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['tsvgx', 12:08:31 INFO - 'tsvgr_opacity', 12:08:31 INFO - 'tart', 12:08:31 INFO - 'tscrollx', 12:08:31 INFO - 'cart']}, 12:08:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:08:31 INFO - 'tsvgr_opacity', 12:08:31 INFO - 'tart', 12:08:31 INFO - 'tscrollx', 12:08:31 INFO - 'cart']}, 12:08:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'talos_options': ['--disable-e10s'], 12:08:31 INFO - 'tests': ['tp5o']}, 12:08:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'tests': ['tp5o']}, 12:08:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'talos_options': ['--disable-e10s', 12:08:31 INFO - '--xperf_path', 12:08:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:31 INFO - 'tests': ['tp5n']}, 12:08:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:08:31 INFO - 'talos_options': ['--xperf_path', 12:08:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:08:31 INFO - 'tests': ['tp5n']}}, 12:08:31 INFO - 'talos.zip': {'path': '', 12:08:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:08:31 INFO - Running post-action listener: _resource_record_post_action 12:08:31 INFO - [mozharness: 2016-08-12 19:08:31.582741Z] Finished populate-webroot step (success) 12:08:31 INFO - [mozharness: 2016-08-12 19:08:31.582855Z] Running create-virtualenv step. 12:08:31 INFO - Running pre-action listener: _resource_record_pre_action 12:08:31 INFO - Running main action method: create_virtualenv 12:08:31 INFO - Creating virtualenv /builds/slave/test/build/venv 12:08:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:08:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:08:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:08:31 INFO - Using real prefix '/tools/python27' 12:08:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:08:32 INFO - Installing distribute.............................................................................................................................................................................................done. 12:08:35 INFO - Installing pip.................done. 12:08:35 INFO - Return code: 0 12:08:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:35 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:35 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 12:08:35 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 12:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:35 INFO - 'HOME': '/Users/cltbld', 12:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:35 INFO - 'LOGNAME': 'cltbld', 12:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:35 INFO - 'MOZ_NO_REMOTE': '1', 12:08:35 INFO - 'NO_EM_RESTART': '1', 12:08:35 INFO - 'PAGER': '/bin/cat', 12:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:35 INFO - 'PWD': '/builds/slave/test', 12:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:35 INFO - 'SHELL': '/bin/bash', 12:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:35 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:35 INFO - 'USER': 'cltbld', 12:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:35 INFO - 'XPC_FLAGS': '0x0', 12:08:35 INFO - 'XPC_SERVICE_NAME': '0', 12:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:35 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 12:08:35 INFO - Cleaning up... 12:08:35 INFO - Return code: 0 12:08:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:35 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:35 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 12:08:35 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 12:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:35 INFO - 'HOME': '/Users/cltbld', 12:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:35 INFO - 'LOGNAME': 'cltbld', 12:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:35 INFO - 'MOZ_NO_REMOTE': '1', 12:08:35 INFO - 'NO_EM_RESTART': '1', 12:08:35 INFO - 'PAGER': '/bin/cat', 12:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:35 INFO - 'PWD': '/builds/slave/test', 12:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:35 INFO - 'SHELL': '/bin/bash', 12:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:35 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:35 INFO - 'USER': 'cltbld', 12:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:35 INFO - 'XPC_FLAGS': '0x0', 12:08:35 INFO - 'XPC_SERVICE_NAME': '0', 12:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:35 INFO - Downloading/unpacking psutil>=3.1.1 12:08:35 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 12:08:35 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 12:08:35 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 12:08:35 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 12:08:35 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 12:08:35 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 12:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:08:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:39 INFO - Installing collected packages: psutil 12:08:39 INFO - Running setup.py install for psutil 12:08:39 INFO - building 'psutil._psutil_osx' extension 12:08:39 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 12:08:39 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 12:08:39 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 12:08:40 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 12:08:40 INFO - building 'psutil._psutil_posix' extension 12:08:40 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 12:08:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:08:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:08:40 INFO - ^ 12:08:40 INFO - 1 warning generated. 12:08:40 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 12:08:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:08:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:08:40 INFO - Successfully installed psutil 12:08:40 INFO - Cleaning up... 12:08:40 INFO - Return code: 0 12:08:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:40 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:40 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 12:08:40 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 12:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:40 INFO - 'HOME': '/Users/cltbld', 12:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:40 INFO - 'LOGNAME': 'cltbld', 12:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:40 INFO - 'MOZ_NO_REMOTE': '1', 12:08:40 INFO - 'NO_EM_RESTART': '1', 12:08:40 INFO - 'PAGER': '/bin/cat', 12:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:40 INFO - 'PWD': '/builds/slave/test', 12:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:40 INFO - 'SHELL': '/bin/bash', 12:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:40 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:40 INFO - 'USER': 'cltbld', 12:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:40 INFO - 'XPC_FLAGS': '0x0', 12:08:40 INFO - 'XPC_SERVICE_NAME': '0', 12:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:08:40 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 12:08:40 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 12:08:40 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 12:08:40 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 12:08:40 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 12:08:40 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 12:08:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:08:43 INFO - Installing collected packages: mozsystemmonitor 12:08:43 INFO - Running setup.py install for mozsystemmonitor 12:08:43 INFO - Successfully installed mozsystemmonitor 12:08:43 INFO - Cleaning up... 12:08:43 INFO - Return code: 0 12:08:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:43 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:43 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 12:08:43 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 12:08:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:43 INFO - 'HOME': '/Users/cltbld', 12:08:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:43 INFO - 'LOGNAME': 'cltbld', 12:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:43 INFO - 'MOZ_NO_REMOTE': '1', 12:08:43 INFO - 'NO_EM_RESTART': '1', 12:08:43 INFO - 'PAGER': '/bin/cat', 12:08:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:43 INFO - 'PWD': '/builds/slave/test', 12:08:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:43 INFO - 'SHELL': '/bin/bash', 12:08:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:43 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:43 INFO - 'USER': 'cltbld', 12:08:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:43 INFO - 'XPC_FLAGS': '0x0', 12:08:43 INFO - 'XPC_SERVICE_NAME': '0', 12:08:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:43 INFO - Downloading/unpacking jsonschema==2.5.1 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:08:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:08:46 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 12:08:46 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 12:08:46 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 12:08:46 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 12:08:46 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 12:08:46 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 12:08:46 INFO - Downloading functools32-3.2.3-2.tar.gz 12:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:08:46 INFO - warning: no files found matching '*.txt' 12:08:46 INFO - no previously-included directories found matching 'build' 12:08:46 INFO - no previously-included directories found matching 'dist' 12:08:46 INFO - no previously-included directories found matching '.git*' 12:08:46 INFO - Installing collected packages: jsonschema, functools32 12:08:46 INFO - Running setup.py install for functools32 12:08:46 INFO - warning: no files found matching '*.txt' 12:08:46 INFO - no previously-included directories found matching 'build' 12:08:46 INFO - no previously-included directories found matching 'dist' 12:08:46 INFO - no previously-included directories found matching '.git*' 12:08:46 INFO - Successfully installed jsonschema functools32 12:08:46 INFO - Cleaning up... 12:08:46 INFO - Return code: 0 12:08:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:46 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:46 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 12:08:46 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 12:08:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:46 INFO - 'HOME': '/Users/cltbld', 12:08:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:46 INFO - 'LOGNAME': 'cltbld', 12:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:46 INFO - 'MOZ_NO_REMOTE': '1', 12:08:46 INFO - 'NO_EM_RESTART': '1', 12:08:46 INFO - 'PAGER': '/bin/cat', 12:08:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:46 INFO - 'PWD': '/builds/slave/test', 12:08:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:46 INFO - 'SHELL': '/bin/bash', 12:08:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:46 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:46 INFO - 'USER': 'cltbld', 12:08:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:46 INFO - 'XPC_FLAGS': '0x0', 12:08:46 INFO - 'XPC_SERVICE_NAME': '0', 12:08:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:08:47 INFO - Cleaning up... 12:08:47 INFO - Return code: 0 12:08:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:08: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')]} 12:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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')]} 12:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08: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', '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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:08: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 blobuploader==1.2.4 12:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:47 INFO - 'HOME': '/Users/cltbld', 12:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:47 INFO - 'LOGNAME': 'cltbld', 12:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:47 INFO - 'MOZ_NO_REMOTE': '1', 12:08:47 INFO - 'NO_EM_RESTART': '1', 12:08:47 INFO - 'PAGER': '/bin/cat', 12:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:47 INFO - 'PWD': '/builds/slave/test', 12:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:47 INFO - 'SHELL': '/bin/bash', 12:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:47 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:47 INFO - 'USER': 'cltbld', 12:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:47 INFO - 'XPC_FLAGS': '0x0', 12:08:47 INFO - 'XPC_SERVICE_NAME': '0', 12:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:47 INFO - Downloading/unpacking blobuploader==1.2.4 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08:49 INFO - Downloading blobuploader-1.2.4.tar.gz 12:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:08:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:08:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08: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 12:08:51 INFO - Downloading docopt-0.6.1.tar.gz 12:08:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:08:51 INFO - Installing collected packages: blobuploader, requests, docopt 12:08:51 INFO - Running setup.py install for blobuploader 12:08:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:08:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:08:51 INFO - Running setup.py install for requests 12:08:51 INFO - Running setup.py install for docopt 12:08:52 INFO - Successfully installed blobuploader requests docopt 12:08:52 INFO - Cleaning up... 12:08:52 INFO - Return code: 0 12:08:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:52 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:52 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 12:08:52 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 12:08:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:52 INFO - 'HOME': '/Users/cltbld', 12:08:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:52 INFO - 'LOGNAME': 'cltbld', 12:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:52 INFO - 'MOZ_NO_REMOTE': '1', 12:08:52 INFO - 'NO_EM_RESTART': '1', 12:08:52 INFO - 'PAGER': '/bin/cat', 12:08:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:52 INFO - 'PWD': '/builds/slave/test', 12:08:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:52 INFO - 'SHELL': '/bin/bash', 12:08:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:52 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:52 INFO - 'USER': 'cltbld', 12:08:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:52 INFO - 'XPC_FLAGS': '0x0', 12:08:52 INFO - 'XPC_SERVICE_NAME': '0', 12:08:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:52 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-2GrtAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:52 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-GxjTlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:52 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-9Y7utO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:52 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-c4DXLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:52 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-uYNSNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:52 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-LhpROU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-v3xtVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-vhxpUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Qxo3ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-_zR87f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-FUceuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-NqQEq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-KzB64v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-SgvXAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-l3AjvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:53 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-tMsFGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:54 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-_6LKBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:08:54 INFO - Running setup.py install for manifestparser 12:08:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Running setup.py install for mozcrash 12:08:54 INFO - Running setup.py install for mozdebug 12:08:54 INFO - Running setup.py install for mozdevice 12:08:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:08:54 INFO - Running setup.py install for mozfile 12:08:54 INFO - Running setup.py install for mozhttpd 12:08:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for mozinfo 12:08:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for mozInstall 12:08:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for mozleak 12:08:55 INFO - Running setup.py install for mozlog 12:08:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for moznetwork 12:08:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:08:55 INFO - Running setup.py install for mozprocess 12:08:55 INFO - Running setup.py install for mozprofile 12:08:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Running setup.py install for mozrunner 12:08:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Running setup.py install for mozscreenshot 12:08:56 INFO - Running setup.py install for moztest 12:08:56 INFO - Running setup.py install for mozversion 12:08:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:08:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:08:56 INFO - Cleaning up... 12:08:56 INFO - Return code: 0 12:08:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:08:56 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:08:56 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 12:08:56 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 12:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:56 INFO - 'HOME': '/Users/cltbld', 12:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:56 INFO - 'LOGNAME': 'cltbld', 12:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:56 INFO - 'MOZ_NO_REMOTE': '1', 12:08:56 INFO - 'NO_EM_RESTART': '1', 12:08:56 INFO - 'PAGER': '/bin/cat', 12:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:56 INFO - 'PWD': '/builds/slave/test', 12:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:56 INFO - 'SHELL': '/bin/bash', 12:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:08:56 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:56 INFO - 'USER': 'cltbld', 12:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:56 INFO - 'XPC_FLAGS': '0x0', 12:08:56 INFO - 'XPC_SERVICE_NAME': '0', 12:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:08:56 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-fvpMVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-FJrJem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-vTPNO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-wenKxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-F7rfFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-6wnldT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-E630yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-TJ535Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-y0WlaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:08:57 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)) 12:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:08:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-F8NbB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:08:58 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)) 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:08:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-jocpzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:08:58 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)) 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:08:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-OoyUDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:08:58 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)) 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:08:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-GjwhBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:08:58 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)) 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:08:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-bXjp09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:08:58 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)) 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-NJCHyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:08:58 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)) 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:08:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-coueSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:08:58 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)) 12:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:08:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nqZkfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:08:58 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)) 12:08:58 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)) 12:08:58 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)) 12:08:58 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)) 12:08:58 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)) 12:08:58 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)) 12:08:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:08:58 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 12:08:58 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 12:08:58 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 12:08:58 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 12:08:58 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 12:08:58 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 12:09:01 INFO - Downloading blessings-1.6.tar.gz 12:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:01 INFO - Installing collected packages: blessings 12:09:01 INFO - Running setup.py install for blessings 12:09:01 INFO - Successfully installed blessings 12:09:01 INFO - Cleaning up... 12:09:01 INFO - Return code: 0 12:09:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:01 INFO - Reading from file tmpfile_stdout 12:09:01 INFO - Current package versions: 12:09:01 INFO - blessings == 1.6 12:09:01 INFO - blobuploader == 1.2.4 12:09:01 INFO - docopt == 0.6.1 12:09:01 INFO - functools32 == 3.2.3-2 12:09:01 INFO - jsonschema == 2.5.1 12:09:01 INFO - manifestparser == 1.1 12:09:01 INFO - mozInstall == 1.12 12:09:01 INFO - mozcrash == 0.17 12:09:01 INFO - mozdebug == 0.1 12:09:01 INFO - mozdevice == 0.48 12:09:01 INFO - mozfile == 1.2 12:09:01 INFO - mozhttpd == 0.7 12:09:01 INFO - mozinfo == 0.9 12:09:01 INFO - mozleak == 0.1 12:09:01 INFO - mozlog == 3.3 12:09:01 INFO - moznetwork == 0.27 12:09:01 INFO - mozprocess == 0.23 12:09:01 INFO - mozprofile == 0.28 12:09:01 INFO - mozrunner == 6.12 12:09:01 INFO - mozscreenshot == 0.1 12:09:01 INFO - mozsystemmonitor == 0.3 12:09:01 INFO - moztest == 0.7 12:09:01 INFO - mozversion == 1.4 12:09:01 INFO - psutil == 3.1.1 12:09:01 INFO - requests == 1.2.3 12:09:01 INFO - wsgiref == 0.1.2 12:09:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:01 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')]} 12:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:01 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')]} 12:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:01 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:09:01 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 12:09:01 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 12:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:01 INFO - 'HOME': '/Users/cltbld', 12:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:01 INFO - 'LOGNAME': 'cltbld', 12:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:01 INFO - 'MOZ_NO_REMOTE': '1', 12:09:01 INFO - 'NO_EM_RESTART': '1', 12:09:01 INFO - 'PAGER': '/bin/cat', 12:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:01 INFO - 'PWD': '/builds/slave/test', 12:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:01 INFO - 'SHELL': '/bin/bash', 12:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:09:01 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:01 INFO - 'USER': 'cltbld', 12:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:01 INFO - 'XPC_FLAGS': '0x0', 12:09:01 INFO - 'XPC_SERVICE_NAME': '0', 12:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 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)) 12:09:02 INFO - Cleaning up... 12:09:02 INFO - Return code: 0 12:09:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:02 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')]} 12:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:02 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')]} 12:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:02 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 0x1059d0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a65618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2e958fd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, '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.udfnaAX7qD/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.8vAsk88nqh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 12:09:02 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 12:09:02 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 12:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:02 INFO - 'HOME': '/Users/cltbld', 12:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:02 INFO - 'LOGNAME': 'cltbld', 12:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:02 INFO - 'MOZ_NO_REMOTE': '1', 12:09:02 INFO - 'NO_EM_RESTART': '1', 12:09:02 INFO - 'PAGER': '/bin/cat', 12:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:02 INFO - 'PWD': '/builds/slave/test', 12:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:02 INFO - 'SHELL': '/bin/bash', 12:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:09:02 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:02 INFO - 'USER': 'cltbld', 12:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:02 INFO - 'XPC_FLAGS': '0x0', 12:09:02 INFO - 'XPC_SERVICE_NAME': '0', 12:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:02 INFO - Cleaning up... 12:09:02 INFO - Return code: 0 12:09:02 INFO - Running post-action listener: _resource_record_post_action 12:09:02 INFO - Running post-action listener: _start_resource_monitoring 12:09:02 INFO - Starting resource monitoring. 12:09:02 INFO - [mozharness: 2016-08-12 19:09:02.483174Z] Finished create-virtualenv step (success) 12:09:02 INFO - [mozharness: 2016-08-12 19:09:02.484119Z] Running install step. 12:09:02 INFO - Running pre-action listener: _resource_record_pre_action 12:09:02 INFO - Running main action method: install 12:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:02 INFO - Reading from file tmpfile_stdout 12:09:02 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:02 INFO - Reading from file tmpfile_stdout 12:09:02 INFO - Output received: 12:09:02 INFO - Usage: mozinstall [options] installer 12:09:02 INFO - Options: 12:09:02 INFO - -h, --help show this help message and exit 12:09:02 INFO - -d DEST, --destination=DEST 12:09:02 INFO - Directory to install application into. [default: 12:09:02 INFO - "/builds/slave/test"] 12:09:02 INFO - --app=APP Application being installed. [default: firefox] 12:09:02 INFO - mkdir: /builds/slave/test/build/application 12:09:02 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'] 12:09:02 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 12:09:36 INFO - Reading from file tmpfile_stdout 12:09:36 INFO - Output received: 12:09:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:09:36 INFO - Running post-action listener: _resource_record_post_action 12:09:36 INFO - [mozharness: 2016-08-12 19:09:36.821774Z] Finished install step (success) 12:09:36 INFO - [mozharness: 2016-08-12 19:09:36.821959Z] Running run-tests step. 12:09:36 INFO - Running pre-action listener: _resource_record_pre_action 12:09:36 INFO - Running main action method: run_tests 12:09:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:09:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:09:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:09:36 INFO - 2016-08-12 12:09:36.836 screenresolution[1634:7509] starting screenresolution argv=screenresolution get 12:09:36 INFO - 2016-08-12 12:09:36.839 screenresolution[1634:7509] Display 0: 1600x1200x32@60 12:09:36 INFO - 2016-08-12 12:09:36.845 screenresolution[1635:7511] starting screenresolution argv=screenresolution list 12:09:36 INFO - Available Modes on Display 0 12:09:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:09:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:09:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:09:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:09:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:09:37 INFO - Intel Iris: 12:09:37 INFO - Chipset Model: Intel Iris 12:09:37 INFO - Type: GPU 12:09:37 INFO - Bus: Built-In 12:09:37 INFO - VRAM (Dynamic, Max): 1536 MB 12:09:37 INFO - Vendor: Intel (0x8086) 12:09:37 INFO - Device ID: 0x0a2e 12:09:37 INFO - Revision ID: 0x0009 12:09:37 INFO - Displays: 12:09:37 INFO - Display: 12:09:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:09:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:09:37 INFO - Display Serial Number: 1600x1200 60 12:09:37 INFO - Main Display: Yes 12:09:37 INFO - Mirror: Off 12:09:37 INFO - Online: Yes 12:09:37 INFO - Rotation: Supported 12:09:37 INFO - Return code: 0 12:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:09:37 INFO - Python 2.7.3 12:09:37 INFO - Return code: 0 12:09:37 INFO - grabbing minidump binary from tooltool 12:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:37 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 0x105934d30>, '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 0x105699db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10592cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:09:37 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 12:09:37 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 12:09:37 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 12:09:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:09:37 INFO - Return code: 0 12:09:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:09:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:09:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:09:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:09:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 12:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0178 --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 12:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.udfnaAX7qD/Render', 12:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:37 INFO - 'HOME': '/Users/cltbld', 12:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:37 INFO - 'LOGNAME': 'cltbld', 12:09:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:09:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:37 INFO - 'MOZ_NO_REMOTE': '1', 12:09:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:09:37 INFO - 'NO_EM_RESTART': '1', 12:09:37 INFO - 'PAGER': '/bin/cat', 12:09:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:37 INFO - 'PWD': '/builds/slave/test', 12:09:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:37 INFO - 'SHELL': '/bin/bash', 12:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8vAsk88nqh/Listeners', 12:09:37 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 12:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:37 INFO - 'USER': 'cltbld', 12:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:37 INFO - 'XPC_FLAGS': '0x0', 12:09:37 INFO - 'XPC_SERVICE_NAME': '0', 12:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 12:09:37 INFO - mozversion application_buildid: 20160812085316 12:09:37 INFO - mozversion application_changeset: 8725f14625e0c87776f9acf66e6712ceca301000 12:09:37 INFO - mozversion application_display_name: Nightly 12:09:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:09:37 INFO - mozversion application_name: Firefox 12:09:37 INFO - mozversion application_remotingname: firefox 12:09:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:09:37 INFO - mozversion application_vendor: Mozilla 12:09:37 INFO - mozversion application_version: 51.0a1 12:09:37 INFO - mozversion platform_buildid: 20160812085316 12:09:37 INFO - mozversion platform_changeset: 8725f14625e0c87776f9acf66e6712ceca301000 12:09:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:09:37 INFO - mozversion platform_version: 51.0a1 12:09:37 INFO - using testdate: 1471028977 12:09:37 INFO - actual date: 1471028977 12:09:37 INFO - starting webserver on 'localhost:49263' 12:09:37 INFO - SUITE-START | Running 2 tests 12:09:37 INFO - TEST-START | dromaeo_css 12:09:37 INFO - operating with platform_type : mac_ 12:09:37 INFO - Initialising browser for dromaeo_css test... 12:09:37 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpn5Rzhj/profile http://localhost:49263/getInfo.html) 12:09:39 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 12:09:39 INFO - PROCESS | 1645 | colorDepth:24 12:09:39 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 12:09:39 INFO - PROCESS | 1645 | __metrics 12:09:40 INFO - PROCESS | 1645 | 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" 12:09:40 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:09:40 INFO - TEST-INFO | 1645: exit 0 12:09:40 INFO - Browser initialized. 12:09:40 INFO - Running cycle 1/1 for dromaeo_css test... 12:09:40 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpn5Rzhj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:52 INFO - PROCESS | 1647 | RSS: Main: 243355648 12:09:52 INFO - PROCESS | 1647 | 12:10:44 INFO - PROCESS | 1647 | 1471029044444 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] 12:12:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 12:12:57 INFO - PROCESS | 1647 | RSS: Main: 388497408 12:12:57 INFO - PROCESS | 1647 | 12:16:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 12:16:01 INFO - PROCESS | 1647 | RSS: Main: 397266944 12:16:01 INFO - PROCESS | 1647 | 12:19:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 12:19:04 INFO - PROCESS | 1647 | RSS: Main: 350785536 12:19:04 INFO - PROCESS | 1647 | 12:22:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 12:22:07 INFO - PROCESS | 1647 | RSS: Main: 361762816 12:22:07 INFO - PROCESS | 1647 | 12:25:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 12:25:10 INFO - PROCESS | 1647 | RSS: Main: 339460096 12:25:10 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 12:28:14 INFO - PROCESS | 1647 | RSS: Main: 414810112 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | __start_tp_report 12:28:14 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 12:28:14 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 12:28:14 INFO - PROCESS | 1647 | |i|pagename|runs| 12:28:14 INFO - PROCESS | 1647 | |0;dojo.html;3362.630110687824;3388.7289016878653;3394.1853955050788;3421.3841508528453;3441.518187453757;5548.278723765905;5571.5264202542785;5636.774529018845;5670.432956704322;5683.090705487123;4787.497312782151;5657.632253903494;5664;5720.256366672327;5773.855653608662;4451.910961780763;5060.873478163057;5105.157648987931;5159.896802063957;5174.689518628876;5256.894862102756;5270.894582108355;5316.149416093426;5357.249985002083;5376.865578360533;5119.436861945183;5168.121419358718;5202.76587553561;5242.8689282767855;5248.343957005374;5189.792408303663;5192.247124166975;5211.478852114796;5221;5224.817131400421;5116.897662046738;5117.462666420047;5123.846284611468;5168.715720635363;5246.422893481714;5553.055980483327;5697.344805347355;5702.059160238556;5703.65778053314;5728.427157284238;6160.983437732769;6180.3201647818705;6181.96909015452;6193.1329613854095;6193.969030154865;5094.872628184325;6045.788397406113;6146.385361463863;6270.9372906271265;6277.246730392337;5808;5829.912551311733;5835.095560188178;5839.562032847511;5864.970675146597;6371.96814015927;6380.202474690664;6383.425491705769;6390.584612000298;6396.328385519547;5914.260717410324;5981.102834574848;5994.730237139252;6018.21763170785;6084.847878803065;6185.443310102112;6209.410106039919;6212.378762123842;6226.595271307441;6233.781817636452;5098.847034588968;6020.397960203944;6159.784407545714;6160.229971253593;6166.321704612489;5998.610090344114;6079.300880398722;6234.9688251559355;6237.500999919996;6250.843728906813;4926.75366231687;4930.975345123252;4942.456329803718;4942.975285123552;4945.530174633404;4357.825686972549;4441.267190913528;4460.130274596422;4480.4623445186135;4490;5596.356419011784;5632;5644.858878527987;5669.40471250521;5725.456081672234;6197.721102550306;6201.472874805602;6228.314885362606;6232.937670623236;6247.250329960434;5654.632448890891;5657.688827114466;5661.631993920464;5668.121441176624;5693.060644993529;4652.883677908079;5260.815871444481;5340.332458442695;5375.086235340002;5385.811496597678;6292.4966002719675;6316.084167795736;6321.1782468278825;6335.841603959938;6349.111124442582;6266.874662506633;6270;6273.843153921006;6275.341089185752;6284.08880712285;6214.937850621436;6242.094896240111;6266.342034086538;6323.20959880015;6340.365963403808;6124.2588809726185;6159.076138579249;6164.013757798731;6171.9691401542705;6178.814635560941;6222.564420490522;6239.344868788893;6244.281907580505;6249.1563638908165;6262.906056409066;5861.648301101947;5895.587308888336;5964.314103877936;6040.6979651018155;6047.0627052806885;6034.426729460694;6045.1234570987845;6073.240844894388;6120.051392034242;6127.632342059491;5897.67562784051;5909.468147866713;5961.612495187799;5978.432048955342;5987;4568.451785785727;5806.535477161901;6060.545459090497;6068.4538391544975;6094.8171554853425;4845.49122342149;5866.178734977192;5866.208061911588;5908.084246941731;5922.111683247547 12:28:14 INFO - PROCESS | 1647 | |1;ext.html;463.09464995932944;495.5218214423081;522.034236662175;535.0102310725158;547.3951283831369;26442.86778566107;32341.51487727683;32472.025839224832;32503;32523.512147317808;32774.50838237428;35029.64970350296;41675.58324416755;42332;44986.325205121866;9600.615975360977;9759.90240097597;9780.119789218968;9781.168600668952;9826.459544725038;9640.276979226552;9725.51372431379;9729.318947673666;9738.220942324633;9767.218622510218;8644.135586441369;8833.690820821272;8834.425762325429;8947.418417802855;8998.865017024717;8813.294936405104;11506.942465287662;11702.882971170264;11721.413929303586;12006.21959572625;11733.53065877364;11807.114466415052;11809.704757381049;11816.881831181665;11821.704457388549;40113.59886401157;40468;40476.79761601202;40487.7975610123;40627.79686101551;3473.9826300868335;3475.8609655613745;3487.0410637074756;3521.9823900880588;3636.2909232699635;32167.19582010445;32685.836570816995;33599.83200083984;33801.66198338034;35059.29881402383;3205.3108581655056;3221.1624977506017;3223.8549265282886;3233.676632336771;3344.2809795894;471103.6444817754;477184;480746;481464;481843;65518.01722974159;66795.65634749208;66859;67057;67166.328336716;30234.84882575573;35292.823535882155;36049;37498;68794;16967.406140785013;17069.573260668334;17119.400820971456;17134.314627414762;17144.571385715208;12930.418131184118;13004.544840930523;13023.348832558524;13032.47870085186;13038.956883449302;20533.794662053187;20808.58382832365;20829.1668333268;20886.89556552238;20914.163433462796;7262.273772622695;7337.899315102777;7340.889886651705;8908.955455222813;8969.461832290084;721.3291638775988;761.6991288441145;1015.4973288222377;1096.658633816807;1119.7256672115384;27857.30356741057;30088.699113008588;30706.846465767976;30732;30924.381512370077;1044.7127040064042;1084.6854412220525;1089.9507732086886;1136.710138914588;1148.2192109365712;11639.14077338377;14913.179775112472;15737.527874163794;15974.186352228891;15996.8404265425;507418;680483.195168042;680512;682674;685268;57760.422395776346;58354.7082264586;58416;58638;58660.41339586635;1235.7775600392015;1367.8221831161886;1384.702289007868;1393.4078016842802;1419.0492370112256;29433.85283073571;29681;31720.572574233753;32390.35219295554;32488.837555812068;50450.74774626103;56001;57584.13623795562;57832.71083644555;60661.69669151626;617508;630807;630879;630963;631077;44179.11641767211;44276.557234427244;44589;44617.55382446134;45845.249285043006;47243.52756472391;47554.286685700434;47746;48085.75957120192;48116.55650330496;39606.80196598999;39752.80741577757;40210.79894600508;40259.49567837583;42303;37525.061873453385;39037.219255615295;39625.801870991614;40933;41124.62568301918;38864.02839929024;39136;39442.40836387399;39549.80225098856;39564.80217598894;32386.35227295488;33241.50137747984;34055;34902.77840275578;35059.64940350564;22089.889550552787;25821.967121314938;25984.35039124037;26220.737792622593;26517 12:28:14 INFO - PROCESS | 1647 | |2;jquery.html;307.66771886362733;374.33929115111835;376.08798663241635;377.1325950314276;394.2332163941136;15349.539513814589;16914.91542542287;16932.661346773057;20792;20811.167553297888;19771.901140494276;20529.486762830937;20732.89633551834;21072.894635526838;21263.36209913701;4958.851234462972;5292.153255479124;5546.140348246028;5561.5828812839;5567.248421463111;5993.910091348634;6130.846728831771;6131.264248290189;6134.355892631276;6147.784827531037;5833.766649334021;6065.575409721321;6069.332373438918;6109.847253818646;6117.755289788403;6143.508519318444;6148.016317389241;6175.66033868137;6196.225471816023;6207.720652570622;5736.655800651933;5972.850678733023;6045.697715114226;6051.425114614105;6052.394760523956;12987.350632468433;13221.405036773273;13338.466461341535;25318.73406329676;25320.493590128095;2320.8259380546356;2350.6589268128455;2496.4383013821744;2970.435617232719;2981.418623368444;10016.148627366683;10100.242481813893;10463.84304235437;10481.528331225114;10494.212934029902;1766.0904634113442;1796.0570700382273;1811.7372980917826;1849.084703071988;1863.4037108125406;88983;120077.39961300224;123210.38394807969;124311.13533297008;127151.36424317912;11089.500972456259;11452.25560338592;11717.355545445082;11725.882741172649;11877.940610296893;14623.78064329036;14639;14671.77992330116;14678.972471926863;14686;7783.260590243918;8151.673933042612;8159.592020398956;8268.173182681683;8285.461445006054;7885.921140788519;7891.250331218525;7947.4039447041705;8058.11360750317;8074.717384891501;4478;4572.222722137245;4573.611243044312;4650.348951146842;4671.859844204611;1774.946751597454;1792.2024699009075;1845.5109396010068;1922.423273018089;1970.2513045043079;665.7503436211421;764.5603777827771;850.4132148817296;865.0916537635458;965.5510187762724;20746.896265518575;21414.03636836346;21531.246406375703;21646.99797594007;21792;795.7891158842912;880.7401816464159;924.0574613893925;924.7549399409162;1026.2816028779885;10472.947635261775;11294.096472282197;11366.261193022427;11421.657350279505;11431.885681143249;573432;583492;584984;594825;595674;107101;122004;123038.38480807717;124503;132754;923.8521836506127;948.9098535639104;965.8937516873139;1023.7287118913493;1023.8054769593903;18976.539502245083;20022.62274333136;20051.393358780297;20165.661824474475;20207.98960051962;37556.2488750218;47526.28710569414;58490.4150958502;58781.70609146927;58862.41137588741;64077;79185.60407197927;82130;85621;86184;7760.844783104193;8050.83898322042;8080.232377924088;8484;8494.830103397773;9413.388129771543;11111.999920007238;12844.229346239255;13118.409671564994;13570.93214533921;9632.855507167538;11174.776504470028;11437;11709.004734597655;11739.060875129799;11278.492467838803;11344.376059316486;11574.131940104695;11583.594574189863;11693.239939403911;11707;11979.34113623759;12002.1598488105;12010.039196864405;12046.698832529257;8025.518468891885;8292.33661307106;8297.25324720778;8344.249017588203;8439.915600843913;11070.5571777128;11387.772244554897;11502.367369794742;11667.241629294409;11683 12:28:14 INFO - PROCESS | 1647 | |3;mootools.html;1474.542891703572;1520.8783297336213;1707.5816424975883;1851.25949620152;1919.2227148005063;7621.428392870536;9001.594928228229;9004.639814407432;9007.819843603123;9011.684591039315;7828.256315650005;8092.797680058002;8472.872906906401;8678.262347700438;8789.692360767374;2007.3576455534235;2319.9072037118535;2357.9410514737096;2361.020176626698;2362.244081893786;2331.067572970808;2349.788519033287;2382.642603609455;2383.7377888432256;2386.6658667786523;2345.94135146621;2360.8111351091957;2374.1097088591778;2377.512609914972;2384.7972922301537;2327.5461285049423;2341.0869760793303;2350.27141586108;2350.9882450587806;2367.8816059197143;2344.6248600224053;2351.6825228594093;2355.599548076831;2355.8350915435926;2358.13927916311;8881.911180888173;8885.0670679579;8973.371863969525;8978.102189781035;9013.324000699908;5150.9742451287875;5188.143956247215;5293.57651387888;5321.042212401766;5336.91994620081;3407.875401117625;3497.055794935379;3589.856405743767;3599.6760291573623;3616.4936908832783;1038.7403149212696;1078.541619811577;1079.794838980599;1096.1942971915635;1099.5162128663362;11676.883231167665;11821.527138914433;11827.467763950557;11840.822387664191;11849.94075029628;4482.955170448254;4646.651501137429;4739.644526660514;4750.263709125022;4758.357621721093;9900.900990990142;9908.851367229498;9914.157296629723;9919.355241909252;9923.65267215644;5331.786728530893;5335.439778823167;5336.306280183629;5346.1713434417725;5352.8661783454945;4370.38814565961;4532.9320060199125;4700.106979673851;4823.107725070873;4881.755912204447;3827.8468861245797;3866.149447121629;3944.1322908959983;3975.6421922027157;3977.9204415911518;1254.8996080313552;1255.334672623511;1256.9245845249313;1269.397036407699;1272.8090786381931;828.3787159630277;876.2464280718577;911.9179273865384;915.1671978499667;917.9173874351206;4905.705657660552;5978.970105149447;6043.002904520793;6186.10301506279;6250.312465628777;959.6449313753956;960.7357976556364;968.5447839515416;974.4153507895206;1010.545254635417;3451.930961380758;3739.139997800521;3846.769193848378;3909.667678247381;4076.30702780528;10579.57681692739;10875.83686244707;10979.725506862393;10986.505607247693;10992.670219893416;10936.289141205796;10947.945260273647;10949.014588687056;10955.616553420592;10959.178061645409;7722.961385193038;7959.681612735541;8162.10216876155;8204;8363.372747043995;5804.1926873115535;5890.1740460457395;5991.191189189403;6043.214382130295;6080.179175811209;10052.64815731446;10229.107615090934;10246.38521688701;10258.487075646337;10261.384316941007;9810.509474526389;10145.239107067147;10157.644482443078;10355.94822025885;10357.792844143225;7061.152661680631;7268.745593904188;7486.213947535648;7621.31408173267;7695.538267703956;8414.579271036546;8602.483850968962;8608.569571521273;8612.138786121337;8615.569221539023;7362.558246505227;7469.551826890404;7587.430942679432;7656.234376562299;7734.651940662571;6203.534734894811;7188.28117188277;7339.045924029842;7365.8158546036775;7555.9244407555225;7012.789616311519;7036.331548502884;7115.324044215791;7128.108986376703;7135.678894449658;4868.269759536098;4953.405591329064;4973.204287314013;5004.449510553909;5083.059633967728;4922.2616607507725;4969.378827646544;5007.7245751484015;5042.974785126051;5234.39804422481 12:28:14 INFO - PROCESS | 1647 | |4;prototype.html;288.39437181917975;295.69543370328563;306.5753930805834;313.4592827372781;314.69317415519873;4403.361512580674;4586.724796512203;5193.63644544882;5243.134882744343;5257.290265814114;4221.290377397151;4840.273958906166;4910.410750709911;4970.179920313144;5156.819511317105;3986.5016872890887;4003.219372222418;4014.4781178446906;4016.1967606478674;4018.457508236387;3178.6980236877575;3428.6914177724;3939.7439166454233;4045.4538637283886;4046.5751096134923;3466.757326987112;3823.8146174685935;3912.2957867583964;3955.940660890089;3985.8206380712936;3910.843566257346;3922.980385098056;3945.8816235512695;3951.525816901961;3955.64399204073;2908.243856597279;3332.8833490827706;3455.8617655293756;3468.722502199818;3483.651634836521;4987.576056035241;5002.849914502571;5010.248462730583;5012.198048312289;5023.8492845214705;3735.775853448775;4292.716912588028;4536.54634536547;4539;4560.361549383089;5097.3118628984985;5364.570834333244;5371.435999220064;5374.226505253257;5410.91883621746;3224.758143139274;3313.7349012078976;3767.4913886625504;3834.0798208430056;3837.347650899326;4775.785089670973;5234.7644356004075;5633.478960680593;5686.09022556389;5722.685252311121;3867.003072618743;3873.65137137659;3885.980570097188;3889.183271513014;3889.844406223776;3174.4445221841593;3316.93366132676;3890.2414029264587;3938.212357528506;3939.5075615548058;4399.428074350314;4468.821247150143;4564.657650676146;4585.610223131004;4602.3786788783755;4818.807247710052;5471.398146203915;5472.124460086367;5479.863003424946;5487.835364939058;1244.925304481734;1309.214471317221;1335.7128217433299;1337.2043634037732;1353.6142199473102;645.8417687666552;727.6143643868758;809.8704207326799;823.4359463767339;830.5141492227115;3590.838412271454;4034.616711412411;4185.074865561875;4225.408442818008;4227.302495088276;3700.444933259979;3790.6497971257995;4239;4638.350630911607;4660.673752837337;3530.770499917497;3809.8095095245126;4184.748915065105;4211.7472951623;4226.978865105655;2056.568120694671;2113.547492946604;2388.3073908566666;2465.5315490056832;2478.7397323280798;4487.730736155841;4524.502304746476;4727.881802954885;4792.400949881265;4808.8797780055775;4359.825606975685;4378.365137055109;4423.778811059498;4433.8226470941445;4470.742275151028;4923.803047878045;5090.174952901176;5142.942822870807;5234.659747116425;5457.5633949283965;5408.053590621546;5526.198701188386;5647.3787883333625;5654.886902262014;5702.151048042472;4680.806394369414;5397.27136836522;5572.387037425961;5584.4136365681015;5634.084411300178;5161.845144645667;5828.193260089386;5839.175340152207;5849.607227807946;5861.9413805861395;3290.621578518501;3423.109991402303;3455.308938212418;3713.2759111973987;3873.934667966219;5363.892722145613;5745.787871669354;5777.133429985536;5794.01501744696;5801.6519008859605;4601.67788254819;5537.640053396516;5549.472800083986;5550.222968784454;5563;5087.491250875031;5114.590832733446;5354.143337066051;5568.916466252928;5601.971990140023;4548.56788605082;5122.538971492583;5181.2746215529105;5415.756290966837;5446.482584154499;5535.861603459945;5595.748191331481;5612.905483430692;5634.0422128239115;5645.915311270416;5285.753554739297;5397;5457.908418316273;5461.453854614507;5525.475079867406 12:28:14 INFO - PROCESS | 1647 | |5;yui.html;684.216572025031;691.7924622613211;711.9964400177998;733.2374330696076;864.8745931839885;14905.701885962275;16436.0960147192;16442.917785411086;17034.403795867147;18004.63990720188;16631.750523742154;17047.14764261785;17051.658966820687;17059.061751603687;17274.740878886827;687.6149356360454;846.3948276981956;873.9563021848882;875.7329014650529;875.8117004843957;710.7867639708093;712.7220384050198;856.751542052804;864.8140649760301;867.397158974513;908.2325435007408;910.5902343945252;912.3613470570594;913.9086091390841;916.8166366726681;721.1202333153544;905.9003509613938;907.2107266678032;907.37844576465;907.5825120444604;654.715198888485;676.1615596660106;676.4216594811414;676.8477092654163;677.3226773226774;15349.846501534954;15424.768628470581;15433.151176685278;15436.228188590525;15438.228088595526;14054.43782248719;14300.713985720227;14348.856511434857;14473.276336183255;14578.271086445635;12984.870151298555;12994.545190918367;13335.399907004208;13357.398917048757;13649.658758531017;4585.358049873021;4682.204025315705;4686.625069994427;4708.976455117737;4822.276658501218;116007.41996289964;117262;119705;120114;120903.79096209013;11188.496517656726;11236.887631123584;11281.661550153509;11530.423478826026;11763.882361176278;21585.56828863414;23045.769542304697;23059.07763689467;23059.769402306098;23078.769212307998;8271.503709777433;8400.789980250544;8528.701495447629;8651.307895368482;8701.738947831449;4374.934375984363;4416.712913660666;4492.842750503717;4512.593866551961;4642.53574642533;3657.487951686766;3805.6955443564448;3809.790461524643;3810.5617853946596;3811.7331786774657;667.1527160506226;682.5461068389565;695.5617960684737;699.0458024796117;702.5187746393736;536.4528181255098;596.7523477756682;611.843979785152;670.6512613440983;675.9695813688396;8330.291925186306;8993.955030224806;9025.437858660904;9203.907960920438;9393.483358415353;479.52287473962934;621.3506885304868;630.8518496336715;677.2110491277705;694.4652617484509;4314.913701725948;4627.537246275413;4672.808433849346;4691.648126390534;4733.26634371666;99247;100526;147390;153548;157924.21037894738;116443;135614;136588;137361;149336.3329583802;72183.27816721871;74685.6265718668;122146;123478.38260808638;125187;8352.791180220544;9410.43537387759;9672;9933.453660048766;9936.652217172365;45199.32201017053;47563.28655070107;54500.63748406321;59465;60997.39002609917;73470.63264683857;78993.60503197447;87532;96855;97212;12653.683657908627;13082.149660272053;13241.073124881166;13410.061295709207;14097;15101.093934363973;15167.772483412979;15581.610459738597;15964.92017539905;15968.962017468828;13529.323533823466;14032.508862189774;14250.928745356206;14492.275386230856;14684;13915.58253252404;14100.929495352457;14154.858451415766;14174.43302267939;14446.566603001505;13690.315484225948;13840.100393474391;13862.792058119338;13995.370208340446;14163;10979.505922233679;11335.489902954552;11734.237274577124;11835.11236657237;11947.223430476992;11274.549018039515;11428.94285528567;11504.94247528757;11563.537458501902;11580.36308003068 12:28:14 INFO - PROCESS | 1647 | __end_tp_report 12:28:14 INFO - PROCESS | 1647 | __start_cc_report 12:28:14 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1409 12:28:14 INFO - PROCESS | 1647 | __end_cc_report 12:28:14 INFO - PROCESS | 1647 | __startTimestamp1471030094289__endTimestamp 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | ------- Summary: start ------- 12:28:14 INFO - PROCESS | 1647 | Number of tests: 6 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5683.20 Median:5866.21 stddev:650.73 (11.1%) stddev-sans-first:626.74 12:28:14 INFO - PROCESS | 1647 | Values: 3362.6 3388.7 3394.2 3421.4 3441.5 5548.3 5571.5 5636.8 5670.4 5683.1 4787.5 5657.6 5664.0 5720.3 5773.9 4451.9 5060.9 5105.2 5159.9 5174.7 5256.9 5270.9 5316.1 5357.2 5376.9 5119.4 5168.1 5202.8 5242.9 5248.3 5189.8 5192.2 5211.5 5221.0 5224.8 5116.9 5117.5 5123.8 5168.7 5246.4 5553.1 5697.3 5702.1 5703.7 5728.4 6161.0 6180.3 6182.0 6193.1 6194.0 5094.9 6045.8 6146.4 6270.9 6277.2 5808.0 5829.9 5835.1 5839.6 5865.0 6372.0 6380.2 6383.4 6390.6 6396.3 5914.3 5981.1 5994.7 6018.2 6084.8 6185.4 6209.4 6212.4 6226.6 6233.8 5098.8 6020.4 6159.8 6160.2 6166.3 5998.6 6079.3 6235.0 6237.5 6250.8 4926.8 4931.0 4942.5 4943.0 4945.5 4357.8 4441.3 4460.1 4480.5 4490.0 5596.4 5632.0 5644.9 5669.4 5725.5 6197.7 6201.5 6228.3 6232.9 6247.3 5654.6 5657.7 5661.6 5668.1 5693.1 4652.9 5260.8 5340.3 5375.1 5385.8 6292.5 6316.1 6321.2 6335.8 6349.1 6266.9 6270.0 6273.8 6275.3 6284.1 6214.9 6242.1 6266.3 6323.2 6340.4 6124.3 6159.1 6164.0 6172.0 6178.8 6222.6 6239.3 6244.3 6249.2 6262.9 5861.6 5895.6 5964.3 6040.7 6047.1 6034.4 6045.1 6073.2 6120.1 6127.6 5897.7 5909.5 5961.6 5978.4 5987.0 4568.5 5806.5 6060.5 6068.5 6094.8 4845.5 5866.2 5866.2 5908.1 5922.1 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:71932.71 Median:39086.61 stddev:158023.91 (404.3%) stddev-sans-first:158376.10 12:28:14 INFO - PROCESS | 1647 | Values: 463.1 495.5 522.0 535.0 547.4 26442.9 32341.5 32472.0 32503.0 32523.5 32774.5 35029.6 41675.6 42332.0 44986.3 9600.6 9759.9 9780.1 9781.2 9826.5 9640.3 9725.5 9729.3 9738.2 9767.2 8644.1 8833.7 8834.4 8947.4 8998.9 8813.3 11506.9 11702.9 11721.4 12006.2 11733.5 11807.1 11809.7 11816.9 11821.7 40113.6 40468.0 40476.8 40487.8 40627.8 3474.0 3475.9 3487.0 3522.0 3636.3 32167.2 32685.8 33599.8 33801.7 35059.3 3205.3 3221.2 3223.9 3233.7 3344.3 471103.6 477184.0 480746.0 481464.0 481843.0 65518.0 66795.7 66859.0 67057.0 67166.3 30234.8 35292.8 36049.0 37498.0 68794.0 16967.4 17069.6 17119.4 17134.3 17144.6 12930.4 13004.5 13023.3 13032.5 13039.0 20533.8 20808.6 20829.2 20886.9 20914.2 7262.3 7337.9 7340.9 8909.0 8969.5 721.3 761.7 1015.5 1096.7 1119.7 27857.3 30088.7 30706.8 30732.0 30924.4 1044.7 1084.7 1090.0 1136.7 1148.2 11639.1 14913.2 15737.5 15974.2 15996.8 507418.0 680483.2 680512.0 682674.0 685268.0 57760.4 58354.7 58416.0 58638.0 58660.4 1235.8 1367.8 1384.7 1393.4 1419.0 29433.9 29681.0 31720.6 32390.4 32488.8 50450.7 56001.0 57584.1 57832.7 60661.7 617508.0 630807.0 630879.0 630963.0 631077.0 44179.1 44276.6 44589.0 44617.6 45845.2 47243.5 47554.3 47746.0 48085.8 48116.6 39606.8 39752.8 40210.8 40259.5 42303.0 37525.1 39037.2 39625.8 40933.0 41124.6 38864.0 39136.0 39442.4 39549.8 39564.8 32386.4 33241.5 34055.0 34902.8 35059.6 22089.9 25822.0 25984.4 26220.7 26517.0 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:34360.32 Median:13907.59 stddev:97906.87 (704.0%) stddev-sans-first:98148.13 12:28:14 INFO - PROCESS | 1647 | Values: 307.7 374.3 376.1 377.1 394.2 15349.5 16914.9 16932.7 20792.0 20811.2 19771.9 20529.5 20732.9 21072.9 21263.4 4958.9 5292.2 5546.1 5561.6 5567.2 5993.9 6130.8 6131.3 6134.4 6147.8 5833.8 6065.6 6069.3 6109.8 6117.8 6143.5 6148.0 6175.7 6196.2 6207.7 5736.7 5972.9 6045.7 6051.4 6052.4 12987.4 13221.4 13338.5 25318.7 25320.5 2320.8 2350.7 2496.4 2970.4 2981.4 10016.1 10100.2 10463.8 10481.5 10494.2 1766.1 1796.1 1811.7 1849.1 1863.4 88983.0 120077.4 123210.4 124311.1 127151.4 11089.5 11452.3 11717.4 11725.9 11877.9 14623.8 14639.0 14671.8 14679.0 14686.0 7783.3 8151.7 8159.6 8268.2 8285.5 7885.9 7891.3 7947.4 8058.1 8074.7 4478.0 4572.2 4573.6 4650.3 4671.9 1774.9 1792.2 1845.5 1922.4 1970.3 665.8 764.6 850.4 865.1 965.6 20746.9 21414.0 21531.2 21647.0 21792.0 795.8 880.7 924.1 924.8 1026.3 10472.9 11294.1 11366.3 11421.7 11431.9 573432.0 583492.0 584984.0 594825.0 595674.0 107101.0 122004.0 123038.4 124503.0 132754.0 923.9 948.9 965.9 1023.7 1023.8 18976.5 20022.6 20051.4 20165.7 20208.0 37556.2 47526.3 58490.4 58781.7 58862.4 64077.0 79185.6 82130.0 85621.0 86184.0 7760.8 8050.8 8080.2 8484.0 8494.8 9413.4 11112.0 12844.2 13118.4 13570.9 9632.9 11174.8 11437.0 11709.0 11739.1 11278.5 11344.4 11574.1 11583.6 11693.2 11707.0 11979.3 12002.2 12010.0 12046.7 8025.5 8292.3 8297.3 8344.2 8439.9 11070.6 11387.8 11502.4 11667.2 11683.0 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5735.99 Median:4893.73 stddev:3240.70 (66.2%) stddev-sans-first:3233.97 12:28:14 INFO - PROCESS | 1647 | Values: 1474.5 1520.9 1707.6 1851.3 1919.2 7621.4 9001.6 9004.6 9007.8 9011.7 7828.3 8092.8 8472.9 8678.3 8789.7 2007.4 2319.9 2357.9 2361.0 2362.2 2331.1 2349.8 2382.6 2383.7 2386.7 2345.9 2360.8 2374.1 2377.5 2384.8 2327.5 2341.1 2350.3 2351.0 2367.9 2344.6 2351.7 2355.6 2355.8 2358.1 8881.9 8885.1 8973.4 8978.1 9013.3 5151.0 5188.1 5293.6 5321.0 5336.9 3407.9 3497.1 3589.9 3599.7 3616.5 1038.7 1078.5 1079.8 1096.2 1099.5 11676.9 11821.5 11827.5 11840.8 11849.9 4483.0 4646.7 4739.6 4750.3 4758.4 9900.9 9908.9 9914.2 9919.4 9923.7 5331.8 5335.4 5336.3 5346.2 5352.9 4370.4 4532.9 4700.1 4823.1 4881.8 3827.8 3866.1 3944.1 3975.6 3977.9 1254.9 1255.3 1256.9 1269.4 1272.8 828.4 876.2 911.9 915.2 917.9 4905.7 5979.0 6043.0 6186.1 6250.3 959.6 960.7 968.5 974.4 1010.5 3451.9 3739.1 3846.8 3909.7 4076.3 10579.6 10875.8 10979.7 10986.5 10992.7 10936.3 10947.9 10949.0 10955.6 10959.2 7723.0 7959.7 8162.1 8204.0 8363.4 5804.2 5890.2 5991.2 6043.2 6080.2 10052.6 10229.1 10246.4 10258.5 10261.4 9810.5 10145.2 10157.6 10355.9 10357.8 7061.2 7268.7 7486.2 7621.3 7695.5 8414.6 8602.5 8608.6 8612.1 8615.6 7362.6 7469.6 7587.4 7656.2 7734.7 6203.5 7188.3 7339.0 7365.8 7555.9 7012.8 7036.3 7115.3 7128.1 7135.7 4868.3 4953.4 4973.2 5004.4 5083.1 4922.3 4969.4 5007.7 5043.0 5234.4 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4268.02 Median:4575.13 stddev:1346.58 (29.4%) stddev-sans-first:1316.81 12:28:14 INFO - PROCESS | 1647 | Values: 288.4 295.7 306.6 313.5 314.7 4403.4 4586.7 5193.6 5243.1 5257.3 4221.3 4840.3 4910.4 4970.2 5156.8 3986.5 4003.2 4014.5 4016.2 4018.5 3178.7 3428.7 3939.7 4045.5 4046.6 3466.8 3823.8 3912.3 3955.9 3985.8 3910.8 3923.0 3945.9 3951.5 3955.6 2908.2 3332.9 3455.9 3468.7 3483.7 4987.6 5002.8 5010.2 5012.2 5023.8 3735.8 4292.7 4536.5 4539.0 4560.4 5097.3 5364.6 5371.4 5374.2 5410.9 3224.8 3313.7 3767.5 3834.1 3837.3 4775.8 5234.8 5633.5 5686.1 5722.7 3867.0 3873.7 3886.0 3889.2 3889.8 3174.4 3316.9 3890.2 3938.2 3939.5 4399.4 4468.8 4564.7 4585.6 4602.4 4818.8 5471.4 5472.1 5479.9 5487.8 1244.9 1309.2 1335.7 1337.2 1353.6 645.8 727.6 809.9 823.4 830.5 3590.8 4034.6 4185.1 4225.4 4227.3 3700.4 3790.6 4239.0 4638.4 4660.7 3530.8 3809.8 4184.7 4211.7 4227.0 2056.6 2113.5 2388.3 2465.5 2478.7 4487.7 4524.5 4727.9 4792.4 4808.9 4359.8 4378.4 4423.8 4433.8 4470.7 4923.8 5090.2 5142.9 5234.7 5457.6 5408.1 5526.2 5647.4 5654.9 5702.2 4680.8 5397.3 5572.4 5584.4 5634.1 5161.8 5828.2 5839.2 5849.6 5861.9 3290.6 3423.1 3455.3 3713.3 3873.9 5363.9 5745.8 5777.1 5794.0 5801.7 4601.7 5537.6 5549.5 5550.2 5563.0 5087.5 5114.6 5354.1 5568.9 5602.0 4548.6 5122.5 5181.3 5415.8 5446.5 5535.9 5595.7 5612.9 5634.0 5645.9 5285.8 5397.0 5457.9 5461.5 5525.5 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:24603.01 Median:3811.15 stddev:38844.20 (1019.2%) stddev-sans-first:38911.66 12:28:14 INFO - PROCESS | 1647 | Values: 684.2 691.8 712.0 733.2 864.9 14905.7 16436.1 16442.9 17034.4 18004.6 16631.8 17047.1 17051.7 17059.1 17274.7 687.6 846.4 874.0 875.7 875.8 710.8 712.7 856.8 864.8 867.4 908.2 910.6 912.4 913.9 916.8 721.1 905.9 907.2 907.4 907.6 654.7 676.2 676.4 676.8 677.3 15349.8 15424.8 15433.2 15436.2 15438.2 14054.4 14300.7 14348.9 14473.3 14578.3 12984.9 12994.5 13335.4 13357.4 13649.7 4585.4 4682.2 4686.6 4709.0 4822.3 116007.4 117262.0 119705.0 120114.0 120903.8 11188.5 11236.9 11281.7 11530.4 11763.9 21585.6 23045.8 23059.1 23059.8 23078.8 8271.5 8400.8 8528.7 8651.3 8701.7 4374.9 4416.7 4492.8 4512.6 4642.5 3657.5 3805.7 3809.8 3810.6 3811.7 667.2 682.5 695.6 699.0 702.5 536.5 596.8 611.8 670.7 676.0 8330.3 8994.0 9025.4 9203.9 9393.5 479.5 621.4 630.9 677.2 694.5 4314.9 4627.5 4672.8 4691.6 4733.3 99247.0 100526.0 147390.0 153548.0 157924.2 116443.0 135614.0 136588.0 137361.0 149336.3 72183.3 74685.6 122146.0 123478.4 125187.0 8352.8 9410.4 9672.0 9933.5 9936.7 45199.3 47563.3 54500.6 59465.0 60997.4 73470.6 78993.6 87532.0 96855.0 97212.0 12653.7 13082.1 13241.1 13410.1 14097.0 15101.1 15167.8 15581.6 15964.9 15969.0 13529.3 14032.5 14250.9 14492.3 14684.0 13915.6 14100.9 14154.9 14174.4 14446.6 13690.3 13840.1 13862.8 13995.4 14163.0 10979.5 11335.5 11734.2 11835.1 11947.2 11274.5 11428.9 11504.9 11563.5 11580.4 12:28:14 INFO - PROCESS | 1647 | -------- Summary: end -------- 12:28:14 INFO - PROCESS | 1647 | 12:28:14 INFO - TEST-INFO | 1647: exit 0 12:28:14 INFO - TEST-OK | dromaeo_css | took 1117538ms 12:28:14 INFO - TEST-START | kraken 12:28:14 INFO - operating with platform_type : mac_ 12:28:14 INFO - Initialising browser for kraken test... 12:28:14 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpYdrBnp/profile http://localhost:49263/getInfo.html) 12:28:17 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 12:28:17 INFO - PROCESS | 1656 | colorDepth:24 12:28:17 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 12:28:17 INFO - PROCESS | 1656 | __metrics 12:28:17 INFO - PROCESS | 1656 | 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" 12:28:17 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:28:18 INFO - TEST-INFO | 1656: exit 0 12:28:18 INFO - Browser initialized. 12:28:18 INFO - Running cycle 1/1 for kraken test... 12:28:18 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpYdrBnp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:30 INFO - PROCESS | 1657 | RSS: Main: 243412992 12:28:30 INFO - PROCESS | 1657 | 12:28:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 12:28:33 INFO - PROCESS | 1657 | RSS: Main: 293801984 12:28:33 INFO - PROCESS | 1657 | 12:28:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 12:28:36 INFO - PROCESS | 1657 | RSS: Main: 304734208 12:28:36 INFO - PROCESS | 1657 | 12:28:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 12:28:41 INFO - PROCESS | 1657 | RSS: Main: 392212480 12:28:41 INFO - PROCESS | 1657 | 12:28:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 12:28:43 INFO - PROCESS | 1657 | RSS: Main: 296751104 12:28:43 INFO - PROCESS | 1657 | 12:28:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:28:46 INFO - PROCESS | 1657 | RSS: Main: 302137344 12:28:46 INFO - PROCESS | 1657 | 12:28:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 12:28:49 INFO - PROCESS | 1657 | RSS: Main: 338935808 12:28:49 INFO - PROCESS | 1657 | 12:28:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 12:28:53 INFO - PROCESS | 1657 | RSS: Main: 340168704 12:28:53 INFO - PROCESS | 1657 | 12:28:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 12:28:56 INFO - PROCESS | 1657 | RSS: Main: 340901888 12:28:56 INFO - PROCESS | 1657 | 12:28:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:28:59 INFO - PROCESS | 1657 | RSS: Main: 314380288 12:28:59 INFO - PROCESS | 1657 | 12:29:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:01 INFO - PROCESS | 1657 | RSS: Main: 321150976 12:29:01 INFO - PROCESS | 1657 | 12:29:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:04 INFO - PROCESS | 1657 | RSS: Main: 320655360 12:29:04 INFO - PROCESS | 1657 | 12:29:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:08 INFO - PROCESS | 1657 | RSS: Main: 329547776 12:29:08 INFO - PROCESS | 1657 | 12:29:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:11 INFO - PROCESS | 1657 | RSS: Main: 331067392 12:29:11 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 12:29:13 INFO - PROCESS | 1657 | RSS: Main: 374276096 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | __start_tp_report 12:29:13 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 12:29:13 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 12:29:13 INFO - PROCESS | 1657 | |i|pagename|runs| 12:29:13 INFO - PROCESS | 1657 | |0;ai-astar;102;112;113;113;113;113;112;113;114;113 12:29:13 INFO - PROCESS | 1657 | |1;audio-beat-detection;141;113;108;159;105;107;127;107;139;107 12:29:13 INFO - PROCESS | 1657 | |2;audio-dft;189;185;188;184;184;184;181;183;182;183 12:29:13 INFO - PROCESS | 1657 | |3;audio-fft;77;59;62;85;59;58;63;62;85;56 12:29:13 INFO - PROCESS | 1657 | |4;audio-oscillator;86;68;101;71;84;67;97;70;67;99 12:29:13 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;90;85;88;84;87;84;88;85;88;84 12:29:13 INFO - PROCESS | 1657 | |6;imaging-darkroom;93;94;93;94;94;94;106;148;94;94 12:29:13 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;93;132;84;100;132;132;85;86;86 12:29:13 INFO - PROCESS | 1657 | |8;json-parse-financial;55;52;53;52;57;71;81;81;81;53 12:29:13 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;67;69;72;86;76;76;73;72;72;72 12:29:13 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;78;82;82;83;84;84;81;82;83;79 12:29:13 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;145;146;154;135;133;136;137;134;175;157 12:29:13 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;166;167;166;167;164;164;164;164;172;165 12:29:13 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;66;67;63;68;66;67;66;65;67 12:29:13 INFO - PROCESS | 1657 | __end_tp_report 12:29:13 INFO - PROCESS | 1657 | __start_cc_report 12:29:13 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2038 12:29:13 INFO - PROCESS | 1657 | __end_cc_report 12:29:13 INFO - PROCESS | 1657 | __startTimestamp1471030153462__endTimestamp 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | ------- Summary: start ------- 12:29:13 INFO - PROCESS | 1657 | Number of tests: 14 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.49 (3.1%) stddev-sans-first:0.60 12:29:13 INFO - PROCESS | 1657 | Values: 102.0 112.0 113.0 113.0 113.0 113.0 112.0 113.0 114.0 113.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:120.00 stddev:19.09 (15.9%) stddev-sans-first:18.87 12:29:13 INFO - PROCESS | 1657 | Values: 141.0 113.0 108.0 159.0 105.0 107.0 127.0 107.0 139.0 107.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.30 Median:184.00 stddev:2.50 (1.4%) stddev-sans-first:1.99 12:29:13 INFO - PROCESS | 1657 | Values: 189.0 185.0 188.0 184.0 184.0 184.0 181.0 183.0 182.0 183.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:66.60 Median:62.50 stddev:11.27 (18.0%) stddev-sans-first:11.30 12:29:13 INFO - PROCESS | 1657 | Values: 77.0 59.0 62.0 85.0 59.0 58.0 63.0 62.0 85.0 56.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:77.50 stddev:14.13 (18.2%) stddev-sans-first:14.87 12:29:13 INFO - PROCESS | 1657 | Values: 86.0 68.0 101.0 71.0 84.0 67.0 97.0 70.0 67.0 99.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.50 stddev:2.16 (2.5%) stddev-sans-first:1.83 12:29:13 INFO - PROCESS | 1657 | Values: 90.0 85.0 88.0 84.0 87.0 84.0 88.0 85.0 88.0 84.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:100.40 Median:94.00 stddev:17.17 (18.3%) stddev-sans-first:18.00 12:29:13 INFO - PROCESS | 1657 | Values: 93.0 94.0 93.0 94.0 94.0 94.0 106.0 148.0 94.0 94.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:101.50 Median:85.00 stddev:21.59 (25.4%) stddev-sans-first:22.06 12:29:13 INFO - PROCESS | 1657 | Values: 85.0 93.0 132.0 84.0 100.0 132.0 132.0 85.0 86.0 86.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:64.00 stddev:13.23 (20.7%) stddev-sans-first:13.66 12:29:13 INFO - PROCESS | 1657 | Values: 55.0 52.0 53.0 52.0 57.0 71.0 81.0 81.0 81.0 53.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:72.50 stddev:5.17 (7.1%) stddev-sans-first:4.92 12:29:13 INFO - PROCESS | 1657 | Values: 67.0 69.0 72.0 86.0 76.0 76.0 73.0 72.0 72.0 72.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.50 stddev:1.99 (2.4%) stddev-sans-first:1.56 12:29:13 INFO - PROCESS | 1657 | Values: 78.0 82.0 82.0 83.0 84.0 84.0 81.0 82.0 83.0 79.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:145.50 stddev:13.48 (9.3%) stddev-sans-first:14.30 12:29:13 INFO - PROCESS | 1657 | Values: 145.0 146.0 154.0 135.0 133.0 136.0 137.0 134.0 175.0 157.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:166.00 stddev:2.47 (1.5%) stddev-sans-first:2.62 12:29:13 INFO - PROCESS | 1657 | Values: 166.0 167.0 166.0 167.0 164.0 164.0 164.0 164.0 172.0 165.0 12:29:13 INFO - PROCESS | 1657 | 12:29:13 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:67.00 stddev:1.40 (2.1%) stddev-sans-first:1.45 12:29:13 INFO - PROCESS | 1657 | Values: 67.0 66.0 67.0 63.0 68.0 66.0 67.0 66.0 65.0 67.0 12:29:13 INFO - PROCESS | 1657 | -------- Summary: end -------- 12:29:13 INFO - PROCESS | 1657 | 12:29:14 INFO - TEST-INFO | 1657: exit 0 12:29:14 INFO - TEST-OK | kraken | took 59386ms 12:29:14 INFO - SUITE-END | took 1176s 12:29:14 INFO - Completed test suite (00:19:37) 12:29:14 INFO - javascript benchmark 12:29:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.630110687824, 3388.7289016878653, 3394.1853955050788, 3421.3841508528453, 3441.518187453757, 5548.278723765905, 5571.5264202542785, 5636.774529018845, 5670.432956704322, 5683.090705487123, 4787.497312782151, 5657.632253903494, 5664.0, 5720.256366672327, 5773.855653608662, 4451.910961780763, 5060.873478163057, 5105.157648987931, 5159.896802063957, 5174.689518628876, 5256.894862102756, 5270.894582108355, 5316.149416093426, 5357.249985002083, 5376.865578360533, 5119.436861945183, 5168.121419358718, 5202.76587553561, 5242.8689282767855, 5248.343957005374, 5189.792408303663, 5192.247124166975, 5211.478852114796, 5221.0, 5224.817131400421, 5116.897662046738, 5117.462666420047, 5123.846284611468, 5168.715720635363, 5246.422893481714, 5553.055980483327, 5697.344805347355, 5702.059160238556, 5703.65778053314, 5728.427157284238, 6160.983437732769, 6180.3201647818705, 6181.96909015452, 6193.1329613854095, 6193.969030154865, 5094.872628184325, 6045.788397406113, 6146.385361463863, 6270.9372906271265, 6277.246730392337, 5808.0, 5829.912551311733, 5835.095560188178, 5839.562032847511, 5864.970675146597, 6371.96814015927, 6380.202474690664, 6383.425491705769, 6390.584612000298, 6396.328385519547, 5914.260717410324, 5981.102834574848, 5994.730237139252, 6018.21763170785, 6084.847878803065, 6185.443310102112, 6209.410106039919, 6212.378762123842, 6226.595271307441, 6233.781817636452, 5098.847034588968, 6020.397960203944, 6159.784407545714, 6160.229971253593, 6166.321704612489, 5998.610090344114, 6079.300880398722, 6234.9688251559355, 6237.500999919996, 6250.843728906813, 4926.75366231687, 4930.975345123252, 4942.456329803718, 4942.975285123552, 4945.530174633404, 4357.825686972549, 4441.267190913528, 4460.130274596422, 4480.4623445186135, 4490.0, 5596.356419011784, 5632.0, 5644.858878527987, 5669.40471250521, 5725.456081672234, 6197.721102550306, 6201.472874805602, 6228.314885362606, 6232.937670623236, 6247.250329960434, 5654.632448890891, 5657.688827114466, 5661.631993920464, 5668.121441176624, 5693.060644993529, 4652.883677908079, 5260.815871444481, 5340.332458442695, 5375.086235340002, 5385.811496597678, 6292.4966002719675, 6316.084167795736, 6321.1782468278825, 6335.841603959938, 6349.111124442582, 6266.874662506633, 6270.0, 6273.843153921006, 6275.341089185752, 6284.08880712285, 6214.937850621436, 6242.094896240111, 6266.342034086538, 6323.20959880015, 6340.365963403808, 6124.2588809726185, 6159.076138579249, 6164.013757798731, 6171.9691401542705, 6178.814635560941, 6222.564420490522, 6239.344868788893, 6244.281907580505, 6249.1563638908165, 6262.906056409066, 5861.648301101947, 5895.587308888336, 5964.314103877936, 6040.6979651018155, 6047.0627052806885, 6034.426729460694, 6045.1234570987845, 6073.240844894388, 6120.051392034242, 6127.632342059491, 5897.67562784051, 5909.468147866713, 5961.612495187799, 5978.432048955342, 5987.0, 4568.451785785727, 5806.535477161901, 6060.545459090497, 6068.4538391544975, 6094.8171554853425, 4845.49122342149, 5866.178734977192, 5866.208061911588, 5908.084246941731, 5922.111683247547], "value": 5643.634884512709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.09464995932944, 495.5218214423081, 522.034236662175, 535.0102310725158, 547.3951283831369, 26442.86778566107, 32341.51487727683, 32472.025839224832, 32503.0, 32523.512147317808, 32774.50838237428, 35029.64970350296, 41675.58324416755, 42332.0, 44986.325205121866, 9600.615975360977, 9759.90240097597, 9780.119789218968, 9781.168600668952, 9826.459544725038, 9640.276979226552, 9725.51372431379, 9729.318947673666, 9738.220942324633, 9767.218622510218, 8644.135586441369, 8833.690820821272, 8834.425762325429, 8947.418417802855, 8998.865017024717, 8813.294936405104, 11506.942465287662, 11702.882971170264, 11721.413929303586, 12006.21959572625, 11733.53065877364, 11807.114466415052, 11809.704757381049, 11816.881831181665, 11821.704457388549, 40113.59886401157, 40468.0, 40476.79761601202, 40487.7975610123, 40627.79686101551, 3473.9826300868335, 3475.8609655613745, 3487.0410637074756, 3521.9823900880588, 3636.2909232699635, 32167.19582010445, 32685.836570816995, 33599.83200083984, 33801.66198338034, 35059.29881402383, 3205.3108581655056, 3221.1624977506017, 3223.8549265282886, 3233.676632336771, 3344.2809795894, 471103.6444817754, 477184.0, 480746.0, 481464.0, 481843.0, 65518.01722974159, 66795.65634749208, 66859.0, 67057.0, 67166.328336716, 30234.84882575573, 35292.823535882155, 36049.0, 37498.0, 68794.0, 16967.406140785013, 17069.573260668334, 17119.400820971456, 17134.314627414762, 17144.571385715208, 12930.418131184118, 13004.544840930523, 13023.348832558524, 13032.47870085186, 13038.956883449302, 20533.794662053187, 20808.58382832365, 20829.1668333268, 20886.89556552238, 20914.163433462796, 7262.273772622695, 7337.899315102777, 7340.889886651705, 8908.955455222813, 8969.461832290084, 721.3291638775988, 761.6991288441145, 1015.4973288222377, 1096.658633816807, 1119.7256672115384, 27857.30356741057, 30088.699113008588, 30706.846465767976, 30732.0, 30924.381512370077, 1044.7127040064042, 1084.6854412220525, 1089.9507732086886, 1136.710138914588, 1148.2192109365712, 11639.14077338377, 14913.179775112472, 15737.527874163794, 15974.186352228891, 15996.8404265425, 507418.0, 680483.195168042, 680512.0, 682674.0, 685268.0, 57760.422395776346, 58354.7082264586, 58416.0, 58638.0, 58660.41339586635, 1235.7775600392015, 1367.8221831161886, 1384.702289007868, 1393.4078016842802, 1419.0492370112256, 29433.85283073571, 29681.0, 31720.572574233753, 32390.35219295554, 32488.837555812068, 50450.74774626103, 56001.0, 57584.13623795562, 57832.71083644555, 60661.69669151626, 617508.0, 630807.0, 630879.0, 630963.0, 631077.0, 44179.11641767211, 44276.557234427244, 44589.0, 44617.55382446134, 45845.249285043006, 47243.52756472391, 47554.286685700434, 47746.0, 48085.75957120192, 48116.55650330496, 39606.80196598999, 39752.80741577757, 40210.79894600508, 40259.49567837583, 42303.0, 37525.061873453385, 39037.219255615295, 39625.801870991614, 40933.0, 41124.62568301918, 38864.02839929024, 39136.0, 39442.40836387399, 39549.80225098856, 39564.80217598894, 32386.35227295488, 33241.50137747984, 34055.0, 34902.77840275578, 35059.64940350564, 22089.889550552787, 25821.967121314938, 25984.35039124037, 26220.737792622593, 26517.0], "value": 20476.93617719313, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.66771886362733, 374.33929115111835, 376.08798663241635, 377.1325950314276, 394.2332163941136, 15349.539513814589, 16914.91542542287, 16932.661346773057, 20792.0, 20811.167553297888, 19771.901140494276, 20529.486762830937, 20732.89633551834, 21072.894635526838, 21263.36209913701, 4958.851234462972, 5292.153255479124, 5546.140348246028, 5561.5828812839, 5567.248421463111, 5993.910091348634, 6130.846728831771, 6131.264248290189, 6134.355892631276, 6147.784827531037, 5833.766649334021, 6065.575409721321, 6069.332373438918, 6109.847253818646, 6117.755289788403, 6143.508519318444, 6148.016317389241, 6175.66033868137, 6196.225471816023, 6207.720652570622, 5736.655800651933, 5972.850678733023, 6045.697715114226, 6051.425114614105, 6052.394760523956, 12987.350632468433, 13221.405036773273, 13338.466461341535, 25318.73406329676, 25320.493590128095, 2320.8259380546356, 2350.6589268128455, 2496.4383013821744, 2970.435617232719, 2981.418623368444, 10016.148627366683, 10100.242481813893, 10463.84304235437, 10481.528331225114, 10494.212934029902, 1766.0904634113442, 1796.0570700382273, 1811.7372980917826, 1849.084703071988, 1863.4037108125406, 88983.0, 120077.39961300224, 123210.38394807969, 124311.13533297008, 127151.36424317912, 11089.500972456259, 11452.25560338592, 11717.355545445082, 11725.882741172649, 11877.940610296893, 14623.78064329036, 14639.0, 14671.77992330116, 14678.972471926863, 14686.0, 7783.260590243918, 8151.673933042612, 8159.592020398956, 8268.173182681683, 8285.461445006054, 7885.921140788519, 7891.250331218525, 7947.4039447041705, 8058.11360750317, 8074.717384891501, 4478.0, 4572.222722137245, 4573.611243044312, 4650.348951146842, 4671.859844204611, 1774.946751597454, 1792.2024699009075, 1845.5109396010068, 1922.423273018089, 1970.2513045043079, 665.7503436211421, 764.5603777827771, 850.4132148817296, 865.0916537635458, 965.5510187762724, 20746.896265518575, 21414.03636836346, 21531.246406375703, 21646.99797594007, 21792.0, 795.7891158842912, 880.7401816464159, 924.0574613893925, 924.7549399409162, 1026.2816028779885, 10472.947635261775, 11294.096472282197, 11366.261193022427, 11421.657350279505, 11431.885681143249, 573432.0, 583492.0, 584984.0, 594825.0, 595674.0, 107101.0, 122004.0, 123038.38480807717, 124503.0, 132754.0, 923.8521836506127, 948.9098535639104, 965.8937516873139, 1023.7287118913493, 1023.8054769593903, 18976.539502245083, 20022.62274333136, 20051.393358780297, 20165.661824474475, 20207.98960051962, 37556.2488750218, 47526.28710569414, 58490.4150958502, 58781.70609146927, 58862.41137588741, 64077.0, 79185.60407197927, 82130.0, 85621.0, 86184.0, 7760.844783104193, 8050.83898322042, 8080.232377924088, 8484.0, 8494.830103397773, 9413.388129771543, 11111.999920007238, 12844.229346239255, 13118.409671564994, 13570.93214533921, 9632.855507167538, 11174.776504470028, 11437.0, 11709.004734597655, 11739.060875129799, 11278.492467838803, 11344.376059316486, 11574.131940104695, 11583.594574189863, 11693.239939403911, 11707.0, 11979.34113623759, 12002.1598488105, 12010.039196864405, 12046.698832529257, 8025.518468891885, 8292.33661307106, 8297.25324720778, 8344.249017588203, 8439.915600843913, 11070.5571777128, 11387.772244554897, 11502.367369794742, 11667.241629294409, 11683.0], "value": 9478.263349068558, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.542891703572, 1520.8783297336213, 1707.5816424975883, 1851.25949620152, 1919.2227148005063, 7621.428392870536, 9001.594928228229, 9004.639814407432, 9007.819843603123, 9011.684591039315, 7828.256315650005, 8092.797680058002, 8472.872906906401, 8678.262347700438, 8789.692360767374, 2007.3576455534235, 2319.9072037118535, 2357.9410514737096, 2361.020176626698, 2362.244081893786, 2331.067572970808, 2349.788519033287, 2382.642603609455, 2383.7377888432256, 2386.6658667786523, 2345.94135146621, 2360.8111351091957, 2374.1097088591778, 2377.512609914972, 2384.7972922301537, 2327.5461285049423, 2341.0869760793303, 2350.27141586108, 2350.9882450587806, 2367.8816059197143, 2344.6248600224053, 2351.6825228594093, 2355.599548076831, 2355.8350915435926, 2358.13927916311, 8881.911180888173, 8885.0670679579, 8973.371863969525, 8978.102189781035, 9013.324000699908, 5150.9742451287875, 5188.143956247215, 5293.57651387888, 5321.042212401766, 5336.91994620081, 3407.875401117625, 3497.055794935379, 3589.856405743767, 3599.6760291573623, 3616.4936908832783, 1038.7403149212696, 1078.541619811577, 1079.794838980599, 1096.1942971915635, 1099.5162128663362, 11676.883231167665, 11821.527138914433, 11827.467763950557, 11840.822387664191, 11849.94075029628, 4482.955170448254, 4646.651501137429, 4739.644526660514, 4750.263709125022, 4758.357621721093, 9900.900990990142, 9908.851367229498, 9914.157296629723, 9919.355241909252, 9923.65267215644, 5331.786728530893, 5335.439778823167, 5336.306280183629, 5346.1713434417725, 5352.8661783454945, 4370.38814565961, 4532.9320060199125, 4700.106979673851, 4823.107725070873, 4881.755912204447, 3827.8468861245797, 3866.149447121629, 3944.1322908959983, 3975.6421922027157, 3977.9204415911518, 1254.8996080313552, 1255.334672623511, 1256.9245845249313, 1269.397036407699, 1272.8090786381931, 828.3787159630277, 876.2464280718577, 911.9179273865384, 915.1671978499667, 917.9173874351206, 4905.705657660552, 5978.970105149447, 6043.002904520793, 6186.10301506279, 6250.312465628777, 959.6449313753956, 960.7357976556364, 968.5447839515416, 974.4153507895206, 1010.545254635417, 3451.930961380758, 3739.139997800521, 3846.769193848378, 3909.667678247381, 4076.30702780528, 10579.57681692739, 10875.83686244707, 10979.725506862393, 10986.505607247693, 10992.670219893416, 10936.289141205796, 10947.945260273647, 10949.014588687056, 10955.616553420592, 10959.178061645409, 7722.961385193038, 7959.681612735541, 8162.10216876155, 8204.0, 8363.372747043995, 5804.1926873115535, 5890.1740460457395, 5991.191189189403, 6043.214382130295, 6080.179175811209, 10052.64815731446, 10229.107615090934, 10246.38521688701, 10258.487075646337, 10261.384316941007, 9810.509474526389, 10145.239107067147, 10157.644482443078, 10355.94822025885, 10357.792844143225, 7061.152661680631, 7268.745593904188, 7486.213947535648, 7621.31408173267, 7695.538267703956, 8414.579271036546, 8602.483850968962, 8608.569571521273, 8612.138786121337, 8615.569221539023, 7362.558246505227, 7469.551826890404, 7587.430942679432, 7656.234376562299, 7734.651940662571, 6203.534734894811, 7188.28117188277, 7339.045924029842, 7365.8158546036775, 7555.9244407555225, 7012.789616311519, 7036.331548502884, 7115.324044215791, 7128.108986376703, 7135.678894449658, 4868.269759536098, 4953.405591329064, 4973.204287314013, 5004.449510553909, 5083.059633967728, 4922.2616607507725, 4969.378827646544, 5007.7245751484015, 5042.974785126051, 5234.39804422481], "value": 4604.2600761660315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.39437181917975, 295.69543370328563, 306.5753930805834, 313.4592827372781, 314.69317415519873, 4403.361512580674, 4586.724796512203, 5193.63644544882, 5243.134882744343, 5257.290265814114, 4221.290377397151, 4840.273958906166, 4910.410750709911, 4970.179920313144, 5156.819511317105, 3986.5016872890887, 4003.219372222418, 4014.4781178446906, 4016.1967606478674, 4018.457508236387, 3178.6980236877575, 3428.6914177724, 3939.7439166454233, 4045.4538637283886, 4046.5751096134923, 3466.757326987112, 3823.8146174685935, 3912.2957867583964, 3955.940660890089, 3985.8206380712936, 3910.843566257346, 3922.980385098056, 3945.8816235512695, 3951.525816901961, 3955.64399204073, 2908.243856597279, 3332.8833490827706, 3455.8617655293756, 3468.722502199818, 3483.651634836521, 4987.576056035241, 5002.849914502571, 5010.248462730583, 5012.198048312289, 5023.8492845214705, 3735.775853448775, 4292.716912588028, 4536.54634536547, 4539.0, 4560.361549383089, 5097.3118628984985, 5364.570834333244, 5371.435999220064, 5374.226505253257, 5410.91883621746, 3224.758143139274, 3313.7349012078976, 3767.4913886625504, 3834.0798208430056, 3837.347650899326, 4775.785089670973, 5234.7644356004075, 5633.478960680593, 5686.09022556389, 5722.685252311121, 3867.003072618743, 3873.65137137659, 3885.980570097188, 3889.183271513014, 3889.844406223776, 3174.4445221841593, 3316.93366132676, 3890.2414029264587, 3938.212357528506, 3939.5075615548058, 4399.428074350314, 4468.821247150143, 4564.657650676146, 4585.610223131004, 4602.3786788783755, 4818.807247710052, 5471.398146203915, 5472.124460086367, 5479.863003424946, 5487.835364939058, 1244.925304481734, 1309.214471317221, 1335.7128217433299, 1337.2043634037732, 1353.6142199473102, 645.8417687666552, 727.6143643868758, 809.8704207326799, 823.4359463767339, 830.5141492227115, 3590.838412271454, 4034.616711412411, 4185.074865561875, 4225.408442818008, 4227.302495088276, 3700.444933259979, 3790.6497971257995, 4239.0, 4638.350630911607, 4660.673752837337, 3530.770499917497, 3809.8095095245126, 4184.748915065105, 4211.7472951623, 4226.978865105655, 2056.568120694671, 2113.547492946604, 2388.3073908566666, 2465.5315490056832, 2478.7397323280798, 4487.730736155841, 4524.502304746476, 4727.881802954885, 4792.400949881265, 4808.8797780055775, 4359.825606975685, 4378.365137055109, 4423.778811059498, 4433.8226470941445, 4470.742275151028, 4923.803047878045, 5090.174952901176, 5142.942822870807, 5234.659747116425, 5457.5633949283965, 5408.053590621546, 5526.198701188386, 5647.3787883333625, 5654.886902262014, 5702.151048042472, 4680.806394369414, 5397.27136836522, 5572.387037425961, 5584.4136365681015, 5634.084411300178, 5161.845144645667, 5828.193260089386, 5839.175340152207, 5849.607227807946, 5861.9413805861395, 3290.621578518501, 3423.109991402303, 3455.308938212418, 3713.2759111973987, 3873.934667966219, 5363.892722145613, 5745.787871669354, 5777.133429985536, 5794.01501744696, 5801.6519008859605, 4601.67788254819, 5537.640053396516, 5549.472800083986, 5550.222968784454, 5563.0, 5087.491250875031, 5114.590832733446, 5354.143337066051, 5568.916466252928, 5601.971990140023, 4548.56788605082, 5122.538971492583, 5181.2746215529105, 5415.756290966837, 5446.482584154499, 5535.861603459945, 5595.748191331481, 5612.905483430692, 5634.0422128239115, 5645.915311270416, 5285.753554739297, 5397.0, 5457.908418316273, 5461.453854614507, 5525.475079867406], "value": 3841.0204930526406, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.216572025031, 691.7924622613211, 711.9964400177998, 733.2374330696076, 864.8745931839885, 14905.701885962275, 16436.0960147192, 16442.917785411086, 17034.403795867147, 18004.63990720188, 16631.750523742154, 17047.14764261785, 17051.658966820687, 17059.061751603687, 17274.740878886827, 687.6149356360454, 846.3948276981956, 873.9563021848882, 875.7329014650529, 875.8117004843957, 710.7867639708093, 712.7220384050198, 856.751542052804, 864.8140649760301, 867.397158974513, 908.2325435007408, 910.5902343945252, 912.3613470570594, 913.9086091390841, 916.8166366726681, 721.1202333153544, 905.9003509613938, 907.2107266678032, 907.37844576465, 907.5825120444604, 654.715198888485, 676.1615596660106, 676.4216594811414, 676.8477092654163, 677.3226773226774, 15349.846501534954, 15424.768628470581, 15433.151176685278, 15436.228188590525, 15438.228088595526, 14054.43782248719, 14300.713985720227, 14348.856511434857, 14473.276336183255, 14578.271086445635, 12984.870151298555, 12994.545190918367, 13335.399907004208, 13357.398917048757, 13649.658758531017, 4585.358049873021, 4682.204025315705, 4686.625069994427, 4708.976455117737, 4822.276658501218, 116007.41996289964, 117262.0, 119705.0, 120114.0, 120903.79096209013, 11188.496517656726, 11236.887631123584, 11281.661550153509, 11530.423478826026, 11763.882361176278, 21585.56828863414, 23045.769542304697, 23059.07763689467, 23059.769402306098, 23078.769212307998, 8271.503709777433, 8400.789980250544, 8528.701495447629, 8651.307895368482, 8701.738947831449, 4374.934375984363, 4416.712913660666, 4492.842750503717, 4512.593866551961, 4642.53574642533, 3657.487951686766, 3805.6955443564448, 3809.790461524643, 3810.5617853946596, 3811.7331786774657, 667.1527160506226, 682.5461068389565, 695.5617960684737, 699.0458024796117, 702.5187746393736, 536.4528181255098, 596.7523477756682, 611.843979785152, 670.6512613440983, 675.9695813688396, 8330.291925186306, 8993.955030224806, 9025.437858660904, 9203.907960920438, 9393.483358415353, 479.52287473962934, 621.3506885304868, 630.8518496336715, 677.2110491277705, 694.4652617484509, 4314.913701725948, 4627.537246275413, 4672.808433849346, 4691.648126390534, 4733.26634371666, 99247.0, 100526.0, 147390.0, 153548.0, 157924.21037894738, 116443.0, 135614.0, 136588.0, 137361.0, 149336.3329583802, 72183.27816721871, 74685.6265718668, 122146.0, 123478.38260808638, 125187.0, 8352.791180220544, 9410.43537387759, 9672.0, 9933.453660048766, 9936.652217172365, 45199.32201017053, 47563.28655070107, 54500.63748406321, 59465.0, 60997.39002609917, 73470.63264683857, 78993.60503197447, 87532.0, 96855.0, 97212.0, 12653.683657908627, 13082.149660272053, 13241.073124881166, 13410.061295709207, 14097.0, 15101.093934363973, 15167.772483412979, 15581.610459738597, 15964.92017539905, 15968.962017468828, 13529.323533823466, 14032.508862189774, 14250.928745356206, 14492.275386230856, 14684.0, 13915.58253252404, 14100.929495352457, 14154.858451415766, 14174.43302267939, 14446.566603001505, 13690.315484225948, 13840.100393474391, 13862.792058119338, 13995.370208340446, 14163.0, 10979.505922233679, 11335.489902954552, 11734.237274577124, 11835.11236657237, 11947.223430476992, 11274.549018039515, 11428.94285528567, 11504.94247528757, 11563.537458501902, 11580.36308003068], "value": 7991.572093923573, "unit": "score"}], "name": "dromaeo_css", "value": 7327.799463913905, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 114.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 108.0, 159.0, 105.0, 107.0, 127.0, 107.0, 139.0, 107.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 188.0, 184.0, 184.0, 184.0, 181.0, 183.0, 182.0, 183.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 62.0, 85.0, 59.0, 58.0, 63.0, 62.0, 85.0, 56.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 101.0, 71.0, 84.0, 67.0, 97.0, 70.0, 67.0, 99.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 88.0, 84.0, 87.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 106.0, 148.0, 94.0, 94.0], "value": 100.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 93.0, 132.0, 84.0, 100.0, 132.0, 132.0, 85.0, 86.0, 86.0], "value": 101.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 52.0, 57.0, 71.0, 81.0, 81.0, 81.0, 53.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 72.0, 86.0, 76.0, 76.0, 73.0, 72.0, 72.0, 72.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 82.0, 83.0, 84.0, 84.0, 81.0, 82.0, 83.0, 79.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 154.0, 135.0, 133.0, 136.0, 137.0, 134.0, 175.0, 157.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 166.0, 167.0, 164.0, 164.0, 164.0, 164.0, 172.0, 165.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 63.0, 68.0, 66.0, 67.0, 66.0, 65.0, 67.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1449.4, "alertThreshold": 2.0}]} 12:29:14 INFO - Return code: 0 12:29:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:14 INFO - # TBPL SUCCESS # 12:29:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:14 INFO - 2016-08-12 12:29:14.811 screenresolution[1661:9548] starting screenresolution argv=screenresolution get 12:29:14 INFO - 2016-08-12 12:29:14.814 screenresolution[1661:9548] Display 0: 1600x1200x32@60 12:29:14 INFO - 2016-08-12 12:29:14.820 screenresolution[1662:9551] starting screenresolution argv=screenresolution list 12:29:14 INFO - Available Modes on Display 0 12:29:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:15 INFO - Intel Iris: 12:29:15 INFO - Chipset Model: Intel Iris 12:29:15 INFO - Type: GPU 12:29:15 INFO - Bus: Built-In 12:29:15 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:15 INFO - Vendor: Intel (0x8086) 12:29:15 INFO - Device ID: 0x0a2e 12:29:15 INFO - Revision ID: 0x0009 12:29:15 INFO - Displays: 12:29:15 INFO - Display: 12:29:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:15 INFO - Display Serial Number: 1600x1200 60 12:29:15 INFO - Main Display: Yes 12:29:15 INFO - Mirror: Off 12:29:15 INFO - Online: Yes 12:29:15 INFO - Rotation: Supported 12:29:15 INFO - Return code: 0 12:29:15 INFO - Running post-action listener: _resource_record_post_action 12:29:15 INFO - [mozharness: 2016-08-12 19:29:15.057436Z] Finished run-tests step (success) 12:29:15 INFO - Running post-run listener: _resource_record_post_run 12:29:15 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38106112; Write bytes: 701679616; Read time: 385; Write time: 2046 12:29:15 INFO - TinderboxPrint: CPU usage
53.4% 12:29:15 INFO - TinderboxPrint: I/O read bytes / time
38,106,112 / 385 12:29:15 INFO - TinderboxPrint: I/O write bytes / time
701,679,616 / 2,046 12:29:15 INFO - TinderboxPrint: CPU idle
2,258.8 (46.6%) 12:29:15 INFO - TinderboxPrint: CPU system
966.4 (19.9%) 12:29:15 INFO - TinderboxPrint: CPU user
1,624.4 (33.5%) 12:29:15 INFO - TinderboxPrint: Swap in / out
441,667,584 / 0 12:29:15 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399673856; Write bytes: 395302400; Read time: 29567; Write time: 792 12:29:15 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 35918848; Write bytes: 292487680; Read time: 354; Write time: 1217 12:29:15 INFO - Running post-run listener: _upload_blobber_files 12:29:15 INFO - Blob upload gear active. 12:29:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:15 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 12:29:15 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:29:15 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:15 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:29:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:17 INFO - (blobuploader) - INFO - Done attempting. 12:29:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:29:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:29:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:18 INFO - (blobuploader) - INFO - Done attempting. 12:29:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:21 INFO - (blobuploader) - INFO - Done attempting. 12:29:21 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:21 INFO - Return code: 0 12:29:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ef16c4ccde43928e323ac30b58c9d4810bffb5d5c81194c6949d81ca76cdfc832bc347756172be6611bc61c86728ec9cae50e1c7cb123bea987b1e595689282", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/354adf2ebb25dee42f0d31ab13150201a09874968694f780a9a85a0618e5e2b8bcb9d6848788d1b3c3d9d5007db883b553c0f5b6dfa453b3738dc2560ae13584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/462bf53b775b961cccb5da7693b108aa35f4d702309ac92aca3719c621e06b08c12cb02e352a6d36f95edcfd3fc81d5dae604b5d257a8aa563220ffbf3e34892"} 12:29:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:21 INFO - Contents: 12:29:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ef16c4ccde43928e323ac30b58c9d4810bffb5d5c81194c6949d81ca76cdfc832bc347756172be6611bc61c86728ec9cae50e1c7cb123bea987b1e595689282", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/354adf2ebb25dee42f0d31ab13150201a09874968694f780a9a85a0618e5e2b8bcb9d6848788d1b3c3d9d5007db883b553c0f5b6dfa453b3738dc2560ae13584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/462bf53b775b961cccb5da7693b108aa35f4d702309ac92aca3719c621e06b08c12cb02e352a6d36f95edcfd3fc81d5dae604b5d257a8aa563220ffbf3e34892"} 12:29:21 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:21 INFO - Copying logs to upload dir... 12:29:21 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.851638 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-08-12 12:29:21.347248) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 12:29:21.350436) ========= 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.udfnaAX7qD/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.8vAsk88nqh/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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/Fx-Team/sha512/6ef16c4ccde43928e323ac30b58c9d4810bffb5d5c81194c6949d81ca76cdfc832bc347756172be6611bc61c86728ec9cae50e1c7cb123bea987b1e595689282", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/354adf2ebb25dee42f0d31ab13150201a09874968694f780a9a85a0618e5e2b8bcb9d6848788d1b3c3d9d5007db883b553c0f5b6dfa453b3738dc2560ae13584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/462bf53b775b961cccb5da7693b108aa35f4d702309ac92aca3719c621e06b08c12cb02e352a6d36f95edcfd3fc81d5dae604b5d257a8aa563220ffbf3e34892"} build_url:https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011160 build_url: 'https://queue.taskcluster.net/v1/task/KqtCh_eKS0GfxP3aC-Tzgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ef16c4ccde43928e323ac30b58c9d4810bffb5d5c81194c6949d81ca76cdfc832bc347756172be6611bc61c86728ec9cae50e1c7cb123bea987b1e595689282", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/354adf2ebb25dee42f0d31ab13150201a09874968694f780a9a85a0618e5e2b8bcb9d6848788d1b3c3d9d5007db883b553c0f5b6dfa453b3738dc2560ae13584", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/462bf53b775b961cccb5da7693b108aa35f4d702309ac92aca3719c621e06b08c12cb02e352a6d36f95edcfd3fc81d5dae604b5d257a8aa563220ffbf3e34892"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 12:29:21.383524) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 12:29:21.383850) ========= 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.udfnaAX7qD/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.8vAsk88nqh/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.004974 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 12:29:21.432706) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 12:29:21.433032) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 12:29:21.433340) ========= ========= Total master_lag: 0.17 =========