builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1471914305.85 results: success (0) buildid: 20160822160116 builduid: 64502076e4324486ad1921174204d08b revision: 24763f58772d45279a935790f732d80851924b46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:05.847761) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:05.848364) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:05.848657) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:06.118618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:06.118922) ========= 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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-22 18:05:06-- 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% 71.9M=0s 2016-08-22 18:05:06 (71.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.530244 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:06.673625) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:06.673979) ========= 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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.027404 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:06.722623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:06.722987) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 24763f58772d45279a935790f732d80851924b46 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 24763f58772d45279a935790f732d80851924b46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RRSafDY7Ss/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.JSCeJCgbLt/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-22 18:05:06,797 truncating revision to first 12 chars 2016-08-22 18:05:06,798 Setting DEBUG logging. 2016-08-22 18:05:06,798 attempt 1/10 2016-08-22 18:05:06,798 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/24763f58772d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 18:05:07,443 unpacking tar archive at: mozilla-central-24763f58772d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.896005 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:07.641160) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:07.641487) ========= script_repo_revision: 24763f58772d45279a935790f732d80851924b46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:07.641892) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:07.642159) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 18:05:07.660527) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-22 18:05:07.660833) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RRSafDY7Ss/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.JSCeJCgbLt/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05:07 INFO - MultiFileLogger online at 20160822 18:05:07 in /builds/slave/test 18:05:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:05:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:05:07 INFO - {'append_to_log': False, 18:05:07 INFO - 'base_work_dir': '/builds/slave/test', 18:05:07 INFO - 'blob_upload_branch': 'Firefox', 18:05:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:05:07 INFO - 'branch': 'Firefox', 18:05:07 INFO - 'buildbot_json_path': 'buildprops.json', 18:05:07 INFO - 'config_files': ('talos/mac_config.py',), 18:05:07 INFO - 'default_actions': ('clobber', 18:05:07 INFO - 'read-buildbot-config', 18:05:07 INFO - 'download-and-extract', 18:05:07 INFO - 'populate-webroot', 18:05:07 INFO - 'create-virtualenv', 18:05:07 INFO - 'install', 18:05:07 INFO - 'run-tests'), 18:05:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:05:07 INFO - 'download_minidump_stackwalk': True, 18:05:07 INFO - 'download_symbols': 'ondemand', 18:05:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:05:07 INFO - 'tooltool.py': '/tools/tooltool.py', 18:05:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:05:07 INFO - '/tools/misc-python/virtualenv.py')}, 18:05:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:05:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:05:07 INFO - 'installer_path': 'installer.exe', 18:05:07 INFO - 'log_level': 'info', 18:05:07 INFO - 'log_name': 'talos', 18:05:07 INFO - 'log_to_console': True, 18:05:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:05:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:05:07 INFO - 'opt_config_files': (), 18:05:07 INFO - 'pip_index': False, 18:05:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:05:07 INFO - 'cmd': ('bash', 18:05:07 INFO - '-c', 18:05:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:05:07 INFO - 'enabled': True, 18:05:07 INFO - 'halt_on_failure': False, 18:05:07 INFO - 'name': 'check_screen_resolution'},), 18:05:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:05:07 INFO - 'cmd': ('bash', 18:05:07 INFO - '-c', 18:05:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:05:07 INFO - 'enabled': True, 18:05:07 INFO - 'halt_on_failure': False, 18:05:07 INFO - 'name': 'check_screen_resolution'},), 18:05:07 INFO - 'run_cmd_checks_enabled': True, 18:05:07 INFO - 'sps_profile': False, 18:05:07 INFO - 'sps_profile_interval': 0, 18:05:07 INFO - 'suite': 'dromaeojs', 18:05:07 INFO - 'system_bits': '32', 18:05:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:05:07 INFO - 'title': 't-yosemite-r7-0029', 18:05:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:05:07 INFO - 'use_talos_json': True, 18:05:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:05:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:05:07 INFO - 'work_dir': 'build'} 18:05:07 INFO - [mozharness: 2016-08-23 01:05:07.861003Z] Running clobber step. 18:05:07 INFO - Running pre-action listener: _resource_record_pre_action 18:05:07 INFO - Running main action method: clobber 18:05:07 INFO - rmtree: /builds/slave/test/build 18:05:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:05:09 INFO - Running post-action listener: _resource_record_post_action 18:05:09 INFO - [mozharness: 2016-08-23 01:05:09.988248Z] Finished clobber step (success) 18:05:09 INFO - [mozharness: 2016-08-23 01:05:09.988383Z] Running read-buildbot-config step. 18:05:09 INFO - Running pre-action listener: _resource_record_pre_action 18:05:09 INFO - Running main action method: read_buildbot_config 18:05:09 INFO - Using buildbot properties: 18:05:09 INFO - { 18:05:09 INFO - "project": "", 18:05:09 INFO - "product": "firefox", 18:05:09 INFO - "script_repo_revision": "production", 18:05:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:05:09 INFO - "repository": "", 18:05:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:05:09 INFO - "buildid": "20160822160116", 18:05:09 INFO - "pgo_build": "False", 18:05:09 INFO - "basedir": "/builds/slave/test", 18:05:09 INFO - "buildnumber": 40, 18:05:09 INFO - "slavename": "t-yosemite-r7-0029", 18:05:09 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:05:09 INFO - "platform": "yosemite_r7", 18:05:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:05:09 INFO - "branch": "mozilla-central", 18:05:09 INFO - "repo_path": "mozilla-central", 18:05:09 INFO - "revision": "24763f58772d45279a935790f732d80851924b46", 18:05:09 INFO - "stage_platform": "macosx64", 18:05:09 INFO - "builduid": "64502076e4324486ad1921174204d08b", 18:05:09 INFO - "slavebuilddir": "test" 18:05:09 INFO - } 18:05:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:05:09 INFO - Running post-action listener: _resource_record_post_action 18:05:09 INFO - [mozharness: 2016-08-23 01:05:09.991707Z] Finished read-buildbot-config step (success) 18:05:09 INFO - [mozharness: 2016-08-23 01:05:09.991845Z] Running download-and-extract step. 18:05:09 INFO - Running pre-action listener: _resource_record_pre_action 18:05:09 INFO - Running main action method: download_and_extract 18:05:09 INFO - mkdir: /builds/slave/test/build/tests 18:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:09 INFO - trying https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:05:09 INFO - Downloading https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:11 INFO - Downloaded 1199 bytes. 18:05:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:05:11 INFO - Using the following test package requirements: 18:05:11 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:05:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:05:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:05:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'jsshell-mac.zip'], 18:05:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:05:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:05:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:05:11 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:05:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:05:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:05:11 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:05:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:05:11 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')]} 18:05:11 INFO - trying https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:05:11 INFO - Downloading https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:12 INFO - Downloaded 16352445 bytes. 18:05:12 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:05:19 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 18:05:19 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')]} 18:05:19 INFO - trying https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:05:19 INFO - Downloading https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:20 INFO - Downloaded 16352445 bytes. 18:05:20 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:05:27 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')]} 18:05:27 INFO - trying https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:05:27 INFO - Downloading https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:28 INFO - Downloaded 14413981 bytes. 18:05:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:05: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')]} 18:05:28 INFO - trying https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:05:28 INFO - Downloading https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:05:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/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 18:05:31 INFO - Downloaded 111479455 bytes. 18:05:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:05:31 INFO - mkdir: /builds/slave/test/properties 18:05:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:05:31 INFO - Writing to file /builds/slave/test/properties/build_url 18:05:31 INFO - Contents: 18:05:31 INFO - build_url:https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:05:31 INFO - Running post-action listener: _resource_record_post_action 18:05:31 INFO - Running post-action listener: set_extra_try_arguments 18:05:31 INFO - [mozharness: 2016-08-23 01:05:31.894927Z] Finished download-and-extract step (success) 18:05:31 INFO - [mozharness: 2016-08-23 01:05:31.895083Z] Running populate-webroot step. 18:05:31 INFO - Running pre-action listener: _resource_record_pre_action 18:05:31 INFO - Running main action method: populate_webroot 18:05:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:05:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:05:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:05:31 INFO - '--tppagecycles', 18:05:31 INFO - '7'], 18:05:31 INFO - 'tests': ['tsvgm']}}, 18:05:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:05:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:05:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:05:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:05:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['damp', 'tps']}, 18:05:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'tests': ['damp', 'tps']}, 18:05:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['dromaeo_dom']}, 18:05:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:05:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['basic_compositor_video']}, 18:05:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:05:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['a11yr', 18:05:31 INFO - 'ts_paint', 18:05:31 INFO - 'tpaint', 18:05:31 INFO - 'sessionrestore', 18:05:31 INFO - 'sessionrestore_no_auto_restore', 18:05:31 INFO - 'tabpaint']}, 18:05:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:05:31 INFO - 'ts_paint', 18:05:31 INFO - 'tpaint', 18:05:31 INFO - 'sessionrestore', 18:05:31 INFO - 'sessionrestore_no_auto_restore', 18:05:31 INFO - 'tabpaint']}, 18:05:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:05:31 INFO - 'ts_paint', 18:05:31 INFO - 'tpaint', 18:05:31 INFO - 'sessionrestore', 18:05:31 INFO - 'sessionrestore_no_auto_restore', 18:05:31 INFO - 'tabpaint']}, 18:05:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:05:31 INFO - 'ts_paint', 18:05:31 INFO - 'tpaint', 18:05:31 INFO - 'sessionrestore', 18:05:31 INFO - 'sessionrestore_no_auto_restore', 18:05:31 INFO - 'tabpaint']}, 18:05:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['a11yr', 18:05:31 INFO - 'ts_paint', 18:05:31 INFO - 'tpaint', 18:05:31 INFO - 'sessionrestore', 18:05:31 INFO - 'sessionrestore_no_auto_restore', 18:05:31 INFO - 'tabpaint']}, 18:05:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['a11yr', 18:05:31 INFO - 'ts_paint', 18:05:31 INFO - 'tpaint', 18:05:31 INFO - 'sessionrestore', 18:05:31 INFO - 'sessionrestore_no_auto_restore', 18:05:31 INFO - 'tabpaint']}, 18:05:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['tsvgx', 18:05:31 INFO - 'tsvgr_opacity', 18:05:31 INFO - 'tart', 18:05:31 INFO - 'tscrollx', 18:05:31 INFO - 'cart']}, 18:05:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:05:31 INFO - 'tsvgr_opacity', 18:05:31 INFO - 'tart', 18:05:31 INFO - 'tscrollx', 18:05:31 INFO - 'cart']}, 18:05:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'talos_options': ['--disable-e10s'], 18:05:31 INFO - 'tests': ['tp5o']}, 18:05:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'tests': ['tp5o']}, 18:05:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'talos_options': ['--disable-e10s', 18:05:31 INFO - '--xperf_path', 18:05:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:31 INFO - 'tests': ['tp5n']}, 18:05:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:31 INFO - 'talos_options': ['--xperf_path', 18:05:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:31 INFO - 'tests': ['tp5n']}}, 18:05:31 INFO - 'talos.zip': {'path': '', 18:05:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:05:31 INFO - Running post-action listener: _resource_record_post_action 18:05:31 INFO - [mozharness: 2016-08-23 01:05:31.915261Z] Finished populate-webroot step (success) 18:05:31 INFO - [mozharness: 2016-08-23 01:05:31.915395Z] Running create-virtualenv step. 18:05:31 INFO - Running pre-action listener: _resource_record_pre_action 18:05:31 INFO - Running main action method: create_virtualenv 18:05:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:05: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 18:05:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:05:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:05:32 INFO - Using real prefix '/tools/python27' 18:05:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:05:32 INFO - Installing distribute.............................................................................................................................................................................................done. 18:05:35 INFO - Installing pip.................done. 18:05:35 INFO - Return code: 0 18:05:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05: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 18:05: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 18:05:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:35 INFO - 'HOME': '/Users/cltbld', 18:05:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:35 INFO - 'LOGNAME': 'cltbld', 18:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:35 INFO - 'MOZ_NO_REMOTE': '1', 18:05:35 INFO - 'NO_EM_RESTART': '1', 18:05:35 INFO - 'PAGER': '/bin/cat', 18:05:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:35 INFO - 'PWD': '/builds/slave/test', 18:05:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:35 INFO - 'SHELL': '/bin/bash', 18:05:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:35 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:35 INFO - 'USER': 'cltbld', 18:05:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:35 INFO - 'XPC_FLAGS': '0x0', 18:05:35 INFO - 'XPC_SERVICE_NAME': '0', 18:05:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:36 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 18:05:36 INFO - Cleaning up... 18:05:36 INFO - Return code: 0 18:05:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:36 INFO - 'HOME': '/Users/cltbld', 18:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:36 INFO - 'LOGNAME': 'cltbld', 18:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:36 INFO - 'MOZ_NO_REMOTE': '1', 18:05:36 INFO - 'NO_EM_RESTART': '1', 18:05:36 INFO - 'PAGER': '/bin/cat', 18:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:36 INFO - 'PWD': '/builds/slave/test', 18:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:36 INFO - 'SHELL': '/bin/bash', 18:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:36 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:36 INFO - 'USER': 'cltbld', 18:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:36 INFO - 'XPC_FLAGS': '0x0', 18:05:36 INFO - 'XPC_SERVICE_NAME': '0', 18:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:36 INFO - Downloading/unpacking psutil>=3.1.1 18:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:39 INFO - Installing collected packages: psutil 18:05:39 INFO - Running setup.py install for psutil 18:05:39 INFO - building 'psutil._psutil_osx' extension 18:05: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 18:05:40 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 18:05:40 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 18:05: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 18:05:40 INFO - building 'psutil._psutil_posix' extension 18:05: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 18:05:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:05:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:05:40 INFO - ^ 18:05:40 INFO - 1 warning generated. 18:05: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 18:05:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:40 INFO - Successfully installed psutil 18:05:40 INFO - Cleaning up... 18:05:40 INFO - Return code: 0 18:05:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05: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 18:05: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 18:05:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:40 INFO - 'HOME': '/Users/cltbld', 18:05:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:40 INFO - 'LOGNAME': 'cltbld', 18:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:40 INFO - 'MOZ_NO_REMOTE': '1', 18:05:40 INFO - 'NO_EM_RESTART': '1', 18:05:40 INFO - 'PAGER': '/bin/cat', 18:05:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:40 INFO - 'PWD': '/builds/slave/test', 18:05:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:40 INFO - 'SHELL': '/bin/bash', 18:05:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:40 INFO - 'USER': 'cltbld', 18:05:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:40 INFO - 'XPC_FLAGS': '0x0', 18:05:40 INFO - 'XPC_SERVICE_NAME': '0', 18:05:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:05:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:05:43 INFO - Installing collected packages: mozsystemmonitor 18:05:43 INFO - Running setup.py install for mozsystemmonitor 18:05:43 INFO - Successfully installed mozsystemmonitor 18:05:43 INFO - Cleaning up... 18:05:43 INFO - Return code: 0 18:05:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05: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 18:05: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 18:05:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:43 INFO - 'HOME': '/Users/cltbld', 18:05:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:43 INFO - 'LOGNAME': 'cltbld', 18:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:43 INFO - 'MOZ_NO_REMOTE': '1', 18:05:43 INFO - 'NO_EM_RESTART': '1', 18:05:43 INFO - 'PAGER': '/bin/cat', 18:05:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:43 INFO - 'PWD': '/builds/slave/test', 18:05:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:43 INFO - 'SHELL': '/bin/bash', 18:05:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:43 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:43 INFO - 'USER': 'cltbld', 18:05:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:43 INFO - 'XPC_FLAGS': '0x0', 18:05:43 INFO - 'XPC_SERVICE_NAME': '0', 18:05:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:43 INFO - Downloading/unpacking jsonschema==2.5.1 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:46 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:46 INFO - warning: no files found matching '*.txt' 18:05:46 INFO - no previously-included directories found matching 'build' 18:05:46 INFO - no previously-included directories found matching 'dist' 18:05:46 INFO - no previously-included directories found matching '.git*' 18:05:46 INFO - Installing collected packages: jsonschema, functools32 18:05:46 INFO - Running setup.py install for functools32 18:05:46 INFO - warning: no files found matching '*.txt' 18:05:46 INFO - no previously-included directories found matching 'build' 18:05:46 INFO - no previously-included directories found matching 'dist' 18:05:46 INFO - no previously-included directories found matching '.git*' 18:05:46 INFO - Successfully installed jsonschema functools32 18:05:46 INFO - Cleaning up... 18:05:47 INFO - Return code: 0 18:05:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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', '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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:05: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 functools32==3.2.3-2 18:05:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:47 INFO - 'HOME': '/Users/cltbld', 18:05:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:47 INFO - 'LOGNAME': 'cltbld', 18:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:47 INFO - 'MOZ_NO_REMOTE': '1', 18:05:47 INFO - 'NO_EM_RESTART': '1', 18:05:47 INFO - 'PAGER': '/bin/cat', 18:05:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:47 INFO - 'PWD': '/builds/slave/test', 18:05:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:47 INFO - 'SHELL': '/bin/bash', 18:05:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:47 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:47 INFO - 'USER': 'cltbld', 18:05:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:47 INFO - 'XPC_FLAGS': '0x0', 18:05:47 INFO - 'XPC_SERVICE_NAME': '0', 18:05:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:05:47 INFO - Cleaning up... 18:05:47 INFO - Return code: 0 18:05:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05: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 18:05: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 18:05:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:47 INFO - 'HOME': '/Users/cltbld', 18:05:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:47 INFO - 'LOGNAME': 'cltbld', 18:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:47 INFO - 'MOZ_NO_REMOTE': '1', 18:05:47 INFO - 'NO_EM_RESTART': '1', 18:05:47 INFO - 'PAGER': '/bin/cat', 18:05:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:47 INFO - 'PWD': '/builds/slave/test', 18:05:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:47 INFO - 'SHELL': '/bin/bash', 18:05:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:47 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:47 INFO - 'USER': 'cltbld', 18:05:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:47 INFO - 'XPC_FLAGS': '0x0', 18:05:47 INFO - 'XPC_SERVICE_NAME': '0', 18:05:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:47 INFO - Downloading/unpacking blobuploader==1.2.4 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:49 INFO - Downloading blobuploader-1.2.4.tar.gz 18:05:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:05:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:05:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:51 INFO - Downloading docopt-0.6.1.tar.gz 18:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:05:51 INFO - Installing collected packages: blobuploader, requests, docopt 18:05:51 INFO - Running setup.py install for blobuploader 18:05:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:05:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:05:51 INFO - Running setup.py install for requests 18:05:51 INFO - Running setup.py install for docopt 18:05:52 INFO - Successfully installed blobuploader requests docopt 18:05:52 INFO - Cleaning up... 18:05:52 INFO - Return code: 0 18:05:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05: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 18:05: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 18:05:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:52 INFO - 'HOME': '/Users/cltbld', 18:05:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:52 INFO - 'LOGNAME': 'cltbld', 18:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:52 INFO - 'MOZ_NO_REMOTE': '1', 18:05:52 INFO - 'NO_EM_RESTART': '1', 18:05:52 INFO - 'PAGER': '/bin/cat', 18:05:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:52 INFO - 'PWD': '/builds/slave/test', 18:05:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:52 INFO - 'SHELL': '/bin/bash', 18:05:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:52 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:52 INFO - 'USER': 'cltbld', 18:05:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:52 INFO - 'XPC_FLAGS': '0x0', 18:05:52 INFO - 'XPC_SERVICE_NAME': '0', 18:05:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NF4wdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kVxFGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qgipZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EI3ByR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-HiRWFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:52 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xTAoRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xE3Y_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-FwrDAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-olIhyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zOzp7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-j4ScyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-78xngm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-T9IAaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ee2eZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OkdKOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kTsM4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TCysny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:54 INFO - Running setup.py install for manifestparser 18:05:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:54 INFO - Running setup.py install for mozcrash 18:05:54 INFO - Running setup.py install for mozdebug 18:05:54 INFO - Running setup.py install for mozdevice 18:05:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:54 INFO - Running setup.py install for mozfile 18:05:54 INFO - Running setup.py install for mozhttpd 18:05:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Running setup.py install for mozinfo 18:05:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Running setup.py install for mozInstall 18:05:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Running setup.py install for mozleak 18:05:55 INFO - Running setup.py install for mozlog 18:05:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Running setup.py install for moznetwork 18:05:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:55 INFO - Running setup.py install for mozprocess 18:05:55 INFO - Running setup.py install for mozprofile 18:05:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:56 INFO - Running setup.py install for mozrunner 18:05:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:56 INFO - Running setup.py install for mozscreenshot 18:05:56 INFO - Running setup.py install for moztest 18:05:56 INFO - Running setup.py install for mozversion 18:05:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:56 INFO - Cleaning up... 18:05:56 INFO - Return code: 0 18:05:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:05: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 18:05: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 18:05:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:05:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:56 INFO - 'HOME': '/Users/cltbld', 18:05:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:56 INFO - 'LOGNAME': 'cltbld', 18:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:56 INFO - 'MOZ_NO_REMOTE': '1', 18:05:56 INFO - 'NO_EM_RESTART': '1', 18:05:56 INFO - 'PAGER': '/bin/cat', 18:05:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:56 INFO - 'PWD': '/builds/slave/test', 18:05:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:56 INFO - 'SHELL': '/bin/bash', 18:05:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:05:56 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:05:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:56 INFO - 'USER': 'cltbld', 18:05:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:56 INFO - 'XPC_FLAGS': '0x0', 18:05:56 INFO - 'XPC_SERVICE_NAME': '0', 18:05:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zJ49MS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-HUylal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xZsXun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZLwgWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-vr7sgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-UF1Y6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-itOPkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8fjuyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5Tn50C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05: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)) 18:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:57 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-aNJBTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05: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)) 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5gSI3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05: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)) 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-FjSFmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05: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)) 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wS8WGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05: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)) 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-EvYug5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05: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)) 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Si_oMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05: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)) 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-psI62Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05: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)) 18:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-rcRYSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05: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)) 18:05: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)) 18:05: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)) 18:05: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)) 18:05: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)) 18:05: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)) 18:05:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:06:01 INFO - Downloading blessings-1.6.tar.gz 18:06:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:06:01 INFO - Installing collected packages: blessings 18:06:01 INFO - Running setup.py install for blessings 18:06:01 INFO - Successfully installed blessings 18:06:01 INFO - Cleaning up... 18:06:01 INFO - Return code: 0 18:06:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:06:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:01 INFO - Reading from file tmpfile_stdout 18:06:01 INFO - Current package versions: 18:06:01 INFO - blessings == 1.6 18:06:01 INFO - blobuploader == 1.2.4 18:06:01 INFO - docopt == 0.6.1 18:06:01 INFO - functools32 == 3.2.3-2 18:06:01 INFO - jsonschema == 2.5.1 18:06:01 INFO - manifestparser == 1.1 18:06:01 INFO - mozInstall == 1.12 18:06:01 INFO - mozcrash == 0.17 18:06:01 INFO - mozdebug == 0.1 18:06:01 INFO - mozdevice == 0.48 18:06:01 INFO - mozfile == 1.2 18:06:01 INFO - mozhttpd == 0.7 18:06:01 INFO - mozinfo == 0.9 18:06:01 INFO - mozleak == 0.1 18:06:01 INFO - mozlog == 3.3 18:06:01 INFO - moznetwork == 0.27 18:06:01 INFO - mozprocess == 0.23 18:06:01 INFO - mozprofile == 0.28 18:06:01 INFO - mozrunner == 6.12 18:06:01 INFO - mozscreenshot == 0.1 18:06:01 INFO - mozsystemmonitor == 0.3 18:06:01 INFO - moztest == 0.7 18:06:01 INFO - mozversion == 1.4 18:06:01 INFO - psutil == 3.1.1 18:06:01 INFO - requests == 1.2.3 18:06:01 INFO - wsgiref == 0.1.2 18:06:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06: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')]} 18:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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')]} 18:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:06: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 18:06: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 18:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:01 INFO - 'HOME': '/Users/cltbld', 18:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:01 INFO - 'LOGNAME': 'cltbld', 18:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:01 INFO - 'MOZ_NO_REMOTE': '1', 18:06:01 INFO - 'NO_EM_RESTART': '1', 18:06:01 INFO - 'PAGER': '/bin/cat', 18:06:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:01 INFO - 'PWD': '/builds/slave/test', 18:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:01 INFO - 'SHELL': '/bin/bash', 18:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:06:01 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:01 INFO - 'USER': 'cltbld', 18:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:01 INFO - 'XPC_FLAGS': '0x0', 18:06:01 INFO - 'XPC_SERVICE_NAME': '0', 18:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06: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)) 18:06:02 INFO - Cleaning up... 18:06:02 INFO - Return code: 0 18:06:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:06: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')]} 18:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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')]} 18:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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 0x1034e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10357a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc81db6e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, '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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 18:06: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 18:06: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 18:06:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:06:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:02 INFO - 'HOME': '/Users/cltbld', 18:06:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:02 INFO - 'LOGNAME': 'cltbld', 18:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:02 INFO - 'MOZ_NO_REMOTE': '1', 18:06:02 INFO - 'NO_EM_RESTART': '1', 18:06:02 INFO - 'PAGER': '/bin/cat', 18:06:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:02 INFO - 'PWD': '/builds/slave/test', 18:06:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:02 INFO - 'SHELL': '/bin/bash', 18:06:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:06:02 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:06:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:02 INFO - 'USER': 'cltbld', 18:06:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:02 INFO - 'XPC_FLAGS': '0x0', 18:06:02 INFO - 'XPC_SERVICE_NAME': '0', 18:06:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:06:02 INFO - Cleaning up... 18:06:02 INFO - Return code: 0 18:06:02 INFO - Running post-action listener: _resource_record_post_action 18:06:02 INFO - Running post-action listener: _start_resource_monitoring 18:06:02 INFO - Starting resource monitoring. 18:06:02 INFO - [mozharness: 2016-08-23 01:06:02.455584Z] Finished create-virtualenv step (success) 18:06:02 INFO - [mozharness: 2016-08-23 01:06:02.456642Z] Running install step. 18:06:02 INFO - Running pre-action listener: _resource_record_pre_action 18:06:02 INFO - Running main action method: install 18:06:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:02 INFO - Reading from file tmpfile_stdout 18:06:02 INFO - Detecting whether we're running mozinstall >=1.0... 18:06:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:06:02 INFO - Reading from file tmpfile_stdout 18:06:02 INFO - Output received: 18:06:02 INFO - Usage: mozinstall [options] installer 18:06:02 INFO - Options: 18:06:02 INFO - -h, --help show this help message and exit 18:06:02 INFO - -d DEST, --destination=DEST 18:06:02 INFO - Directory to install application into. [default: 18:06:02 INFO - "/builds/slave/test"] 18:06:02 INFO - --app=APP Application being installed. [default: firefox] 18:06:02 INFO - mkdir: /builds/slave/test/build/application 18:06: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'] 18:06: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 18:06:36 INFO - Reading from file tmpfile_stdout 18:06:36 INFO - Output received: 18:06:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:06:36 INFO - Running post-action listener: _resource_record_post_action 18:06:36 INFO - [mozharness: 2016-08-23 01:06:36.729290Z] Finished install step (success) 18:06:36 INFO - [mozharness: 2016-08-23 01:06:36.729444Z] Running run-tests step. 18:06:36 INFO - Running pre-action listener: _resource_record_pre_action 18:06:36 INFO - Running main action method: run_tests 18:06:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:06:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:06:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:06:36 INFO - 2016-08-22 18:06:36.744 screenresolution[1643:7820] starting screenresolution argv=screenresolution get 18:06:36 INFO - 2016-08-22 18:06:36.747 screenresolution[1643:7820] Display 0: 1600x1200x32@60 18:06:36 INFO - 2016-08-22 18:06:36.753 screenresolution[1644:7822] starting screenresolution argv=screenresolution list 18:06:36 INFO - Available Modes on Display 0 18:06:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:06:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:06:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:06:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:06:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:06:36 INFO - Intel Iris: 18:06:36 INFO - Chipset Model: Intel Iris 18:06:36 INFO - Type: GPU 18:06:36 INFO - Bus: Built-In 18:06:36 INFO - VRAM (Dynamic, Max): 1536 MB 18:06:36 INFO - Vendor: Intel (0x8086) 18:06:36 INFO - Device ID: 0x0a2e 18:06:36 INFO - Revision ID: 0x0009 18:06:36 INFO - Displays: 18:06:36 INFO - Display: 18:06:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:06:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:06:36 INFO - Display Serial Number: 1600x1200 60 18:06:36 INFO - Main Display: Yes 18:06:36 INFO - Mirror: Off 18:06:36 INFO - Online: Yes 18:06:36 INFO - Rotation: Supported 18:06:36 INFO - Return code: 0 18:06:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:06:36 INFO - Python 2.7.3 18:06:36 INFO - Return code: 0 18:06:36 INFO - grabbing minidump binary from tooltool 18:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:36 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 0x103449d30>, '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 0x1031aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103441b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:06:36 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 18:06: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 18:06: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 18:06:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:06:37 INFO - Return code: 0 18:06:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:06:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:06:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:06:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:06:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:06:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 18:06:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 18:06:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RRSafDY7Ss/Render', 18:06:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:37 INFO - 'HOME': '/Users/cltbld', 18:06:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:37 INFO - 'LOGNAME': 'cltbld', 18:06:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:06:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:37 INFO - 'MOZ_NO_REMOTE': '1', 18:06:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:06:37 INFO - 'NO_EM_RESTART': '1', 18:06:37 INFO - 'PAGER': '/bin/cat', 18:06:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:37 INFO - 'PWD': '/builds/slave/test', 18:06:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:06:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:37 INFO - 'SHELL': '/bin/bash', 18:06:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JSCeJCgbLt/Listeners', 18:06:37 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 18:06:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:37 INFO - 'USER': 'cltbld', 18:06:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:37 INFO - 'XPC_FLAGS': '0x0', 18:06:37 INFO - 'XPC_SERVICE_NAME': '0', 18:06:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 18:06:37 INFO - mozversion application_buildid: 20160822160116 18:06:37 INFO - mozversion application_changeset: 24763f58772d45279a935790f732d80851924b46 18:06:37 INFO - mozversion application_display_name: Nightly 18:06:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:06:37 INFO - mozversion application_name: Firefox 18:06:37 INFO - mozversion application_remotingname: firefox 18:06:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:06:37 INFO - mozversion application_vendor: Mozilla 18:06:37 INFO - mozversion application_version: 51.0a1 18:06:37 INFO - mozversion platform_buildid: 20160822160116 18:06:37 INFO - mozversion platform_changeset: 24763f58772d45279a935790f732d80851924b46 18:06:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:06:37 INFO - mozversion platform_version: 51.0a1 18:06:37 INFO - using testdate: 1471914397 18:06:37 INFO - actual date: 1471914397 18:06:37 INFO - starting webserver on 'localhost:49269' 18:06:37 INFO - SUITE-START | Running 2 tests 18:06:37 INFO - TEST-START | dromaeo_css 18:06:37 INFO - operating with platform_type : mac_ 18:06:37 INFO - Initialising browser for dromaeo_css test... 18:06:37 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp5W71vM/profile http://localhost:49269/getInfo.html) 18:06:39 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 18:06:39 INFO - PROCESS | 1654 | colorDepth:24 18:06:39 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 18:06:39 INFO - PROCESS | 1654 | __metrics 18:06:39 INFO - PROCESS | 1654 | 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" 18:06:39 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:06:40 INFO - TEST-INFO | 1654: exit 0 18:06:40 INFO - Browser initialized. 18:06:40 INFO - Running cycle 1/1 for dromaeo_css test... 18:06:40 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp5W71vM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:52 INFO - PROCESS | 1656 | RSS: Main: 228352000 18:06:52 INFO - PROCESS | 1656 | 18:07:43 INFO - PROCESS | 1656 | 1471914463518 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] 18:09:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 18:09:57 INFO - PROCESS | 1656 | RSS: Main: 368467968 18:09:57 INFO - PROCESS | 1656 | 18:13:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 18:13:01 INFO - PROCESS | 1656 | RSS: Main: 374964224 18:13:01 INFO - PROCESS | 1656 | 18:16:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 18:16:04 INFO - PROCESS | 1656 | RSS: Main: 340803584 18:16:04 INFO - PROCESS | 1656 | 18:19:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 18:19:07 INFO - PROCESS | 1656 | RSS: Main: 329969664 18:19:07 INFO - PROCESS | 1656 | 18:22:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 18:22:11 INFO - PROCESS | 1656 | RSS: Main: 326668288 18:22:11 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 18:25:15 INFO - PROCESS | 1656 | RSS: Main: 378765312 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | __start_tp_report 18:25:15 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 18:25:15 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 18:25:15 INFO - PROCESS | 1656 | |i|pagename|runs| 18:25:15 INFO - PROCESS | 1656 | |0;dojo.html;3369.5451114099596;3399.2011877208865;3404.5744281964753;3420.162060295228;3434.8282585870716;5686.346070201928;5727.914081288774;5746.252987111675;5802.622829516084;5824.359320474744;5703.629264097831;5729.484346408823;5755.216007912053;5838.211841401409;5874.118882167677;5209.453007434203;5211.322528071345;5220.973895130519;5279.472052794709;5321.414644389114;5398.514133727964;5400.756965936523;5404.378496472917;5413.485718856722;5433.4566543345745;4407.911841763147;5172.27588137661;5371.301730775013;5385.542228910547;5397.217403476513;4602.539746025404;5051.621128415383;5268.367795864482;5283.2075188722;5294.867628309285;5212.687238765686;5217.060929032773;5234.057869583474;5270.947290527084;5278.419373868871;5802.5938184327115;5877.647341159544;5880.8235752927485;5892.911606325909;5926.170336152942;6155.261368635747;6229.6573688447115;6291;6306.684665766693;6315.305316415191;6115.113308570232;6239.438450539428;6266.436020758154;6269.96865015672;6272.090546870724;5981.611195272293;5996.1005849122985;5997.430244126801;6017;6017.548683848729;5331.600129990266;6113.083037544404;6225.128482012522;6283.7800676976085;6357.205349331333;6263.279722831841;6277.843053923689;6279.3720627937755;6283;6291.465225455888;6306.590071645328;6318.399752023551;6324.146240257598;6325;6328.240611126695;5070.239464080418;6064.939350606437;6104.20645316106;6286.308506064329;6294.622322660655;6158.661273629904;6161.7535298588455;6191;6224.470919971763;6228;5017.648764586517;5067.771950262247;5075.3402057732255;5078.923816142761;5083.364579427572;4448.733076015449;4545.113702827983;4569.657275704336;4594.95405045952;4595.724256544618;5774.191613174159;5782.959067367914;5801.564882633735;5802.680852553066;5808;6224.470919971853;6255.155554000243;6269.937300626935;6278.466330361879;6284.780032698834;5881.852953676193;5908.586398952118;5914.76340946366;5918.585699001114;5921.111833225051;5354.7858085676135;5397.136458166675;5454.481824226692;5470.617056806065;5529.391766905637;5954.660201454638;6241.157443745127;6342.619442833445;6345.333739957238;6349.396807303298;5528.7235638218735;6275.184226050585;6278.968605156945;6284.717187726472;6284.748610055546;6201.162843016135;6206.627602343874;6237.2309828423;6253.249610046823;6318.652474113784;6147.846303842261;6247.125402443752;6258.280297765815;6262.499000079892;6292.27638821523;6083.817485475266;6245.656488893154;6249.25008998923;6253.530985176039;6257.53068519872;6047.606905551125;6114.38856114385;6229.937700622936;6232.283287422005;6256.968715156395;6117.877642447037;6185.876282474235;6220.471259942775;6261.56169068179;6269.529785266032;6105.053716673922;6136.723847426966;6139.9079013813935;6154.5384096192065;6160.845978850564;6038.486728628116;6122.969385153046;6233.2208473940755;6246.063090536456;6264.749410023548;5995.2206213192;6094.695265236632;6112.327643959072;6124.0201567748945;6133 18:25:15 INFO - PROCESS | 1656 | |1;ext.html;478.2228878073131;482.6910777102654;523.5811350919266;540.8053100883682;553.1122548309964;31076.689233107663;41866.65066794657;42854.14291714164;43101.353479697755;44940.77529612355;43943.340849887194;44202.33696494555;44276.77861610695;44292.33561496572;44304;9751.51242437877;9914.702558923243;9921.35511191774;9925.320163555836;9953.452560109192;9844.310898237127;9965.202783777317;9966.65116720915;9984.700458986243;9988.700338989842;9926.553305101252;9958.452285124315;9990.300678952477;9991.500424978758;10000.199984001263;9915.553800079013;9963.80072398548;9976.251781116445;9984.70045898617;9985.90014099857;9684.56419461126;9736.318457707963;9757.804843903083;9759.804803903953;9774.5601447935;41846.37230441546;42084.368734469004;42091;42123.57876421258;42182.78908605468;3329.93340133196;3449.2066824630224;3638.599754027055;3983.6813054955533;4294.656427485794;24480.755192448025;32753.344933101205;32778.67221327804;32785.6721432785;32890.848820291176;3252.96747032529;3265.526498657705;3418.7094097001786;3442.0534353053113;3446.4141096013736;503413;504586;504794;506024;512454.9999999963;60959.39040609537;61217.69391153016;61830.96197494297;61891.45271368162;61920;65084.674576626814;65113.34886651073;65150;65168;65288.34711652918;16519.587010324598;16728.916355418387;16747.162641867857;16780.328786848633;16782.58043548921;10876.673699789018;12890.226586404846;12991.415386307639;12995.350232488527;13010.479580816662;17101.401450949405;17716.40552350269;17738.846974946588;17763;17807.46577602674;6274.686265686697;6287.874242515124;6319.52603554726;6356.2054743157105;6372.362763723683;830.8836762853206;850.995745021271;981.5632043740467;1016.5171543516918;1081.8214994525972;29408;29850.701492985227;30215.244618884703;30388;30975.380492390024;990.4651488196438;1014.3710899242517;1029.6460154896288;1065.8294672852307;1121.159130652011;14830.819243195945;15014.774778378332;15697.607559810867;15752.842471575366;15841;628467;633747;633782;635164;635974;59307.703461482415;59332;59639;59681.701591491765;60111.89608363774;1226.5400474821943;1346.2461021827808;1394.3864699532173;1412.5691664042565;1415.8956014308649;30763.230919226302;31888.68111318857;32048.67951320457;32660.34679306448;32763.836180818944;52383.73808130935;52906.470935290155;53121;53158.936821264135;53236.46763532315;528707;530163;531011;531648;532836;26798.19605411841;26804.731952680224;27649.170524884285;27738.722612773614;28230.576541352308;48482.5151748478;48792.26811597758;49847.75076124596;49995.500044999084;50128.498715012385;40222;40555.39166912558;41103.58896410998;41718.374224386054;41957.79021104875;39812.805615831574;39947.80026099851;40469;41271.7936410316;42006.789966049975;39035.80482097571;39343.40984885172;39864.800675996434;39900.80049599733;39985;32034;34982.47526287159;35590.110247244025;35659.643403565635;35766.46350304696;22608.886955565118;23229;25806;25829.008576465927;26087.347816303984 18:25:15 INFO - PROCESS | 1656 | |2;jquery.html;348.1869833937755;364.8941806876005;368.64609974424553;375.8402678861484;400.507375927609;15165.090094594332;16504;19996;20563.177472901065;20748.27381041662;20459.386218413434;20732.8963355183;20813.063412146454;21086;21119.472013199676;5739.971300143494;5843.474087332139;5930.288365396137;6088.147659327676;6090.390960903918;4112.218678451085;5947.464728174464;6048.2137322148055;6051.788187413442;6056.091586262052;4319.460067491564;4349.282368409209;4351.129774045204;5386;6620.106285651447;5773.740181691834;6093.664848433335;6594.736210551572;6622.53642245043;6632.966835165817;5976.193213916109;5977.521798256173;5982.700864956778;6003.009503431928;6005.789797357072;13721.725565488634;24838.503229935483;25104.748952510425;25640.615390769155;25899.870500647565;2167.56648670265;2193.0679461229074;2341.8711970841596;2363.929082127539;2436.5857804173156;9240.21458176049;9370.53147342637;9965.140460504977;10236.232282578836;13510.324483775772;1613.0805440898691;1664.9001059936322;1799.9910000450045;1921.9615607687767;1925.075963537496;110298.3455248172;116413;116818;118860;120103;11068.667939961813;11126.944365278121;11164.330140191614;11288.943555282336;11350.48922798476;14188.503402380868;14188.574342769733;14192.432302707775;14207.644808879653;14238.14571125715;6956.965215173993;8262.586870656443;8390.538520381439;8415.368847336475;8465.788355291166;8419.158084191531;8456.957715211385;8608.956955215184;8612.827743445096;8637.654493820302;4343.69594128408;4443.4445694288215;4444.777761111932;4527.7509736964785;4547.113312903952;1690.3407671008317;1805.2508209093342;1823.2251293200336;1862.2862196782357;1887.1790771014653;851.8424091543085;918.7014220378404;942.2697409507687;951.7573018880141;984.1192133040888;17788.91105544464;19295.710564341545;19795.30716424918;21166.894165529073;21241.78758212398;908.487368531401;943.6272672294406;953.3374304858196;963.2582911158369;981.5877331520778;9560.808783824423;10864.728381790519;11224.438778061065;11234.831477527843;11326.377049262204;575440;585822;589279;598326;599025;94173;119678;121259.39370303092;121305.78694213122;131228.3438582801;960.356561104048;995.0696099147176;1033.5710680067687;1034.7826956339254;1035.1201478743008;18610.534736631693;19076.42770716881;19309.42071737794;19325.613487730447;19422.83462992225;52891;53596.464035359146;54326.728366357915;54464;54558.727206363714;50425.23511677074;64731.49346916506;72610.27389726035;78209;78362;7653.311201991847;7819.374450044096;7860.174681658572;7979.202079792207;8227.629756660845;11203.775924481628;11804.409779511161;12559.686007849878;12868.742625147257;12871.742565148832;9406.905930940604;10905.672829815117;11183.776324473627;11209.383483908463;11251.324920504796;10737.46312684347;10755.408452535186;10948.781024379627;11151.330920144817;11222.831657525307;11029.338239705643;11036.172287078341;11184.440777961232;11226.382548959886;11265.380404077854;7836.490628109154;7840.882386764317;8205.753827385188;8272.131426200403;8307.543085130375;9052.23906708434;10067.546960386946;11193.888061119285;11322.547098115983;11528.884711152781 18:25:15 INFO - PROCESS | 1656 | |3;mootools.html;1532.4176812811133;1552.74379727345;1764.9823501764986;1898.848092152628;1926.4798504403802;8321.375896807742;8820.999999999993;8852.911470885289;8859.778505537364;8865.822683546334;6473.5144864135145;7995.160508146633;8342.499450033003;8595.441296315736;8656.523891185981;2325.883705814707;2386.8209884258663;2387.069043073203;2388.2835149455095;2392.0431827269144;2331.6152834782242;2353.9882300588474;2380.27401642499;2385.642153676945;2386.319898828834;1981.7324661170774;2365.9645105323434;2372.6915500984846;2381.9166329178483;2387.785099341059;2328.0804082387363;2358.127492827656;2360.303710405426;2363.0075368345338;2367.0295178976535;2351.1888398502488;2355.6348765941307;2356.787889089973;2358.50471401006;2364.775346342112;8672.349573781992;8691.826163476759;8758.255548278405;8776.034636190014;8797.340199485065;5069.721165335905;5226.686398816083;5312.229726689647;5314.681119132826;5333.786648534054;3054.373853360078;3448.2413868949043;3660.2130541933348;3689.262147570497;3698.8890333290046;1070.218740319571;1105.6130354375985;1105.9778804423947;1128.3060917535656;1129.6949823547595;11519.193656444053;11552.942235288854;11573.421328933604;11597.536098556047;11690.240134391237;4492.73043617384;4575.633949284049;4770.594499467584;4773.093112308719;4794.2089555223;9785.510724463747;9794;9795.412275263365;9811.75470613226;9821.508924553744;5306.8407947761625;5320.600954928318;5320.733963301896;5323.334583177103;5329.014132385521;4357.411749413852;4524.660650451164;4693.108309421199;4828.493008234155;4878.170710979142;3303.702666760003;3810.847566097325;3874.5931677174053;3967.285888540033;3979.164375481181;1261.375619068567;1269.5620011096157;1272.5864094169433;1288.4588472841428;1291.761024210524;883.2536506651869;924.5977999570208;925.2021096587174;926.1016813690709;957.7078990907701;5409.323834520685;5600.159976003632;5677.744501497443;5908.113782932594;6031.758729650853;400.9659178969762;442.78967490441784;446.81233881769737;1004.8090862736203;1013.9695809125591;3798;3889.241597888441;3950.627087603676;4038.374052021941;4077.062275676611;9717.17404020666;9938;10149.594016239416;10619.94690026545;10644.361338319726;10560.366378017343;10597.20520960931;10602.628907988183;10605.204609654307;10626.043656070991;7140.250273721184;7936.3254123399;8014.278714915569;8164;8181.877271840927;4546.272596384562;5712;5724.313082429969;5739.598228123991;5953.910691339547;9528.333016689043;9683.806323873343;9865.950670246602;9868.309218354647;9974.55114519863;9876.950615246878;9897.802043959224;10170.338927969658;10198.838360108093;10298.536565854405;6863.382295593221;7114.181869085121;7281.344678978918;7370.852582948204;7566.886496702664;8201.09787923316;8410.663573456994;8424.199701028392;8429.957850210709;8430.915690843258;7386.261373862786;7480.551166930001;7540.660670269741;7569.5079819811535;7657.119431265476;7241.275872412927;7263.673134708845;7335.669894854637;7377.631118443949;7461.067366579177;6812.557183783038;6952.374286314053;6970.302969702989;6976.825579360354;6991.091158149408;4897.9020419592125;4938.135826230324;5016.322796422436;5094.2358646203365;5135.35808023997;4843.24929635907;4991.878451658104;5070.721110338966;5094.592432605308;5206.791728330824 18:25:15 INFO - PROCESS | 1656 | |4;prototype.html;298.7012987012987;305.56151921991943;306.4545109704725;312.9327194653151;314.460699899672;5371.803776752836;5373.086575282202;5378.704171270579;5380.7309634518215;5395.298611180541;4618.261078227484;4833.347498087766;5308.230306605549;5351.866203344918;5386.787972706139;3805.6384643458896;3873.4189871519216;3973.801309934506;4098.4057311689785;4132.008318003673;3339.231976645373;3606.7178867230823;4033.657139143176;4060.9593904061026;4082.9591704082877;4033.455483509718;4040.5454036546967;4059.4722686050773;4069.450624165744;4104.835806567734;3787.507624008889;3981.7212795104356;4004.0790618157694;4081.469408976829;4086.9386959195635;3432.3478539077496;3435.862565497402;3444.93207105798;3450.8447119879415;3460.2387474755515;5099.603186502375;5120.590352771769;5123.974380128076;5140.278006867704;5151.896962060775;4226.4716910386205;4504.977475112636;4509.075639493896;4524.524924882873;4535.960906192859;4467.642588592905;4801.8079276828885;4815.410914398275;5009.223570346602;5302.310699609026;3785.5835858055866;3787.6780473659774;3791.696664266852;3809.866654667101;3818.5417749870194;5310.973445132789;5489.549779009728;5719.82840514781;5836.854078648068;5916;3368.6693755966744;3793.4499497572697;3846.1922996171115;3851.4993050903763;3851.576326604127;3891.5330160380936;3900.414937759359;3902.492675952108;3911.4745249352777;3926.3521518949055;4538.72767633943;4559.931601025921;4560.6807523473035;4578.267477203632;4610.40064791575;4122.175564887035;4757.405324334458;5131.666441681279;5496.862578435571;5636.633618814764;1029.25893356783;1300.78537041389;1326.6683329167709;1332.2872263339068;1340.9128406653535;691.0567075941404;790.213737331359;810.2748040503716;818.6111596991382;824.3981893217984;4367.606915377632;5043.218301163325;5103.974480127576;5227.2420499028185;5264.815731449381;4946.455889852114;5230.503102205284;5366.087765079946;5743.84890810943;5751.654900705971;4681.368015317888;4735.502772208937;5165.527824569979;5261.5264626183825;5308.840734777963;2730.4675588260097;2953.394554116401;2999.685033071496;2999.735199924043;3009.307859192398;5621.8594535136135;5902.675352855634;5913.438223368773;5930.28836539618;5934.940650593525;5456.836294911159;5807.656955600857;5863.149843272701;5873.676947767828;5896.439838215449;5863.035882979249;5865.445656900924;5891.086881533369;5903.7048147593305;5922.674252915958;5363.570914326922;5716.28546431696;5716.399778023248;5744.827655170351;5755.172810081249;5336.78652853897;5678.290213723285;5692.686902220417;5729.914051289151;5762.8847423050465;5961.850953726018;5989;5989.401059893976;5999.940000600112;6029.60807547508;3238.70851623355;3820.5481916871413;3919.412088186795;3956.7428117173445;4003.659688926474;4962.784117891139;5560.833175004756;5759.337676167294;5765.538756899522;5776.855578610568;5636.802711905064;5661.773529058792;5669.886602268014;5689.630174038674;5702.800401985911;5184.844454666366;5350.5184533392185;5558.555315574825;5621.578381621313;5666.413404246818;5309.814156504503;5462.787571033738;5529.22590837275;5530.364008139116;5533.335999680065;4919.631027672867;5266.473352664703;5425.83722488326;5526.392096869422;5624.128260119688;4639.652026097989;5452.2094296327605;5476.17857321405;5513.476219759116;5523.6800543772515 18:25:15 INFO - PROCESS | 1656 | |5;yui.html;715.6922523314975;716.8566286742649;775.2596270561614;829.6391069884598;833.791552111972;15220.77168842467;16211.75682364764;16262.94290871094;16282.348706051773;16297.266623001964;14719.484818031371;14892;14894.255287235648;14918;14955;679.7281087564988;703.2088899987514;826.5206180415374;835.3233880556769;858.5792961448876;841.5623875584693;856.7643897928058;860.2300940658107;861.8233262181269;862.211076864668;913.4656226107702;913.963441462344;913.9862902056475;915.1717695485596;917.7705573606598;720.9711611535532;743.0971369785736;749.2282948562989;908.9727308180766;909.1771946388529;543.4375421438831;545.076096017251;650.1320736816356;668.3617145625916;675.0111087257192;14627.341769620287;14646.487372942003;14651.194184319858;14663.853361466356;14696.971212015163;13692.31538423075;13880.861191388058;14014.29928503581;14085.929570352186;14131;11683.7079073023;12418.937905310415;12632.368381580976;12839.422225999853;13034.739305213938;3446.4657978013447;3887.436321733333;4010.5187377514594;4079.7552146870985;4124.731892427013;111861;113860.43069784598;117074;117769;119439;11587.362695051854;11667.35829529367;11702.824457633142;11825.349605771766;12010.579629712918;24111.51776964451;24187.637185442232;24193.63709544358;24455.63316550253;24526.14158504444;6908.689108990107;7888.7633370998965;7890.329322007694;8090.635921383434;8265.800649512865;4475.731456112644;4650.4186976627925;4715.811367545329;4753.096911586787;4759.95281038178;3669.3578623740736;3782.6217378261954;3784.526934133233;3785.9432108518395;3789.4315852621776;669.9162604674416;671.1509939925998;680.1498127340824;695.8573492434039;696.2271878215178;528;611.9169070744757;653.4772182254178;694.9861002779916;715.9856802864018;9219.216366608913;9433.302704646188;9543.33196676226;9579.808403831885;9738.513074346254;517.4437479709252;593.631948192115;612.9540284478682;651.5797310734549;721.9241979592172;4520.299353600197;4580.839670611579;4710.764461776897;4732.195526760459;4850.175470170079;87250.27497250224;139427;141924.29037854745;144035;144249.27875360765;125397;126922;129369.05946410811;130938;132346.33826830806;99727.00818975443;106795;107262.99999999844;107470.46264768782;112232;7993.760187194393;8772.649094036167;9231.399959002913;9313.39462934907;9440.386374885611;55044.34866953998;57922.42077579339;61151.082733758136;62578;66002.66998664977;71146.57706845937;87301;91432.05691630022;92543.53728231585;93947;11840.526378945091;11880.76238475243;11979.28124312544;12039.699007524881;12494.500219991462;13265.933670331586;13334.595701635944;13442.798358024442;13656.863431365957;13699.589012329647;11012.39431831257;11138.3873886937;12340.691482713004;12968.28674422878;13010.739785204432;13663.316834158451;13684.3842027111;14280.642983925485;14307.284635768377;14450.783238251224;13156.934215328862;13599.79600306016;13627.931860341032;13708.86291137076;14129.717405652034;10920.453977300944;11116.221864469733;11180.161487888277;11574.71063223426;11693.707657308296;11124.165687573302;11254.887451125383;11388.715282118013;11485.253458525169;11498.942505287754 18:25:15 INFO - PROCESS | 1656 | __end_tp_report 18:25:15 INFO - PROCESS | 1656 | __start_cc_report 18:25:15 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1534 18:25:15 INFO - PROCESS | 1656 | __end_cc_report 18:25:15 INFO - PROCESS | 1656 | __startTimestamp1471915515302__endTimestamp 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | ------- Summary: start ------- 18:25:15 INFO - PROCESS | 1656 | Number of tests: 6 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5793.39 Median:6064.94 stddev:637.52 (10.5%) stddev-sans-first:610.46 18:25:15 INFO - PROCESS | 1656 | Values: 3369.5 3399.2 3404.6 3420.2 3434.8 5686.3 5727.9 5746.3 5802.6 5824.4 5703.6 5729.5 5755.2 5838.2 5874.1 5209.5 5211.3 5221.0 5279.5 5321.4 5398.5 5400.8 5404.4 5413.5 5433.5 4407.9 5172.3 5371.3 5385.5 5397.2 4602.5 5051.6 5268.4 5283.2 5294.9 5212.7 5217.1 5234.1 5270.9 5278.4 5802.6 5877.6 5880.8 5892.9 5926.2 6155.3 6229.7 6291.0 6306.7 6315.3 6115.1 6239.4 6266.4 6270.0 6272.1 5981.6 5996.1 5997.4 6017.0 6017.5 5331.6 6113.1 6225.1 6283.8 6357.2 6263.3 6277.8 6279.4 6283.0 6291.5 6306.6 6318.4 6324.1 6325.0 6328.2 5070.2 6064.9 6104.2 6286.3 6294.6 6158.7 6161.8 6191.0 6224.5 6228.0 5017.6 5067.8 5075.3 5078.9 5083.4 4448.7 4545.1 4569.7 4595.0 4595.7 5774.2 5783.0 5801.6 5802.7 5808.0 6224.5 6255.2 6269.9 6278.5 6284.8 5881.9 5908.6 5914.8 5918.6 5921.1 5354.8 5397.1 5454.5 5470.6 5529.4 5954.7 6241.2 6342.6 6345.3 6349.4 5528.7 6275.2 6279.0 6284.7 6284.7 6201.2 6206.6 6237.2 6253.2 6318.7 6147.8 6247.1 6258.3 6262.5 6292.3 6083.8 6245.7 6249.3 6253.5 6257.5 6047.6 6114.4 6229.9 6232.3 6257.0 6117.9 6185.9 6220.5 6261.6 6269.5 6105.1 6136.7 6139.9 6154.5 6160.8 6038.5 6123.0 6233.2 6246.1 6264.7 5995.2 6094.7 6112.3 6124.0 6133.0 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:69922.59 Median:42107.29 stddev:149259.90 (354.5%) stddev-sans-first:149587.54 18:25:15 INFO - PROCESS | 1656 | Values: 478.2 482.7 523.6 540.8 553.1 31076.7 41866.7 42854.1 43101.4 44940.8 43943.3 44202.3 44276.8 44292.3 44304.0 9751.5 9914.7 9921.4 9925.3 9953.5 9844.3 9965.2 9966.7 9984.7 9988.7 9926.6 9958.5 9990.3 9991.5 10000.2 9915.6 9963.8 9976.3 9984.7 9985.9 9684.6 9736.3 9757.8 9759.8 9774.6 41846.4 42084.4 42091.0 42123.6 42182.8 3329.9 3449.2 3638.6 3983.7 4294.7 24480.8 32753.3 32778.7 32785.7 32890.8 3253.0 3265.5 3418.7 3442.1 3446.4 503413.0 504586.0 504794.0 506024.0 512455.0 60959.4 61217.7 61831.0 61891.5 61920.0 65084.7 65113.3 65150.0 65168.0 65288.3 16519.6 16728.9 16747.2 16780.3 16782.6 10876.7 12890.2 12991.4 12995.4 13010.5 17101.4 17716.4 17738.8 17763.0 17807.5 6274.7 6287.9 6319.5 6356.2 6372.4 830.9 851.0 981.6 1016.5 1081.8 29408.0 29850.7 30215.2 30388.0 30975.4 990.5 1014.4 1029.6 1065.8 1121.2 14830.8 15014.8 15697.6 15752.8 15841.0 628467.0 633747.0 633782.0 635164.0 635974.0 59307.7 59332.0 59639.0 59681.7 60111.9 1226.5 1346.2 1394.4 1412.6 1415.9 30763.2 31888.7 32048.7 32660.3 32763.8 52383.7 52906.5 53121.0 53158.9 53236.5 528707.0 530163.0 531011.0 531648.0 532836.0 26798.2 26804.7 27649.2 27738.7 28230.6 48482.5 48792.3 49847.8 49995.5 50128.5 40222.0 40555.4 41103.6 41718.4 41957.8 39812.8 39947.8 40469.0 41271.8 42006.8 39035.8 39343.4 39864.8 39900.8 39985.0 32034.0 34982.5 35590.1 35659.6 35766.5 22608.9 23229.0 25806.0 25829.0 26087.3 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:34045.06 Median:13124.03 stddev:98170.85 (748.0%) stddev-sans-first:98413.64 18:25:15 INFO - PROCESS | 1656 | Values: 348.2 364.9 368.6 375.8 400.5 15165.1 16504.0 19996.0 20563.2 20748.3 20459.4 20732.9 20813.1 21086.0 21119.5 5740.0 5843.5 5930.3 6088.1 6090.4 4112.2 5947.5 6048.2 6051.8 6056.1 4319.5 4349.3 4351.1 5386.0 6620.1 5773.7 6093.7 6594.7 6622.5 6633.0 5976.2 5977.5 5982.7 6003.0 6005.8 13721.7 24838.5 25104.7 25640.6 25899.9 2167.6 2193.1 2341.9 2363.9 2436.6 9240.2 9370.5 9965.1 10236.2 13510.3 1613.1 1664.9 1800.0 1922.0 1925.1 110298.3 116413.0 116818.0 118860.0 120103.0 11068.7 11126.9 11164.3 11288.9 11350.5 14188.5 14188.6 14192.4 14207.6 14238.1 6957.0 8262.6 8390.5 8415.4 8465.8 8419.2 8457.0 8609.0 8612.8 8637.7 4343.7 4443.4 4444.8 4527.8 4547.1 1690.3 1805.3 1823.2 1862.3 1887.2 851.8 918.7 942.3 951.8 984.1 17788.9 19295.7 19795.3 21166.9 21241.8 908.5 943.6 953.3 963.3 981.6 9560.8 10864.7 11224.4 11234.8 11326.4 575440.0 585822.0 589279.0 598326.0 599025.0 94173.0 119678.0 121259.4 121305.8 131228.3 960.4 995.1 1033.6 1034.8 1035.1 18610.5 19076.4 19309.4 19325.6 19422.8 52891.0 53596.5 54326.7 54464.0 54558.7 50425.2 64731.5 72610.3 78209.0 78362.0 7653.3 7819.4 7860.2 7979.2 8227.6 11203.8 11804.4 12559.7 12868.7 12871.7 9406.9 10905.7 11183.8 11209.4 11251.3 10737.5 10755.4 10948.8 11151.3 11222.8 11029.3 11036.2 11184.4 11226.4 11265.4 7836.5 7840.9 8205.8 8272.1 8307.5 9052.2 10067.5 11193.9 11322.5 11528.9 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5633.70 Median:5004.10 stddev:3147.72 (62.9%) stddev-sans-first:3141.46 18:25:15 INFO - PROCESS | 1656 | Values: 1532.4 1552.7 1765.0 1898.8 1926.5 8321.4 8821.0 8852.9 8859.8 8865.8 6473.5 7995.2 8342.5 8595.4 8656.5 2325.9 2386.8 2387.1 2388.3 2392.0 2331.6 2354.0 2380.3 2385.6 2386.3 1981.7 2366.0 2372.7 2381.9 2387.8 2328.1 2358.1 2360.3 2363.0 2367.0 2351.2 2355.6 2356.8 2358.5 2364.8 8672.3 8691.8 8758.3 8776.0 8797.3 5069.7 5226.7 5312.2 5314.7 5333.8 3054.4 3448.2 3660.2 3689.3 3698.9 1070.2 1105.6 1106.0 1128.3 1129.7 11519.2 11552.9 11573.4 11597.5 11690.2 4492.7 4575.6 4770.6 4773.1 4794.2 9785.5 9794.0 9795.4 9811.8 9821.5 5306.8 5320.6 5320.7 5323.3 5329.0 4357.4 4524.7 4693.1 4828.5 4878.2 3303.7 3810.8 3874.6 3967.3 3979.2 1261.4 1269.6 1272.6 1288.5 1291.8 883.3 924.6 925.2 926.1 957.7 5409.3 5600.2 5677.7 5908.1 6031.8 401.0 442.8 446.8 1004.8 1014.0 3798.0 3889.2 3950.6 4038.4 4077.1 9717.2 9938.0 10149.6 10619.9 10644.4 10560.4 10597.2 10602.6 10605.2 10626.0 7140.3 7936.3 8014.3 8164.0 8181.9 4546.3 5712.0 5724.3 5739.6 5953.9 9528.3 9683.8 9866.0 9868.3 9974.6 9877.0 9897.8 10170.3 10198.8 10298.5 6863.4 7114.2 7281.3 7370.9 7566.9 8201.1 8410.7 8424.2 8430.0 8430.9 7386.3 7480.6 7540.7 7569.5 7657.1 7241.3 7263.7 7335.7 7377.6 7461.1 6812.6 6952.4 6970.3 6976.8 6991.1 4897.9 4938.1 5016.3 5094.2 5135.4 4843.2 4991.9 5070.7 5094.6 5206.8 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4532.01 Median:5135.97 stddev:1405.03 (27.4%) stddev-sans-first:1372.57 18:25:15 INFO - PROCESS | 1656 | Values: 298.7 305.6 306.5 312.9 314.5 5371.8 5373.1 5378.7 5380.7 5395.3 4618.3 4833.3 5308.2 5351.9 5386.8 3805.6 3873.4 3973.8 4098.4 4132.0 3339.2 3606.7 4033.7 4061.0 4083.0 4033.5 4040.5 4059.5 4069.5 4104.8 3787.5 3981.7 4004.1 4081.5 4086.9 3432.3 3435.9 3444.9 3450.8 3460.2 5099.6 5120.6 5124.0 5140.3 5151.9 4226.5 4505.0 4509.1 4524.5 4536.0 4467.6 4801.8 4815.4 5009.2 5302.3 3785.6 3787.7 3791.7 3809.9 3818.5 5311.0 5489.5 5719.8 5836.9 5916.0 3368.7 3793.4 3846.2 3851.5 3851.6 3891.5 3900.4 3902.5 3911.5 3926.4 4538.7 4559.9 4560.7 4578.3 4610.4 4122.2 4757.4 5131.7 5496.9 5636.6 1029.3 1300.8 1326.7 1332.3 1340.9 691.1 790.2 810.3 818.6 824.4 4367.6 5043.2 5104.0 5227.2 5264.8 4946.5 5230.5 5366.1 5743.8 5751.7 4681.4 4735.5 5165.5 5261.5 5308.8 2730.5 2953.4 2999.7 2999.7 3009.3 5621.9 5902.7 5913.4 5930.3 5934.9 5456.8 5807.7 5863.1 5873.7 5896.4 5863.0 5865.4 5891.1 5903.7 5922.7 5363.6 5716.3 5716.4 5744.8 5755.2 5336.8 5678.3 5692.7 5729.9 5762.9 5961.9 5989.0 5989.4 5999.9 6029.6 3238.7 3820.5 3919.4 3956.7 4003.7 4962.8 5560.8 5759.3 5765.5 5776.9 5636.8 5661.8 5669.9 5689.6 5702.8 5184.8 5350.5 5558.6 5621.6 5666.4 5309.8 5462.8 5529.2 5530.4 5533.3 4919.6 5266.5 5425.8 5526.4 5624.1 4639.7 5452.2 5476.2 5513.5 5523.7 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24360.40 Median:3783.57 stddev:38280.95 (1011.8%) stddev-sans-first:38347.17 18:25:15 INFO - PROCESS | 1656 | Values: 715.7 716.9 775.3 829.6 833.8 15220.8 16211.8 16262.9 16282.3 16297.3 14719.5 14892.0 14894.3 14918.0 14955.0 679.7 703.2 826.5 835.3 858.6 841.6 856.8 860.2 861.8 862.2 913.5 914.0 914.0 915.2 917.8 721.0 743.1 749.2 909.0 909.2 543.4 545.1 650.1 668.4 675.0 14627.3 14646.5 14651.2 14663.9 14697.0 13692.3 13880.9 14014.3 14085.9 14131.0 11683.7 12418.9 12632.4 12839.4 13034.7 3446.5 3887.4 4010.5 4079.8 4124.7 111861.0 113860.4 117074.0 117769.0 119439.0 11587.4 11667.4 11702.8 11825.3 12010.6 24111.5 24187.6 24193.6 24455.6 24526.1 6908.7 7888.8 7890.3 8090.6 8265.8 4475.7 4650.4 4715.8 4753.1 4760.0 3669.4 3782.6 3784.5 3785.9 3789.4 669.9 671.2 680.1 695.9 696.2 528.0 611.9 653.5 695.0 716.0 9219.2 9433.3 9543.3 9579.8 9738.5 517.4 593.6 613.0 651.6 721.9 4520.3 4580.8 4710.8 4732.2 4850.2 87250.3 139427.0 141924.3 144035.0 144249.3 125397.0 126922.0 129369.1 130938.0 132346.3 99727.0 106795.0 107263.0 107470.5 112232.0 7993.8 8772.6 9231.4 9313.4 9440.4 55044.3 57922.4 61151.1 62578.0 66002.7 71146.6 87301.0 91432.1 92543.5 93947.0 11840.5 11880.8 11979.3 12039.7 12494.5 13265.9 13334.6 13442.8 13656.9 13699.6 11012.4 11138.4 12340.7 12968.3 13010.7 13663.3 13684.4 14280.6 14307.3 14450.8 13156.9 13599.8 13627.9 13708.9 14129.7 10920.5 11116.2 11180.2 11574.7 11693.7 11124.2 11254.9 11388.7 11485.3 11498.9 18:25:15 INFO - PROCESS | 1656 | -------- Summary: end -------- 18:25:15 INFO - PROCESS | 1656 | 18:25:15 INFO - TEST-INFO | 1656: exit 0 18:25:15 INFO - TEST-OK | dromaeo_css | took 1118622ms 18:25:15 INFO - TEST-START | kraken 18:25:15 INFO - operating with platform_type : mac_ 18:25:15 INFO - Initialising browser for kraken test... 18:25:15 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpcuCPNC/profile http://localhost:49269/getInfo.html) 18:25:18 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 18:25:18 INFO - PROCESS | 1663 | colorDepth:24 18:25:18 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 18:25:18 INFO - PROCESS | 1663 | __metrics 18:25:18 INFO - PROCESS | 1663 | 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" 18:25:18 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:25:19 INFO - TEST-INFO | 1663: exit 0 18:25:19 INFO - Browser initialized. 18:25:19 INFO - Running cycle 1/1 for kraken test... 18:25:19 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpcuCPNC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:31 INFO - PROCESS | 1664 | RSS: Main: 228663296 18:25:31 INFO - PROCESS | 1664 | 18:25:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 18:25:34 INFO - PROCESS | 1664 | RSS: Main: 293109760 18:25:34 INFO - PROCESS | 1664 | 18:25:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 18:25:37 INFO - PROCESS | 1664 | RSS: Main: 294129664 18:25:37 INFO - PROCESS | 1664 | 18:25:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 18:25:41 INFO - PROCESS | 1664 | RSS: Main: 381935616 18:25:41 INFO - PROCESS | 1664 | 18:25:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 18:25:44 INFO - PROCESS | 1664 | RSS: Main: 280715264 18:25:44 INFO - PROCESS | 1664 | 18:25:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:25:47 INFO - PROCESS | 1664 | RSS: Main: 286023680 18:25:47 INFO - PROCESS | 1664 | 18:25:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 18:25:50 INFO - PROCESS | 1664 | RSS: Main: 324132864 18:25:50 INFO - PROCESS | 1664 | 18:25:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 18:25:54 INFO - PROCESS | 1664 | RSS: Main: 324190208 18:25:54 INFO - PROCESS | 1664 | 18:25:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 18:25:57 INFO - PROCESS | 1664 | RSS: Main: 324521984 18:25:57 INFO - PROCESS | 1664 | 18:25:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:59 INFO - PROCESS | 1664 | RSS: Main: 301408256 18:25:59 INFO - PROCESS | 1664 | 18:26:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:26:02 INFO - PROCESS | 1664 | RSS: Main: 302530560 18:26:02 INFO - PROCESS | 1664 | 18:26:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:26:05 INFO - PROCESS | 1664 | RSS: Main: 302624768 18:26:05 INFO - PROCESS | 1664 | 18:26:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:26:08 INFO - PROCESS | 1664 | RSS: Main: 311508992 18:26:08 INFO - PROCESS | 1664 | 18:26:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:26:12 INFO - PROCESS | 1664 | RSS: Main: 312840192 18:26:12 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 18:26:14 INFO - PROCESS | 1664 | RSS: Main: 356261888 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | __start_tp_report 18:26:14 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 18:26:14 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 18:26:14 INFO - PROCESS | 1664 | |i|pagename|runs| 18:26:14 INFO - PROCESS | 1664 | |0;ai-astar;97;111;107;112;113;110;116;110;112;112 18:26:14 INFO - PROCESS | 1664 | |1;audio-beat-detection;145;108;132;162;108;128;129;108;160;107 18:26:14 INFO - PROCESS | 1664 | |2;audio-dft;185;185;187;183;182;184;186;180;182;184 18:26:14 INFO - PROCESS | 1664 | |3;audio-fft;77;78;76;150;49;70;74;126;118;81 18:26:14 INFO - PROCESS | 1664 | |4;audio-oscillator;82;92;129;70;70;67;110;70;69;113 18:26:14 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;87;85;88;84;87;84;87;84;86;84 18:26:14 INFO - PROCESS | 1664 | |6;imaging-darkroom;93;92;94;95;93;93;93;94;93;93 18:26:14 INFO - PROCESS | 1664 | |7;imaging-desaturate;84;85;84;84;84;85;86;85;88;109 18:26:14 INFO - PROCESS | 1664 | |8;json-parse-financial;56;55;54;56;55;56;56;57;56;54 18:26:14 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;69;68;69;66;76;72;68;69;70;70 18:26:14 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;88;89;87;97;107;105;85;85;86;81 18:26:14 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;156;150;148;138;140;137;139;137;159;140 18:26:14 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;170;170;169;170;170;168;166;169;171;168 18:26:14 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;67;67;68;66;69;66;67;68;65;67 18:26:14 INFO - PROCESS | 1664 | __end_tp_report 18:26:14 INFO - PROCESS | 1664 | __start_cc_report 18:26:14 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2096 18:26:14 INFO - PROCESS | 1664 | __end_cc_report 18:26:14 INFO - PROCESS | 1664 | __startTimestamp1471915574351__endTimestamp 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | ------- Summary: start ------- 18:26:14 INFO - PROCESS | 1664 | Number of tests: 14 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:110.00 Median:112.00 stddev:5.12 (4.6%) stddev-sans-first:2.46 18:26:14 INFO - PROCESS | 1664 | Values: 97.0 111.0 107.0 112.0 113.0 110.0 116.0 110.0 112.0 112.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:130.50 stddev:21.40 (16.4%) stddev-sans-first:21.87 18:26:14 INFO - PROCESS | 1664 | Values: 145.0 108.0 132.0 162.0 108.0 128.0 129.0 108.0 160.0 107.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:2.10 (1.1%) stddev-sans-first:2.18 18:26:14 INFO - PROCESS | 1664 | Values: 185.0 185.0 187.0 183.0 182.0 184.0 186.0 180.0 182.0 184.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:89.90 Median:75.00 stddev:30.92 (41.2%) stddev-sans-first:32.45 18:26:14 INFO - PROCESS | 1664 | Values: 77.0 78.0 76.0 150.0 49.0 70.0 74.0 126.0 118.0 81.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:70.00 stddev:22.63 (32.3%) stddev-sans-first:23.93 18:26:14 INFO - PROCESS | 1664 | Values: 82.0 92.0 129.0 70.0 70.0 67.0 110.0 70.0 69.0 113.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.58 (1.8%) stddev-sans-first:1.59 18:26:14 INFO - PROCESS | 1664 | Values: 87.0 85.0 88.0 84.0 87.0 84.0 87.0 84.0 86.0 84.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 18:26:14 INFO - PROCESS | 1664 | Values: 93.0 92.0 94.0 95.0 93.0 93.0 93.0 94.0 93.0 93.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:87.40 Median:85.00 stddev:7.69 (9.0%) stddev-sans-first:8.06 18:26:14 INFO - PROCESS | 1664 | Values: 84.0 85.0 84.0 84.0 84.0 85.0 86.0 85.0 88.0 109.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:0.97 (1.7%) stddev-sans-first:1.01 18:26:14 INFO - PROCESS | 1664 | Values: 56.0 55.0 54.0 56.0 55.0 56.0 56.0 57.0 56.0 54.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:2.71 (3.9%) stddev-sans-first:2.86 18:26:14 INFO - PROCESS | 1664 | Values: 69.0 68.0 69.0 66.0 76.0 72.0 68.0 69.0 70.0 70.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:86.50 stddev:8.91 (10.3%) stddev-sans-first:9.38 18:26:14 INFO - PROCESS | 1664 | Values: 88.0 89.0 87.0 97.0 107.0 105.0 85.0 85.0 86.0 81.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:144.00 stddev:8.24 (5.7%) stddev-sans-first:7.59 18:26:14 INFO - PROCESS | 1664 | Values: 156.0 150.0 148.0 138.0 140.0 137.0 139.0 137.0 159.0 140.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:170.00 stddev:1.45 (0.9%) stddev-sans-first:1.50 18:26:14 INFO - PROCESS | 1664 | Values: 170.0 170.0 169.0 170.0 170.0 168.0 166.0 169.0 171.0 168.0 18:26:14 INFO - PROCESS | 1664 | 18:26:14 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 18:26:14 INFO - PROCESS | 1664 | Values: 67.0 67.0 68.0 66.0 69.0 66.0 67.0 68.0 65.0 67.0 18:26:14 INFO - PROCESS | 1664 | -------- Summary: end -------- 18:26:14 INFO - PROCESS | 1664 | 18:26:15 INFO - TEST-INFO | 1664: exit 0 18:26:15 INFO - TEST-OK | kraken | took 59215ms 18:26:15 INFO - SUITE-END | took 1177s 18:26:15 INFO - Completed test suite (00:19:38) 18:26:15 INFO - javascript benchmark 18:26:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3369.5451114099596, 3399.2011877208865, 3404.5744281964753, 3420.162060295228, 3434.8282585870716, 5686.346070201928, 5727.914081288774, 5746.252987111675, 5802.622829516084, 5824.359320474744, 5703.629264097831, 5729.484346408823, 5755.216007912053, 5838.211841401409, 5874.118882167677, 5209.453007434203, 5211.322528071345, 5220.973895130519, 5279.472052794709, 5321.414644389114, 5398.514133727964, 5400.756965936523, 5404.378496472917, 5413.485718856722, 5433.4566543345745, 4407.911841763147, 5172.27588137661, 5371.301730775013, 5385.542228910547, 5397.217403476513, 4602.539746025404, 5051.621128415383, 5268.367795864482, 5283.2075188722, 5294.867628309285, 5212.687238765686, 5217.060929032773, 5234.057869583474, 5270.947290527084, 5278.419373868871, 5802.5938184327115, 5877.647341159544, 5880.8235752927485, 5892.911606325909, 5926.170336152942, 6155.261368635747, 6229.6573688447115, 6291.0, 6306.684665766693, 6315.305316415191, 6115.113308570232, 6239.438450539428, 6266.436020758154, 6269.96865015672, 6272.090546870724, 5981.611195272293, 5996.1005849122985, 5997.430244126801, 6017.0, 6017.548683848729, 5331.600129990266, 6113.083037544404, 6225.128482012522, 6283.7800676976085, 6357.205349331333, 6263.279722831841, 6277.843053923689, 6279.3720627937755, 6283.0, 6291.465225455888, 6306.590071645328, 6318.399752023551, 6324.146240257598, 6325.0, 6328.240611126695, 5070.239464080418, 6064.939350606437, 6104.20645316106, 6286.308506064329, 6294.622322660655, 6158.661273629904, 6161.7535298588455, 6191.0, 6224.470919971763, 6228.0, 5017.648764586517, 5067.771950262247, 5075.3402057732255, 5078.923816142761, 5083.364579427572, 4448.733076015449, 4545.113702827983, 4569.657275704336, 4594.95405045952, 4595.724256544618, 5774.191613174159, 5782.959067367914, 5801.564882633735, 5802.680852553066, 5808.0, 6224.470919971853, 6255.155554000243, 6269.937300626935, 6278.466330361879, 6284.780032698834, 5881.852953676193, 5908.586398952118, 5914.76340946366, 5918.585699001114, 5921.111833225051, 5354.7858085676135, 5397.136458166675, 5454.481824226692, 5470.617056806065, 5529.391766905637, 5954.660201454638, 6241.157443745127, 6342.619442833445, 6345.333739957238, 6349.396807303298, 5528.7235638218735, 6275.184226050585, 6278.968605156945, 6284.717187726472, 6284.748610055546, 6201.162843016135, 6206.627602343874, 6237.2309828423, 6253.249610046823, 6318.652474113784, 6147.846303842261, 6247.125402443752, 6258.280297765815, 6262.499000079892, 6292.27638821523, 6083.817485475266, 6245.656488893154, 6249.25008998923, 6253.530985176039, 6257.53068519872, 6047.606905551125, 6114.38856114385, 6229.937700622936, 6232.283287422005, 6256.968715156395, 6117.877642447037, 6185.876282474235, 6220.471259942775, 6261.56169068179, 6269.529785266032, 6105.053716673922, 6136.723847426966, 6139.9079013813935, 6154.5384096192065, 6160.845978850564, 6038.486728628116, 6122.969385153046, 6233.2208473940755, 6246.063090536456, 6264.749410023548, 5995.2206213192, 6094.695265236632, 6112.327643959072, 6124.0201567748945, 6133.0], "value": 5753.798615877677, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.2228878073131, 482.6910777102654, 523.5811350919266, 540.8053100883682, 553.1122548309964, 31076.689233107663, 41866.65066794657, 42854.14291714164, 43101.353479697755, 44940.77529612355, 43943.340849887194, 44202.33696494555, 44276.77861610695, 44292.33561496572, 44304.0, 9751.51242437877, 9914.702558923243, 9921.35511191774, 9925.320163555836, 9953.452560109192, 9844.310898237127, 9965.202783777317, 9966.65116720915, 9984.700458986243, 9988.700338989842, 9926.553305101252, 9958.452285124315, 9990.300678952477, 9991.500424978758, 10000.199984001263, 9915.553800079013, 9963.80072398548, 9976.251781116445, 9984.70045898617, 9985.90014099857, 9684.56419461126, 9736.318457707963, 9757.804843903083, 9759.804803903953, 9774.5601447935, 41846.37230441546, 42084.368734469004, 42091.0, 42123.57876421258, 42182.78908605468, 3329.93340133196, 3449.2066824630224, 3638.599754027055, 3983.6813054955533, 4294.656427485794, 24480.755192448025, 32753.344933101205, 32778.67221327804, 32785.6721432785, 32890.848820291176, 3252.96747032529, 3265.526498657705, 3418.7094097001786, 3442.0534353053113, 3446.4141096013736, 503413.0, 504586.0, 504794.0, 506024.0, 512454.9999999963, 60959.39040609537, 61217.69391153016, 61830.96197494297, 61891.45271368162, 61920.0, 65084.674576626814, 65113.34886651073, 65150.0, 65168.0, 65288.34711652918, 16519.587010324598, 16728.916355418387, 16747.162641867857, 16780.328786848633, 16782.58043548921, 10876.673699789018, 12890.226586404846, 12991.415386307639, 12995.350232488527, 13010.479580816662, 17101.401450949405, 17716.40552350269, 17738.846974946588, 17763.0, 17807.46577602674, 6274.686265686697, 6287.874242515124, 6319.52603554726, 6356.2054743157105, 6372.362763723683, 830.8836762853206, 850.995745021271, 981.5632043740467, 1016.5171543516918, 1081.8214994525972, 29408.0, 29850.701492985227, 30215.244618884703, 30388.0, 30975.380492390024, 990.4651488196438, 1014.3710899242517, 1029.6460154896288, 1065.8294672852307, 1121.159130652011, 14830.819243195945, 15014.774778378332, 15697.607559810867, 15752.842471575366, 15841.0, 628467.0, 633747.0, 633782.0, 635164.0, 635974.0, 59307.703461482415, 59332.0, 59639.0, 59681.701591491765, 60111.89608363774, 1226.5400474821943, 1346.2461021827808, 1394.3864699532173, 1412.5691664042565, 1415.8956014308649, 30763.230919226302, 31888.68111318857, 32048.67951320457, 32660.34679306448, 32763.836180818944, 52383.73808130935, 52906.470935290155, 53121.0, 53158.936821264135, 53236.46763532315, 528707.0, 530163.0, 531011.0, 531648.0, 532836.0, 26798.19605411841, 26804.731952680224, 27649.170524884285, 27738.722612773614, 28230.576541352308, 48482.5151748478, 48792.26811597758, 49847.75076124596, 49995.500044999084, 50128.498715012385, 40222.0, 40555.39166912558, 41103.58896410998, 41718.374224386054, 41957.79021104875, 39812.805615831574, 39947.80026099851, 40469.0, 41271.7936410316, 42006.789966049975, 39035.80482097571, 39343.40984885172, 39864.800675996434, 39900.80049599733, 39985.0, 32034.0, 34982.47526287159, 35590.110247244025, 35659.643403565635, 35766.46350304696, 22608.886955565118, 23229.0, 25806.0, 25829.008576465927, 26087.347816303984], "value": 20289.551034106094, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.1869833937755, 364.8941806876005, 368.64609974424553, 375.8402678861484, 400.507375927609, 15165.090094594332, 16504.0, 19996.0, 20563.177472901065, 20748.27381041662, 20459.386218413434, 20732.8963355183, 20813.063412146454, 21086.0, 21119.472013199676, 5739.971300143494, 5843.474087332139, 5930.288365396137, 6088.147659327676, 6090.390960903918, 4112.218678451085, 5947.464728174464, 6048.2137322148055, 6051.788187413442, 6056.091586262052, 4319.460067491564, 4349.282368409209, 4351.129774045204, 5386.0, 6620.106285651447, 5773.740181691834, 6093.664848433335, 6594.736210551572, 6622.53642245043, 6632.966835165817, 5976.193213916109, 5977.521798256173, 5982.700864956778, 6003.009503431928, 6005.789797357072, 13721.725565488634, 24838.503229935483, 25104.748952510425, 25640.615390769155, 25899.870500647565, 2167.56648670265, 2193.0679461229074, 2341.8711970841596, 2363.929082127539, 2436.5857804173156, 9240.21458176049, 9370.53147342637, 9965.140460504977, 10236.232282578836, 13510.324483775772, 1613.0805440898691, 1664.9001059936322, 1799.9910000450045, 1921.9615607687767, 1925.075963537496, 110298.3455248172, 116413.0, 116818.0, 118860.0, 120103.0, 11068.667939961813, 11126.944365278121, 11164.330140191614, 11288.943555282336, 11350.48922798476, 14188.503402380868, 14188.574342769733, 14192.432302707775, 14207.644808879653, 14238.14571125715, 6956.965215173993, 8262.586870656443, 8390.538520381439, 8415.368847336475, 8465.788355291166, 8419.158084191531, 8456.957715211385, 8608.956955215184, 8612.827743445096, 8637.654493820302, 4343.69594128408, 4443.4445694288215, 4444.777761111932, 4527.7509736964785, 4547.113312903952, 1690.3407671008317, 1805.2508209093342, 1823.2251293200336, 1862.2862196782357, 1887.1790771014653, 851.8424091543085, 918.7014220378404, 942.2697409507687, 951.7573018880141, 984.1192133040888, 17788.91105544464, 19295.710564341545, 19795.30716424918, 21166.894165529073, 21241.78758212398, 908.487368531401, 943.6272672294406, 953.3374304858196, 963.2582911158369, 981.5877331520778, 9560.808783824423, 10864.728381790519, 11224.438778061065, 11234.831477527843, 11326.377049262204, 575440.0, 585822.0, 589279.0, 598326.0, 599025.0, 94173.0, 119678.0, 121259.39370303092, 121305.78694213122, 131228.3438582801, 960.356561104048, 995.0696099147176, 1033.5710680067687, 1034.7826956339254, 1035.1201478743008, 18610.534736631693, 19076.42770716881, 19309.42071737794, 19325.613487730447, 19422.83462992225, 52891.0, 53596.464035359146, 54326.728366357915, 54464.0, 54558.727206363714, 50425.23511677074, 64731.49346916506, 72610.27389726035, 78209.0, 78362.0, 7653.311201991847, 7819.374450044096, 7860.174681658572, 7979.202079792207, 8227.629756660845, 11203.775924481628, 11804.409779511161, 12559.686007849878, 12868.742625147257, 12871.742565148832, 9406.905930940604, 10905.672829815117, 11183.776324473627, 11209.383483908463, 11251.324920504796, 10737.46312684347, 10755.408452535186, 10948.781024379627, 11151.330920144817, 11222.831657525307, 11029.338239705643, 11036.172287078341, 11184.440777961232, 11226.382548959886, 11265.380404077854, 7836.490628109154, 7840.882386764317, 8205.753827385188, 8272.131426200403, 8307.543085130375, 9052.23906708434, 10067.546960386946, 11193.888061119285, 11322.547098115983, 11528.884711152781], "value": 9378.070584525398, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1532.4176812811133, 1552.74379727345, 1764.9823501764986, 1898.848092152628, 1926.4798504403802, 8321.375896807742, 8820.999999999993, 8852.911470885289, 8859.778505537364, 8865.822683546334, 6473.5144864135145, 7995.160508146633, 8342.499450033003, 8595.441296315736, 8656.523891185981, 2325.883705814707, 2386.8209884258663, 2387.069043073203, 2388.2835149455095, 2392.0431827269144, 2331.6152834782242, 2353.9882300588474, 2380.27401642499, 2385.642153676945, 2386.319898828834, 1981.7324661170774, 2365.9645105323434, 2372.6915500984846, 2381.9166329178483, 2387.785099341059, 2328.0804082387363, 2358.127492827656, 2360.303710405426, 2363.0075368345338, 2367.0295178976535, 2351.1888398502488, 2355.6348765941307, 2356.787889089973, 2358.50471401006, 2364.775346342112, 8672.349573781992, 8691.826163476759, 8758.255548278405, 8776.034636190014, 8797.340199485065, 5069.721165335905, 5226.686398816083, 5312.229726689647, 5314.681119132826, 5333.786648534054, 3054.373853360078, 3448.2413868949043, 3660.2130541933348, 3689.262147570497, 3698.8890333290046, 1070.218740319571, 1105.6130354375985, 1105.9778804423947, 1128.3060917535656, 1129.6949823547595, 11519.193656444053, 11552.942235288854, 11573.421328933604, 11597.536098556047, 11690.240134391237, 4492.73043617384, 4575.633949284049, 4770.594499467584, 4773.093112308719, 4794.2089555223, 9785.510724463747, 9794.0, 9795.412275263365, 9811.75470613226, 9821.508924553744, 5306.8407947761625, 5320.600954928318, 5320.733963301896, 5323.334583177103, 5329.014132385521, 4357.411749413852, 4524.660650451164, 4693.108309421199, 4828.493008234155, 4878.170710979142, 3303.702666760003, 3810.847566097325, 3874.5931677174053, 3967.285888540033, 3979.164375481181, 1261.375619068567, 1269.5620011096157, 1272.5864094169433, 1288.4588472841428, 1291.761024210524, 883.2536506651869, 924.5977999570208, 925.2021096587174, 926.1016813690709, 957.7078990907701, 5409.323834520685, 5600.159976003632, 5677.744501497443, 5908.113782932594, 6031.758729650853, 400.9659178969762, 442.78967490441784, 446.81233881769737, 1004.8090862736203, 1013.9695809125591, 3798.0, 3889.241597888441, 3950.627087603676, 4038.374052021941, 4077.062275676611, 9717.17404020666, 9938.0, 10149.594016239416, 10619.94690026545, 10644.361338319726, 10560.366378017343, 10597.20520960931, 10602.628907988183, 10605.204609654307, 10626.043656070991, 7140.250273721184, 7936.3254123399, 8014.278714915569, 8164.0, 8181.877271840927, 4546.272596384562, 5712.0, 5724.313082429969, 5739.598228123991, 5953.910691339547, 9528.333016689043, 9683.806323873343, 9865.950670246602, 9868.309218354647, 9974.55114519863, 9876.950615246878, 9897.802043959224, 10170.338927969658, 10198.838360108093, 10298.536565854405, 6863.382295593221, 7114.181869085121, 7281.344678978918, 7370.852582948204, 7566.886496702664, 8201.09787923316, 8410.663573456994, 8424.199701028392, 8429.957850210709, 8430.915690843258, 7386.261373862786, 7480.551166930001, 7540.660670269741, 7569.5079819811535, 7657.119431265476, 7241.275872412927, 7263.673134708845, 7335.669894854637, 7377.631118443949, 7461.067366579177, 6812.557183783038, 6952.374286314053, 6970.302969702989, 6976.825579360354, 6991.091158149408, 4897.9020419592125, 4938.135826230324, 5016.322796422436, 5094.2358646203365, 5135.35808023997, 4843.24929635907, 4991.878451658104, 5070.721110338966, 5094.592432605308, 5206.791728330824], "value": 4517.559111563359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.7012987012987, 305.56151921991943, 306.4545109704725, 312.9327194653151, 314.460699899672, 5371.803776752836, 5373.086575282202, 5378.704171270579, 5380.7309634518215, 5395.298611180541, 4618.261078227484, 4833.347498087766, 5308.230306605549, 5351.866203344918, 5386.787972706139, 3805.6384643458896, 3873.4189871519216, 3973.801309934506, 4098.4057311689785, 4132.008318003673, 3339.231976645373, 3606.7178867230823, 4033.657139143176, 4060.9593904061026, 4082.9591704082877, 4033.455483509718, 4040.5454036546967, 4059.4722686050773, 4069.450624165744, 4104.835806567734, 3787.507624008889, 3981.7212795104356, 4004.0790618157694, 4081.469408976829, 4086.9386959195635, 3432.3478539077496, 3435.862565497402, 3444.93207105798, 3450.8447119879415, 3460.2387474755515, 5099.603186502375, 5120.590352771769, 5123.974380128076, 5140.278006867704, 5151.896962060775, 4226.4716910386205, 4504.977475112636, 4509.075639493896, 4524.524924882873, 4535.960906192859, 4467.642588592905, 4801.8079276828885, 4815.410914398275, 5009.223570346602, 5302.310699609026, 3785.5835858055866, 3787.6780473659774, 3791.696664266852, 3809.866654667101, 3818.5417749870194, 5310.973445132789, 5489.549779009728, 5719.82840514781, 5836.854078648068, 5916.0, 3368.6693755966744, 3793.4499497572697, 3846.1922996171115, 3851.4993050903763, 3851.576326604127, 3891.5330160380936, 3900.414937759359, 3902.492675952108, 3911.4745249352777, 3926.3521518949055, 4538.72767633943, 4559.931601025921, 4560.6807523473035, 4578.267477203632, 4610.40064791575, 4122.175564887035, 4757.405324334458, 5131.666441681279, 5496.862578435571, 5636.633618814764, 1029.25893356783, 1300.78537041389, 1326.6683329167709, 1332.2872263339068, 1340.9128406653535, 691.0567075941404, 790.213737331359, 810.2748040503716, 818.6111596991382, 824.3981893217984, 4367.606915377632, 5043.218301163325, 5103.974480127576, 5227.2420499028185, 5264.815731449381, 4946.455889852114, 5230.503102205284, 5366.087765079946, 5743.84890810943, 5751.654900705971, 4681.368015317888, 4735.502772208937, 5165.527824569979, 5261.5264626183825, 5308.840734777963, 2730.4675588260097, 2953.394554116401, 2999.685033071496, 2999.735199924043, 3009.307859192398, 5621.8594535136135, 5902.675352855634, 5913.438223368773, 5930.28836539618, 5934.940650593525, 5456.836294911159, 5807.656955600857, 5863.149843272701, 5873.676947767828, 5896.439838215449, 5863.035882979249, 5865.445656900924, 5891.086881533369, 5903.7048147593305, 5922.674252915958, 5363.570914326922, 5716.28546431696, 5716.399778023248, 5744.827655170351, 5755.172810081249, 5336.78652853897, 5678.290213723285, 5692.686902220417, 5729.914051289151, 5762.8847423050465, 5961.850953726018, 5989.0, 5989.401059893976, 5999.940000600112, 6029.60807547508, 3238.70851623355, 3820.5481916871413, 3919.412088186795, 3956.7428117173445, 4003.659688926474, 4962.784117891139, 5560.833175004756, 5759.337676167294, 5765.538756899522, 5776.855578610568, 5636.802711905064, 5661.773529058792, 5669.886602268014, 5689.630174038674, 5702.800401985911, 5184.844454666366, 5350.5184533392185, 5558.555315574825, 5621.578381621313, 5666.413404246818, 5309.814156504503, 5462.787571033738, 5529.22590837275, 5530.364008139116, 5533.335999680065, 4919.631027672867, 5266.473352664703, 5425.83722488326, 5526.392096869422, 5624.128260119688, 4639.652026097989, 5452.2094296327605, 5476.17857321405, 5513.476219759116, 5523.6800543772515], "value": 4072.0489112713108, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.6922523314975, 716.8566286742649, 775.2596270561614, 829.6391069884598, 833.791552111972, 15220.77168842467, 16211.75682364764, 16262.94290871094, 16282.348706051773, 16297.266623001964, 14719.484818031371, 14892.0, 14894.255287235648, 14918.0, 14955.0, 679.7281087564988, 703.2088899987514, 826.5206180415374, 835.3233880556769, 858.5792961448876, 841.5623875584693, 856.7643897928058, 860.2300940658107, 861.8233262181269, 862.211076864668, 913.4656226107702, 913.963441462344, 913.9862902056475, 915.1717695485596, 917.7705573606598, 720.9711611535532, 743.0971369785736, 749.2282948562989, 908.9727308180766, 909.1771946388529, 543.4375421438831, 545.076096017251, 650.1320736816356, 668.3617145625916, 675.0111087257192, 14627.341769620287, 14646.487372942003, 14651.194184319858, 14663.853361466356, 14696.971212015163, 13692.31538423075, 13880.861191388058, 14014.29928503581, 14085.929570352186, 14131.0, 11683.7079073023, 12418.937905310415, 12632.368381580976, 12839.422225999853, 13034.739305213938, 3446.4657978013447, 3887.436321733333, 4010.5187377514594, 4079.7552146870985, 4124.731892427013, 111861.0, 113860.43069784598, 117074.0, 117769.0, 119439.0, 11587.362695051854, 11667.35829529367, 11702.824457633142, 11825.349605771766, 12010.579629712918, 24111.51776964451, 24187.637185442232, 24193.63709544358, 24455.63316550253, 24526.14158504444, 6908.689108990107, 7888.7633370998965, 7890.329322007694, 8090.635921383434, 8265.800649512865, 4475.731456112644, 4650.4186976627925, 4715.811367545329, 4753.096911586787, 4759.95281038178, 3669.3578623740736, 3782.6217378261954, 3784.526934133233, 3785.9432108518395, 3789.4315852621776, 669.9162604674416, 671.1509939925998, 680.1498127340824, 695.8573492434039, 696.2271878215178, 528.0, 611.9169070744757, 653.4772182254178, 694.9861002779916, 715.9856802864018, 9219.216366608913, 9433.302704646188, 9543.33196676226, 9579.808403831885, 9738.513074346254, 517.4437479709252, 593.631948192115, 612.9540284478682, 651.5797310734549, 721.9241979592172, 4520.299353600197, 4580.839670611579, 4710.764461776897, 4732.195526760459, 4850.175470170079, 87250.27497250224, 139427.0, 141924.29037854745, 144035.0, 144249.27875360765, 125397.0, 126922.0, 129369.05946410811, 130938.0, 132346.33826830806, 99727.00818975443, 106795.0, 107262.99999999844, 107470.46264768782, 112232.0, 7993.760187194393, 8772.649094036167, 9231.399959002913, 9313.39462934907, 9440.386374885611, 55044.34866953998, 57922.42077579339, 61151.082733758136, 62578.0, 66002.66998664977, 71146.57706845937, 87301.0, 91432.05691630022, 92543.53728231585, 93947.0, 11840.526378945091, 11880.76238475243, 11979.28124312544, 12039.699007524881, 12494.500219991462, 13265.933670331586, 13334.595701635944, 13442.798358024442, 13656.863431365957, 13699.589012329647, 11012.39431831257, 11138.3873886937, 12340.691482713004, 12968.28674422878, 13010.739785204432, 13663.316834158451, 13684.3842027111, 14280.642983925485, 14307.284635768377, 14450.783238251224, 13156.934215328862, 13599.79600306016, 13627.931860341032, 13708.86291137076, 14129.717405652034, 10920.453977300944, 11116.221864469733, 11180.161487888277, 11574.71063223426, 11693.707657308296, 11124.165687573302, 11254.887451125383, 11388.715282118013, 11485.253458525169, 11498.942505287754], "value": 7797.541366504188, "unit": "score"}], "name": "dromaeo_css", "value": 7345.313510132741, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 111.0, 107.0, 112.0, 113.0, 110.0, 116.0, 110.0, 112.0, 112.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 132.0, 162.0, 108.0, 128.0, 129.0, 108.0, 160.0, 107.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 187.0, 183.0, 182.0, 184.0, 186.0, 180.0, 182.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 76.0, 150.0, 49.0, 70.0, 74.0, 126.0, 118.0, 81.0], "value": 89.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 92.0, 129.0, 70.0, 70.0, 67.0, 110.0, 70.0, 69.0, 113.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 84.0, 87.0, 84.0, 87.0, 84.0, 86.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 95.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 84.0, 85.0, 86.0, 85.0, 88.0, 109.0], "value": 87.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 54.0, 56.0, 55.0, 56.0, 56.0, 57.0, 56.0, 54.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 66.0, 76.0, 72.0, 68.0, 69.0, 70.0, 70.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 97.0, 107.0, 105.0, 85.0, 85.0, 86.0, 81.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 148.0, 138.0, 140.0, 137.0, 139.0, 137.0, 159.0, 140.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 169.0, 170.0, 170.0, 168.0, 166.0, 169.0, 171.0, 168.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 69.0, 66.0, 67.0, 68.0, 65.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1462.6, "alertThreshold": 2.0}]} 18:26:15 INFO - Return code: 0 18:26:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:26:15 INFO - # TBPL SUCCESS # 18:26:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:26:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:26:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:26:15 INFO - 2016-08-22 18:26:15.678 screenresolution[1668:10228] starting screenresolution argv=screenresolution get 18:26:15 INFO - 2016-08-22 18:26:15.681 screenresolution[1668:10228] Display 0: 1600x1200x32@60 18:26:15 INFO - 2016-08-22 18:26:15.687 screenresolution[1669:10231] starting screenresolution argv=screenresolution list 18:26:15 INFO - Available Modes on Display 0 18:26:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:26:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:26:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:26:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:26:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:26:15 INFO - Intel Iris: 18:26:15 INFO - Chipset Model: Intel Iris 18:26:15 INFO - Type: GPU 18:26:15 INFO - Bus: Built-In 18:26:15 INFO - VRAM (Dynamic, Max): 1536 MB 18:26:15 INFO - Vendor: Intel (0x8086) 18:26:15 INFO - Device ID: 0x0a2e 18:26:15 INFO - Revision ID: 0x0009 18:26:15 INFO - Displays: 18:26:15 INFO - Display: 18:26:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:26:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:26:15 INFO - Display Serial Number: 1600x1200 60 18:26:15 INFO - Main Display: Yes 18:26:15 INFO - Mirror: Off 18:26:15 INFO - Online: Yes 18:26:15 INFO - Rotation: Supported 18:26:15 INFO - Return code: 0 18:26:15 INFO - Running post-action listener: _resource_record_post_action 18:26:15 INFO - [mozharness: 2016-08-23 01:26:15.921178Z] Finished run-tests step (success) 18:26:15 INFO - Running post-run listener: _resource_record_post_run 18:26:15 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 33172480; Write bytes: 705705472; Read time: 314; Write time: 2040 18:26:15 INFO - TinderboxPrint: CPU usage
53.4% 18:26:15 INFO - TinderboxPrint: I/O read bytes / time
33,172,480 / 314 18:26:15 INFO - TinderboxPrint: I/O write bytes / time
705,705,472 / 2,040 18:26:15 INFO - TinderboxPrint: CPU idle
2,260.9 (46.6%) 18:26:15 INFO - TinderboxPrint: CPU system
966.9 (19.9%) 18:26:15 INFO - TinderboxPrint: CPU user
1,625.9 (33.5%) 18:26:15 INFO - TinderboxPrint: Swap in / out
435,556,352 / 0 18:26:15 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404009472; Write bytes: 397334016; Read time: 29854; Write time: 722 18:26:15 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30125056; Write bytes: 296361984; Read time: 285; Write time: 1281 18:26:16 INFO - Running post-run listener: _upload_blobber_files 18:26:16 INFO - Blob upload gear active. 18:26:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:26:16 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 18:26:16 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:26:16 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:26:16 INFO - (blobuploader) - INFO - Open directory for files ... 18:26:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:26:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:26:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:18 INFO - (blobuploader) - INFO - Done attempting. 18:26:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:26:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:26:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:19 INFO - (blobuploader) - INFO - Done attempting. 18:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:26:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:21 INFO - (blobuploader) - INFO - Done attempting. 18:26:21 INFO - (blobuploader) - INFO - Iteration through files over. 18:26:21 INFO - Return code: 0 18:26:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:26:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:26:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3de585108c1310d05b98c77653954f027d2a6dba267627ef59184a21ab85a0d949885adeeda8ae8f55f438b4bf67ba7849952133b8b6d3df117f4040137358e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1517cb9d390513d7b201a1b42c29cb75864cc027b8b8b06ec170865a0df0f8fbdaa0a3fd7e84816fd9ebfad7330909eaeba4c1514deb0c245e458349aef12aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cd78d00c3954df98538eb9b8f6a9d183db35773e7c84949dbc6d77a53c04f93869cc3db8b42000b1101ddb3df177ecb15836b9252a38de9b443e366b7b33882"} 18:26:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:26:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:26:21 INFO - Contents: 18:26:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3de585108c1310d05b98c77653954f027d2a6dba267627ef59184a21ab85a0d949885adeeda8ae8f55f438b4bf67ba7849952133b8b6d3df117f4040137358e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1517cb9d390513d7b201a1b42c29cb75864cc027b8b8b06ec170865a0df0f8fbdaa0a3fd7e84816fd9ebfad7330909eaeba4c1514deb0c245e458349aef12aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cd78d00c3954df98538eb9b8f6a9d183db35773e7c84949dbc6d77a53c04f93869cc3db8b42000b1101ddb3df177ecb15836b9252a38de9b443e366b7b33882"} 18:26:21 INFO - Running post-run listener: copy_logs_to_upload_dir 18:26:21 INFO - Copying logs to upload dir... 18:26:21 INFO - mkdir: /builds/slave/test/build/upload/logs 18:26:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.082571 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-22 18:26:21.779299) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 18:26:21.783029) ========= 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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/Firefox/sha512/a3de585108c1310d05b98c77653954f027d2a6dba267627ef59184a21ab85a0d949885adeeda8ae8f55f438b4bf67ba7849952133b8b6d3df117f4040137358e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1517cb9d390513d7b201a1b42c29cb75864cc027b8b8b06ec170865a0df0f8fbdaa0a3fd7e84816fd9ebfad7330909eaeba4c1514deb0c245e458349aef12aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cd78d00c3954df98538eb9b8f6a9d183db35773e7c84949dbc6d77a53c04f93869cc3db8b42000b1101ddb3df177ecb15836b9252a38de9b443e366b7b33882"} build_url:https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011028 build_url: 'https://queue.taskcluster.net/v1/task/fc-Icw-vQUiVV_SOJHlL_g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3de585108c1310d05b98c77653954f027d2a6dba267627ef59184a21ab85a0d949885adeeda8ae8f55f438b4bf67ba7849952133b8b6d3df117f4040137358e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1517cb9d390513d7b201a1b42c29cb75864cc027b8b8b06ec170865a0df0f8fbdaa0a3fd7e84816fd9ebfad7330909eaeba4c1514deb0c245e458349aef12aa5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cd78d00c3954df98538eb9b8f6a9d183db35773e7c84949dbc6d77a53c04f93869cc3db8b42000b1101ddb3df177ecb15836b9252a38de9b443e366b7b33882"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 18:26:21.816413) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-22 18:26:21.816739) ========= 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.RRSafDY7Ss/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.JSCeJCgbLt/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005244 ========= master_lag: 2.97 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-08-22 18:26:24.793527) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 18:26:24.793862) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 18:26:24.795016) ========= ========= Total master_lag: 3.10 =========