builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0250 starttime: 1470805818.67 results: success (0) buildid: 20160809203315 builduid: d756ec85cbda473eba2e02b19a745168 revision: 9bc47f02e32250f3736faa290f1f3c33e4c3c48e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:18.674950) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:18.675376) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:18.675652) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:18.699934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:18.700223) ========= 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.6H1xqLG5bo/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.APmR9NvPZD/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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-09 22:10:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.9M=0.001s 2016-08-09 22:10:18 (15.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133501 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:19.019569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:19.019878) ========= 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.6H1xqLG5bo/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.APmR9NvPZD/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.024813 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:19.068249) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 22:10:19.068600) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9bc47f02e32250f3736faa290f1f3c33e4c3c48e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9bc47f02e32250f3736faa290f1f3c33e4c3c48e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6H1xqLG5bo/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.APmR9NvPZD/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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-09 22:10:19,158 truncating revision to first 12 chars 2016-08-09 22:10:19,158 Setting DEBUG logging. 2016-08-09 22:10:19,158 attempt 1/10 2016-08-09 22:10:19,158 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9bc47f02e322?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 22:10:20,559 unpacking tar archive at: autoland-9bc47f02e322/testing/mozharness/ program finished with exit code 0 elapsedTime=1.669877 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 22:10:20.763974) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:20.764359) ========= script_repo_revision: 9bc47f02e32250f3736faa290f1f3c33e4c3c48e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:20.764813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:20.765132) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:10:20.784101) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-08-09 22:10:20.784704) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6H1xqLG5bo/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.APmR9NvPZD/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:20 INFO - MultiFileLogger online at 20160809 22:10:20 in /builds/slave/test 22:10:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:10:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:10:20 INFO - {'append_to_log': False, 22:10:20 INFO - 'base_work_dir': '/builds/slave/test', 22:10:20 INFO - 'blob_upload_branch': 'Autoland', 22:10:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:10:20 INFO - 'branch': 'Autoland', 22:10:20 INFO - 'buildbot_json_path': 'buildprops.json', 22:10:20 INFO - 'config_files': ('talos/mac_config.py',), 22:10:20 INFO - 'default_actions': ('clobber', 22:10:20 INFO - 'read-buildbot-config', 22:10:20 INFO - 'download-and-extract', 22:10:20 INFO - 'populate-webroot', 22:10:20 INFO - 'create-virtualenv', 22:10:20 INFO - 'install', 22:10:20 INFO - 'run-tests'), 22:10:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:10:20 INFO - 'download_minidump_stackwalk': True, 22:10:20 INFO - 'download_symbols': 'ondemand', 22:10:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:10:20 INFO - 'tooltool.py': '/tools/tooltool.py', 22:10:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:10:20 INFO - '/tools/misc-python/virtualenv.py')}, 22:10:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:10:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:10:20 INFO - 'installer_path': 'installer.exe', 22:10:20 INFO - 'log_level': 'info', 22:10:20 INFO - 'log_name': 'talos', 22:10:20 INFO - 'log_to_console': True, 22:10:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:10:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:10:20 INFO - 'opt_config_files': (), 22:10:20 INFO - 'pip_index': False, 22:10:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:10:20 INFO - 'cmd': ('bash', 22:10:20 INFO - '-c', 22:10:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:10:20 INFO - 'enabled': True, 22:10:20 INFO - 'halt_on_failure': False, 22:10:20 INFO - 'name': 'check_screen_resolution'},), 22:10:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:10:20 INFO - 'cmd': ('bash', 22:10:20 INFO - '-c', 22:10:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:10:20 INFO - 'enabled': True, 22:10:20 INFO - 'halt_on_failure': False, 22:10:20 INFO - 'name': 'check_screen_resolution'},), 22:10:20 INFO - 'run_cmd_checks_enabled': True, 22:10:20 INFO - 'sps_profile': False, 22:10:20 INFO - 'sps_profile_interval': 0, 22:10:20 INFO - 'suite': 'dromaeojs', 22:10:20 INFO - 'system_bits': '32', 22:10:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:10:20 INFO - 'title': 't-yosemite-r7-0250', 22:10:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:10:20 INFO - 'use_talos_json': True, 22:10:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:10:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:10:20 INFO - 'work_dir': 'build'} 22:10:20 INFO - [mozharness: 2016-08-10 05:10:20.982126Z] Running clobber step. 22:10:20 INFO - Running pre-action listener: _resource_record_pre_action 22:10:20 INFO - Running main action method: clobber 22:10:20 INFO - rmtree: /builds/slave/test/build 22:10:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:10:22 INFO - Running post-action listener: _resource_record_post_action 22:10:22 INFO - [mozharness: 2016-08-10 05:10:22.913371Z] Finished clobber step (success) 22:10:22 INFO - [mozharness: 2016-08-10 05:10:22.913536Z] Running read-buildbot-config step. 22:10:22 INFO - Running pre-action listener: _resource_record_pre_action 22:10:22 INFO - Running main action method: read_buildbot_config 22:10:22 INFO - Using buildbot properties: 22:10:22 INFO - { 22:10:22 INFO - "project": "", 22:10:22 INFO - "product": "firefox", 22:10:22 INFO - "script_repo_revision": "production", 22:10:22 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:10:22 INFO - "repository": "", 22:10:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:10:22 INFO - "buildid": "20160809203315", 22:10:22 INFO - "pgo_build": "False", 22:10:22 INFO - "basedir": "/builds/slave/test", 22:10:22 INFO - "buildnumber": 66, 22:10:22 INFO - "slavename": "t-yosemite-r7-0250", 22:10:22 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:10:22 INFO - "platform": "yosemite_r7", 22:10:22 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:10:22 INFO - "branch": "autoland", 22:10:22 INFO - "repo_path": "integration/autoland", 22:10:22 INFO - "revision": "9bc47f02e32250f3736faa290f1f3c33e4c3c48e", 22:10:22 INFO - "stage_platform": "macosx64", 22:10:22 INFO - "builduid": "d756ec85cbda473eba2e02b19a745168", 22:10:22 INFO - "slavebuilddir": "test" 22:10:22 INFO - } 22:10:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:10:22 INFO - Running post-action listener: _resource_record_post_action 22:10:22 INFO - [mozharness: 2016-08-10 05:10:22.916921Z] Finished read-buildbot-config step (success) 22:10:22 INFO - [mozharness: 2016-08-10 05:10:22.917050Z] Running download-and-extract step. 22:10:22 INFO - Running pre-action listener: _resource_record_pre_action 22:10:22 INFO - Running main action method: download_and_extract 22:10:22 INFO - mkdir: /builds/slave/test/build/tests 22:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:22 INFO - trying https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:10:22 INFO - Downloading https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:24 INFO - Downloaded 1199 bytes. 22:10:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:10:24 INFO - Using the following test package requirements: 22:10:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:10:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:10:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:10:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'jsshell-mac.zip'], 22:10:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:10:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:10:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:10:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:10:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:10:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:10:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:10:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:24 INFO - trying https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:10:24 INFO - Downloading https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:25 INFO - Downloaded 16326286 bytes. 22:10:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:10:28 INFO - Return code: 0 22:10:28 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:10: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')]} 22:10:28 INFO - trying https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:10:28 INFO - Downloading https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:29 INFO - Downloaded 16326286 bytes. 22:10:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:10:32 INFO - Return code: 0 22:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:32 INFO - trying https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:10:32 INFO - Downloading https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:33 INFO - Downloaded 14413723 bytes. 22:10:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:10:33 INFO - Return code: 0 22:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:33 INFO - trying https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:10:33 INFO - Downloading https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 22:10:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/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 22:10:36 INFO - Downloaded 110905981 bytes. 22:10:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:10:36 INFO - mkdir: /builds/slave/test/properties 22:10:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:10:36 INFO - Writing to file /builds/slave/test/properties/build_url 22:10:36 INFO - Contents: 22:10:36 INFO - build_url:https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:10:37 INFO - Running post-action listener: _resource_record_post_action 22:10:37 INFO - Running post-action listener: set_extra_try_arguments 22:10:37 INFO - [mozharness: 2016-08-10 05:10:37.260993Z] Finished download-and-extract step (success) 22:10:37 INFO - [mozharness: 2016-08-10 05:10:37.261146Z] Running populate-webroot step. 22:10:37 INFO - Running pre-action listener: _resource_record_pre_action 22:10:37 INFO - Running main action method: populate_webroot 22:10:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:10:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:10:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:10:37 INFO - '--tppagecycles', 22:10:37 INFO - '7'], 22:10:37 INFO - 'tests': ['tsvgm']}}, 22:10:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:10:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:10:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:10:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:10:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['damp', 'tps']}, 22:10:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'tests': ['damp', 'tps']}, 22:10:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['dromaeo_dom']}, 22:10:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:10:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['basic_compositor_video']}, 22:10:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:10:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['a11yr', 22:10:37 INFO - 'ts_paint', 22:10:37 INFO - 'tpaint', 22:10:37 INFO - 'sessionrestore', 22:10:37 INFO - 'sessionrestore_no_auto_restore', 22:10:37 INFO - 'tabpaint']}, 22:10:37 INFO - 'other-e10s': {'tests': ['a11yr', 22:10:37 INFO - 'ts_paint', 22:10:37 INFO - 'tpaint', 22:10:37 INFO - 'sessionrestore', 22:10:37 INFO - 'sessionrestore_no_auto_restore', 22:10:37 INFO - 'tabpaint']}, 22:10:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:10:37 INFO - 'ts_paint', 22:10:37 INFO - 'tpaint', 22:10:37 INFO - 'sessionrestore', 22:10:37 INFO - 'sessionrestore_no_auto_restore', 22:10:37 INFO - 'tabpaint']}, 22:10:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:10:37 INFO - 'ts_paint', 22:10:37 INFO - 'tpaint', 22:10:37 INFO - 'sessionrestore', 22:10:37 INFO - 'sessionrestore_no_auto_restore', 22:10:37 INFO - 'tabpaint']}, 22:10:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['a11yr', 22:10:37 INFO - 'ts_paint', 22:10:37 INFO - 'tpaint', 22:10:37 INFO - 'sessionrestore', 22:10:37 INFO - 'sessionrestore_no_auto_restore', 22:10:37 INFO - 'tabpaint']}, 22:10:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['a11yr', 22:10:37 INFO - 'ts_paint', 22:10:37 INFO - 'tpaint', 22:10:37 INFO - 'sessionrestore', 22:10:37 INFO - 'sessionrestore_no_auto_restore', 22:10:37 INFO - 'tabpaint']}, 22:10:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['tsvgx', 22:10:37 INFO - 'tsvgr_opacity', 22:10:37 INFO - 'tart', 22:10:37 INFO - 'tscrollx', 22:10:37 INFO - 'cart']}, 22:10:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:10:37 INFO - 'tsvgr_opacity', 22:10:37 INFO - 'tart', 22:10:37 INFO - 'tscrollx', 22:10:37 INFO - 'cart']}, 22:10:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'talos_options': ['--disable-e10s'], 22:10:37 INFO - 'tests': ['tp5o']}, 22:10:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'tests': ['tp5o']}, 22:10:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'talos_options': ['--disable-e10s', 22:10:37 INFO - '--xperf_path', 22:10:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:37 INFO - 'tests': ['tp5n']}, 22:10:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:37 INFO - 'talos_options': ['--xperf_path', 22:10:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:37 INFO - 'tests': ['tp5n']}}, 22:10:37 INFO - 'talos.zip': {'path': '', 22:10:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:10:37 INFO - Running post-action listener: _resource_record_post_action 22:10:37 INFO - [mozharness: 2016-08-10 05:10:37.279794Z] Finished populate-webroot step (success) 22:10:37 INFO - [mozharness: 2016-08-10 05:10:37.279905Z] Running create-virtualenv step. 22:10:37 INFO - Running pre-action listener: _resource_record_pre_action 22:10:37 INFO - Running main action method: create_virtualenv 22:10:37 INFO - Creating virtualenv /builds/slave/test/build/venv 22:10:37 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 22:10:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:10:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:10:37 INFO - Using real prefix '/tools/python27' 22:10:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:10:38 INFO - Installing distribute.............................................................................................................................................................................................done. 22:10:40 INFO - Installing pip.................done. 22:10:41 INFO - Return code: 0 22:10:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:41 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 22:10:41 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 22:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:41 INFO - 'HOME': '/Users/cltbld', 22:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:41 INFO - 'LOGNAME': 'cltbld', 22:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:41 INFO - 'MOZ_NO_REMOTE': '1', 22:10:41 INFO - 'NO_EM_RESTART': '1', 22:10:41 INFO - 'PAGER': '/bin/cat', 22:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:41 INFO - 'PWD': '/builds/slave/test', 22:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:41 INFO - 'SHELL': '/bin/bash', 22:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:10:41 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:41 INFO - 'USER': 'cltbld', 22:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:41 INFO - 'XPC_FLAGS': '0x0', 22:10:41 INFO - 'XPC_SERVICE_NAME': '0', 22:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:41 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 22:10:41 INFO - Cleaning up... 22:10:41 INFO - Return code: 0 22:10:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:41 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 22:10:41 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 22:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:41 INFO - 'HOME': '/Users/cltbld', 22:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:41 INFO - 'LOGNAME': 'cltbld', 22:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:41 INFO - 'MOZ_NO_REMOTE': '1', 22:10:41 INFO - 'NO_EM_RESTART': '1', 22:10:41 INFO - 'PAGER': '/bin/cat', 22:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:41 INFO - 'PWD': '/builds/slave/test', 22:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:41 INFO - 'SHELL': '/bin/bash', 22:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:10:41 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:41 INFO - 'USER': 'cltbld', 22:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:41 INFO - 'XPC_FLAGS': '0x0', 22:10:41 INFO - 'XPC_SERVICE_NAME': '0', 22:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:41 INFO - Downloading/unpacking psutil>=3.1.1 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:10:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:44 INFO - Installing collected packages: psutil 22:10:44 INFO - Running setup.py install for psutil 22:10:44 INFO - building 'psutil._psutil_osx' extension 22:10:44 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 22:10:45 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 22:10:45 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 22:10:45 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 22:10:45 INFO - building 'psutil._psutil_posix' extension 22:10:45 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 22:10:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:10:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:10:45 INFO - ^ 22:10:45 INFO - 1 warning generated. 22:10:45 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 22:10:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:45 INFO - Successfully installed psutil 22:10:45 INFO - Cleaning up... 22:10:45 INFO - Return code: 0 22:10:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:10:45 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')]} 22:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:45 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')]} 22:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:45 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:45 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 22:10:45 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 22:10:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:10:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:45 INFO - 'HOME': '/Users/cltbld', 22:10:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:45 INFO - 'LOGNAME': 'cltbld', 22:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:45 INFO - 'MOZ_NO_REMOTE': '1', 22:10:45 INFO - 'NO_EM_RESTART': '1', 22:10:45 INFO - 'PAGER': '/bin/cat', 22:10:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:45 INFO - 'PWD': '/builds/slave/test', 22:10:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:45 INFO - 'SHELL': '/bin/bash', 22:10:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:10:45 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:10:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:45 INFO - 'USER': 'cltbld', 22:10:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:45 INFO - 'XPC_FLAGS': '0x0', 22:10:45 INFO - 'XPC_SERVICE_NAME': '0', 22:10:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:10:45 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 22:10:45 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 22:10:45 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 22:10:45 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 22:10:45 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 22:10:45 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 22:10:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:10:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:10:48 INFO - Installing collected packages: mozsystemmonitor 22:10:48 INFO - Running setup.py install for mozsystemmonitor 22:10:48 INFO - Successfully installed mozsystemmonitor 22:10:48 INFO - Cleaning up... 22:10:48 INFO - Return code: 0 22:10:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:48 INFO - 'HOME': '/Users/cltbld', 22:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:48 INFO - 'LOGNAME': 'cltbld', 22:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:48 INFO - 'MOZ_NO_REMOTE': '1', 22:10:48 INFO - 'NO_EM_RESTART': '1', 22:10:48 INFO - 'PAGER': '/bin/cat', 22:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:48 INFO - 'PWD': '/builds/slave/test', 22:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:48 INFO - 'SHELL': '/bin/bash', 22:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:10:48 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:48 INFO - 'USER': 'cltbld', 22:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:48 INFO - 'XPC_FLAGS': '0x0', 22:10:48 INFO - 'XPC_SERVICE_NAME': '0', 22:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:48 INFO - Downloading/unpacking jsonschema==2.5.1 22:10:48 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 22:10:48 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 22:10:48 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 22:10:48 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 22:10:48 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 22:10:48 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 22:10:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:10:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:10:51 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 22:10:51 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 22:10:51 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 22:10:51 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 22:10:51 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 22:10:51 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 22:10:51 INFO - Downloading functools32-3.2.3-2.tar.gz 22:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:10:51 INFO - warning: no files found matching '*.txt' 22:10:51 INFO - no previously-included directories found matching 'build' 22:10:51 INFO - no previously-included directories found matching 'dist' 22:10:51 INFO - no previously-included directories found matching '.git*' 22:10:51 INFO - Installing collected packages: jsonschema, functools32 22:10:51 INFO - Running setup.py install for functools32 22:10:51 INFO - warning: no files found matching '*.txt' 22:10:51 INFO - no previously-included directories found matching 'build' 22:10:51 INFO - no previously-included directories found matching 'dist' 22:10:51 INFO - no previously-included directories found matching '.git*' 22:10:52 INFO - Successfully installed jsonschema functools32 22:10:52 INFO - Cleaning up... 22:10:52 INFO - Return code: 0 22:10:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:10: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')]} 22:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10: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')]} 22:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:52 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:52 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 22:10:52 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 22:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:52 INFO - 'HOME': '/Users/cltbld', 22:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:52 INFO - 'LOGNAME': 'cltbld', 22:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:52 INFO - 'MOZ_NO_REMOTE': '1', 22:10:52 INFO - 'NO_EM_RESTART': '1', 22:10:52 INFO - 'PAGER': '/bin/cat', 22:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:52 INFO - 'PWD': '/builds/slave/test', 22:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:52 INFO - 'SHELL': '/bin/bash', 22:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:10:52 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:52 INFO - 'USER': 'cltbld', 22:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:52 INFO - 'XPC_FLAGS': '0x0', 22:10:52 INFO - 'XPC_SERVICE_NAME': '0', 22:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:10:52 INFO - Cleaning up... 22:10:52 INFO - Return code: 0 22:10:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:10: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')]} 22:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10: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')]} 22:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:52 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:52 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 22:10:52 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 22:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:52 INFO - 'HOME': '/Users/cltbld', 22:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:52 INFO - 'LOGNAME': 'cltbld', 22:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:52 INFO - 'MOZ_NO_REMOTE': '1', 22:10:52 INFO - 'NO_EM_RESTART': '1', 22:10:52 INFO - 'PAGER': '/bin/cat', 22:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:52 INFO - 'PWD': '/builds/slave/test', 22:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:52 INFO - 'SHELL': '/bin/bash', 22:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:10:52 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:52 INFO - 'USER': 'cltbld', 22:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:52 INFO - 'XPC_FLAGS': '0x0', 22:10:52 INFO - 'XPC_SERVICE_NAME': '0', 22:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:52 INFO - Downloading/unpacking blobuploader==1.2.4 22:10:52 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 22:10:52 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 22:10:52 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 22:10:52 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 22:10:52 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 22:10:52 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 22:10:54 INFO - Downloading blobuploader-1.2.4.tar.gz 22:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:10:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:10:55 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 22:10:55 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 22:10:55 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 22:10:55 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 22:10:55 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 22:10:55 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 22:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:10:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:10:55 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 22:10:55 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 22:10:55 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 22:10:55 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 22:10:55 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 22:10:55 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 22:10:56 INFO - Downloading docopt-0.6.1.tar.gz 22:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:10:56 INFO - Installing collected packages: blobuploader, requests, docopt 22:10:56 INFO - Running setup.py install for blobuploader 22:10:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:10:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:10:56 INFO - Running setup.py install for requests 22:10:56 INFO - Running setup.py install for docopt 22:10:57 INFO - Successfully installed blobuploader requests docopt 22:10:57 INFO - Cleaning up... 22:10:57 INFO - Return code: 0 22:10:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:57 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')]} 22:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:57 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')]} 22:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:57 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:10:57 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 22:10:57 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 22:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:10:57 INFO - 'HOME': '/Users/cltbld', 22:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:10:57 INFO - 'LOGNAME': 'cltbld', 22:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:57 INFO - 'MOZ_NO_REMOTE': '1', 22:10:57 INFO - 'NO_EM_RESTART': '1', 22:10:57 INFO - 'PAGER': '/bin/cat', 22:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:57 INFO - 'PWD': '/builds/slave/test', 22:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:10:57 INFO - 'SHELL': '/bin/bash', 22:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:10:57 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:10:57 INFO - 'USER': 'cltbld', 22:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:57 INFO - 'XPC_FLAGS': '0x0', 22:10:57 INFO - 'XPC_SERVICE_NAME': '0', 22:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:57 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Mb6G0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:57 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-xZ2yes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:57 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-iWce4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:57 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-rfxTFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:57 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-vREtnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:57 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-71Rzo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Bo_rND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-a21_16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-FAzqr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-kKgMxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-RUpOGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-DbjB18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-_5Xrif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-24ZZaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:58 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Qazslg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:59 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-2T75M1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:59 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Q1hvKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:10:59 INFO - Running setup.py install for manifestparser 22:10:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:10:59 INFO - Running setup.py install for mozcrash 22:10:59 INFO - Running setup.py install for mozdebug 22:10:59 INFO - Running setup.py install for mozdevice 22:10:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:10:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:10:59 INFO - Running setup.py install for mozfile 22:10:59 INFO - Running setup.py install for mozhttpd 22:11:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Running setup.py install for mozinfo 22:11:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Running setup.py install for mozInstall 22:11:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Running setup.py install for mozleak 22:11:00 INFO - Running setup.py install for mozlog 22:11:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Running setup.py install for moznetwork 22:11:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:11:00 INFO - Running setup.py install for mozprocess 22:11:01 INFO - Running setup.py install for mozprofile 22:11:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:11:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:11:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:11:01 INFO - Running setup.py install for mozrunner 22:11:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:11:01 INFO - Running setup.py install for mozscreenshot 22:11:01 INFO - Running setup.py install for moztest 22:11:01 INFO - Running setup.py install for mozversion 22:11:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:11:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:11:01 INFO - Cleaning up... 22:11:01 INFO - Return code: 0 22:11:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11: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')]} 22:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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')]} 22:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:01 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:11:01 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 22:11:01 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 22:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:01 INFO - 'HOME': '/Users/cltbld', 22:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:01 INFO - 'LOGNAME': 'cltbld', 22:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:01 INFO - 'MOZ_NO_REMOTE': '1', 22:11:01 INFO - 'NO_EM_RESTART': '1', 22:11:01 INFO - 'PAGER': '/bin/cat', 22:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:01 INFO - 'PWD': '/builds/slave/test', 22:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:01 INFO - 'SHELL': '/bin/bash', 22:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:11:01 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:01 INFO - 'USER': 'cltbld', 22:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:01 INFO - 'XPC_FLAGS': '0x0', 22:11:01 INFO - 'XPC_SERVICE_NAME': '0', 22:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-v4ODmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-pENEbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-sbcyFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-brvBdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-oyXacZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-qJUa6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-tfkZnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-SLq0tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:02 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-KdCqP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:02 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)) 22:11:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-6lZ3Gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:03 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)) 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-cpqkCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:03 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)) 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-I8srNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:03 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)) 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-FcpD1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:03 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)) 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-RpWvo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:03 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)) 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-6R7yPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:03 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)) 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-gkW710-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:03 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)) 22:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:03 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-_UP_mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:03 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)) 22:11:03 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)) 22:11:03 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)) 22:11:03 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)) 22:11:03 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)) 22:11:03 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)) 22:11:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:06 INFO - Downloading blessings-1.6.tar.gz 22:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:11:06 INFO - Installing collected packages: blessings 22:11:06 INFO - Running setup.py install for blessings 22:11:06 INFO - Successfully installed blessings 22:11:06 INFO - Cleaning up... 22:11:06 INFO - Return code: 0 22:11:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:11:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:06 INFO - Reading from file tmpfile_stdout 22:11:06 INFO - Current package versions: 22:11:06 INFO - blessings == 1.6 22:11:06 INFO - blobuploader == 1.2.4 22:11:06 INFO - docopt == 0.6.1 22:11:06 INFO - functools32 == 3.2.3-2 22:11:06 INFO - jsonschema == 2.5.1 22:11:06 INFO - manifestparser == 1.1 22:11:06 INFO - mozInstall == 1.12 22:11:06 INFO - mozcrash == 0.17 22:11:06 INFO - mozdebug == 0.1 22:11:06 INFO - mozdevice == 0.48 22:11:06 INFO - mozfile == 1.2 22:11:06 INFO - mozhttpd == 0.7 22:11:06 INFO - mozinfo == 0.9 22:11:06 INFO - mozleak == 0.1 22:11:06 INFO - mozlog == 3.3 22:11:06 INFO - moznetwork == 0.27 22:11:06 INFO - mozprocess == 0.23 22:11:06 INFO - mozprofile == 0.28 22:11:06 INFO - mozrunner == 6.12 22:11:06 INFO - mozscreenshot == 0.1 22:11:06 INFO - mozsystemmonitor == 0.3 22:11:06 INFO - moztest == 0.7 22:11:06 INFO - mozversion == 1.4 22:11:06 INFO - psutil == 3.1.1 22:11:06 INFO - requests == 1.2.3 22:11:06 INFO - wsgiref == 0.1.2 22:11:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:06 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')]} 22:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:06 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')]} 22:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:06 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:11:06 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 22:11:06 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 22:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:06 INFO - 'HOME': '/Users/cltbld', 22:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:06 INFO - 'LOGNAME': 'cltbld', 22:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:06 INFO - 'MOZ_NO_REMOTE': '1', 22:11:06 INFO - 'NO_EM_RESTART': '1', 22:11:06 INFO - 'PAGER': '/bin/cat', 22:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:06 INFO - 'PWD': '/builds/slave/test', 22:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:06 INFO - 'SHELL': '/bin/bash', 22:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:11:06 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:06 INFO - 'USER': 'cltbld', 22:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:06 INFO - 'XPC_FLAGS': '0x0', 22:11:06 INFO - 'XPC_SERVICE_NAME': '0', 22:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 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)) 22:11:07 INFO - Cleaning up... 22:11:07 INFO - Return code: 0 22:11:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:07 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 0x10ab71a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac06618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9dd949be40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, '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.6H1xqLG5bo/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.APmR9NvPZD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:11:07 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 22:11:07 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 22:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:07 INFO - 'HOME': '/Users/cltbld', 22:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:07 INFO - 'LOGNAME': 'cltbld', 22:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:07 INFO - 'MOZ_NO_REMOTE': '1', 22:11:07 INFO - 'NO_EM_RESTART': '1', 22:11:07 INFO - 'PAGER': '/bin/cat', 22:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:07 INFO - 'PWD': '/builds/slave/test', 22:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:07 INFO - 'SHELL': '/bin/bash', 22:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:11:07 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:07 INFO - 'USER': 'cltbld', 22:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:07 INFO - 'XPC_FLAGS': '0x0', 22:11:07 INFO - 'XPC_SERVICE_NAME': '0', 22:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:11:07 INFO - Cleaning up... 22:11:07 INFO - Return code: 0 22:11:07 INFO - Running post-action listener: _resource_record_post_action 22:11:07 INFO - Running post-action listener: _start_resource_monitoring 22:11:07 INFO - Starting resource monitoring. 22:11:07 INFO - [mozharness: 2016-08-10 05:11:07.417800Z] Finished create-virtualenv step (success) 22:11:07 INFO - [mozharness: 2016-08-10 05:11:07.418765Z] Running install step. 22:11:07 INFO - Running pre-action listener: _resource_record_pre_action 22:11:07 INFO - Running main action method: install 22:11:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:07 INFO - Reading from file tmpfile_stdout 22:11:07 INFO - Detecting whether we're running mozinstall >=1.0... 22:11:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:11:07 INFO - Reading from file tmpfile_stdout 22:11:07 INFO - Output received: 22:11:07 INFO - Usage: mozinstall [options] installer 22:11:07 INFO - Options: 22:11:07 INFO - -h, --help show this help message and exit 22:11:07 INFO - -d DEST, --destination=DEST 22:11:07 INFO - Directory to install application into. [default: 22:11:07 INFO - "/builds/slave/test"] 22:11:07 INFO - --app=APP Application being installed. [default: firefox] 22:11:07 INFO - mkdir: /builds/slave/test/build/application 22:11:07 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'] 22:11:07 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 22:11:41 INFO - Reading from file tmpfile_stdout 22:11:41 INFO - Output received: 22:11:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:11:41 INFO - Running post-action listener: _resource_record_post_action 22:11:41 INFO - [mozharness: 2016-08-10 05:11:41.582415Z] Finished install step (success) 22:11:41 INFO - [mozharness: 2016-08-10 05:11:41.582539Z] Running run-tests step. 22:11:41 INFO - Running pre-action listener: _resource_record_pre_action 22:11:41 INFO - Running main action method: run_tests 22:11:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:11:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:11:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:11:41 INFO - 2016-08-09 22:11:41.597 screenresolution[1635:7367] starting screenresolution argv=screenresolution get 22:11:41 INFO - 2016-08-09 22:11:41.599 screenresolution[1635:7367] Display 0: 1600x1200x32@60 22:11:41 INFO - 2016-08-09 22:11:41.605 screenresolution[1636:7369] starting screenresolution argv=screenresolution list 22:11:41 INFO - Available Modes on Display 0 22:11:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:11:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:11:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:11:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:11:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:11:41 INFO - Intel Iris: 22:11:41 INFO - Chipset Model: Intel Iris 22:11:41 INFO - Type: GPU 22:11:41 INFO - Bus: Built-In 22:11:41 INFO - VRAM (Dynamic, Max): 1536 MB 22:11:41 INFO - Vendor: Intel (0x8086) 22:11:41 INFO - Device ID: 0x0a2e 22:11:41 INFO - Revision ID: 0x0009 22:11:41 INFO - Displays: 22:11:41 INFO - Display: 22:11:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:11:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:11:41 INFO - Display Serial Number: 1600x1200 60 22:11:41 INFO - Main Display: Yes 22:11:41 INFO - Mirror: Off 22:11:41 INFO - Online: Yes 22:11:41 INFO - Rotation: Supported 22:11:41 INFO - Return code: 0 22:11:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:11:41 INFO - Python 2.7.3 22:11:41 INFO - Return code: 0 22:11:41 INFO - grabbing minidump binary from tooltool 22:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:41 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 0x10aad5d30>, '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 0x10a83adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aacdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:11:41 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 22:11:41 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 22:11:41 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 22:11:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:11:41 INFO - Return code: 0 22:11:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:11:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:11:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:11:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:11:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:11:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--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 22:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0250 --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 22:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6H1xqLG5bo/Render', 22:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:41 INFO - 'HOME': '/Users/cltbld', 22:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:41 INFO - 'LOGNAME': 'cltbld', 22:11:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:11:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:41 INFO - 'MOZ_NO_REMOTE': '1', 22:11:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:11:41 INFO - 'NO_EM_RESTART': '1', 22:11:41 INFO - 'PAGER': '/bin/cat', 22:11:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:41 INFO - 'PWD': '/builds/slave/test', 22:11:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:41 INFO - 'SHELL': '/bin/bash', 22:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.APmR9NvPZD/Listeners', 22:11:41 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:41 INFO - 'USER': 'cltbld', 22:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:41 INFO - 'XPC_FLAGS': '0x0', 22:11:41 INFO - 'XPC_SERVICE_NAME': '0', 22:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--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 22:11:42 INFO - mozversion application_buildid: 20160809203315 22:11:42 INFO - mozversion application_changeset: 9bc47f02e32250f3736faa290f1f3c33e4c3c48e 22:11:42 INFO - mozversion application_display_name: Nightly 22:11:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:11:42 INFO - mozversion application_name: Firefox 22:11:42 INFO - mozversion application_remotingname: firefox 22:11:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:11:42 INFO - mozversion application_vendor: Mozilla 22:11:42 INFO - mozversion application_version: 51.0a1 22:11:42 INFO - mozversion platform_buildid: 20160809203315 22:11:42 INFO - mozversion platform_changeset: 9bc47f02e32250f3736faa290f1f3c33e4c3c48e 22:11:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:11:42 INFO - mozversion platform_version: 51.0a1 22:11:42 INFO - using testdate: 1470805902 22:11:42 INFO - actual date: 1470805902 22:11:42 INFO - starting webserver on 'localhost:49265' 22:11:42 INFO - SUITE-START | Running 2 tests 22:11:42 INFO - TEST-START | dromaeo_css 22:11:42 INFO - operating with platform_type : mac_ 22:11:42 INFO - Initialising browser for dromaeo_css test... 22:11:42 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmp8Qh5FO/profile http://localhost:49265/getInfo.html) 22:11:44 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 22:11:44 INFO - PROCESS | 1646 | colorDepth:24 22:11:44 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 22:11:44 INFO - PROCESS | 1646 | __metrics 22:11:44 INFO - PROCESS | 1646 | 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" 22:11:44 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:11:45 INFO - TEST-INFO | 1646: exit 0 22:11:45 INFO - Browser initialized. 22:11:45 INFO - Running cycle 1/1 for dromaeo_css test... 22:11:45 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmp8Qh5FO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:11:57 INFO - PROCESS | 1648 | RSS: Main: 243646464 22:11:57 INFO - PROCESS | 1648 | 22:12:48 INFO - PROCESS | 1648 | 1470805968697 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] 22:14:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 22:15:02 INFO - PROCESS | 1648 | RSS: Main: 458649600 22:15:02 INFO - PROCESS | 1648 | 22:18:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 22:18:05 INFO - PROCESS | 1648 | RSS: Main: 385183744 22:18:05 INFO - PROCESS | 1648 | 22:21:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 22:21:08 INFO - PROCESS | 1648 | RSS: Main: 380846080 22:21:08 INFO - PROCESS | 1648 | 22:24:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 22:24:11 INFO - PROCESS | 1648 | RSS: Main: 370257920 22:24:11 INFO - PROCESS | 1648 | 22:27:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 22:27:15 INFO - PROCESS | 1648 | RSS: Main: 352309248 22:27:15 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 22:30:19 INFO - PROCESS | 1648 | RSS: Main: 408616960 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | __start_tp_report 22:30:19 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 22:30:19 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 22:30:19 INFO - PROCESS | 1648 | |i|pagename|runs| 22:30:19 INFO - PROCESS | 1648 | |0;dojo.html;2979.4785912465327;3346.995901229631;3358.66413358664;3371.4100032494307;3423.6576342365765;5623.128415095667;5682.147677848325;5714;5747.971260143699;5757.251557297556;5729.770809167639;5753.395893431192;5790.797322093727;5840.53275737941;5843.941560584393;5123.257127716479;5123.692578445287;5206.401263854667;5211.869703257411;5362.3029006229135;5328.46715328466;5364.892702145955;5373.489518495737;5379.35447746271;5416.91874621881;5153.793848246066;5173.9741301293625;5188.922166167473;5253.737313134328;5263.078961181797;4407.206702793496;5202.219667049935;5208.218767184915;5287.920681189786;5319.468053194688;5220.530152286275;5231;5258.8159414420315;5283.233931079972;5312.787488500456;5628.352739434977;5684.744186511617;5825.038868586678;5857.736401861926;5872.471477566996;6212.409821066992;6265.154204182422;6286.937130628681;6303.464205542535;6329.493640508748;6286.528510361742;6324.146240257553;6329.145565348665;6330.240371155491;6348.269948955883;5710.914336284876;5776.624519406225;5918.171455996121;5963.165156878041;5976.671283079386;6241.251049874044;6284.748610055638;6288.213973253343;6338.429541341301;6341.460975817107;6245.531585131133;6264.21697287839;6265.71804268809;6269.749210031548;6272.278687950853;6152.692365381713;6266.624002559861;6266.937330626726;6339.429451349309;6353.110564520971;6161.6611086389785;6162.7226774795245;6238.407351301619;6252.218472690914;6305.432511074026;6287.622742635456;6292.590981586274;6299.811005669837;6313.905291420632;6314.273858506239;4992.525710057539;5016.648834581617;5043.142665746832;5044.747762611854;5070.594352451795;4389.297712366006;4398.64810815134;4416.227160246944;4486.080353527519;4512.887177820581;5749.338826034976;5792.4497172768515;5805.8548536286935;5832.62087964281;5857.4435428634215;5354.598405119632;6295.401936815995;6318.273398559225;6330.905036424458;6347.619142851444;5617.129344951539;5642.069058605366;5688.744006519716;5761.711914404263;5776.855578610484;5464.26232458613;5471.261379713687;5482.930828488406;5488.396276409591;5493.423190565013;6206.751729930843;6252.374762523711;6260.686965651699;6282.3089460158435;6342.270638876404;6334.081558173947;6340.397662222082;6343.904841427475;6346.650934198663;6386.297507274289;6284.591501552384;6319.58922670025;6339.936600633934;6343.524235682435;6344.365563443619;6141.486007784186;6173.629582225081;6202.596831205957;6265.091561723616;6285.96857015712;6272.435480806749;6310.05349197624;6331.873362532816;6344.936550634619;6346.587471814318;5971;6194.163787888758;6215.285242197205;6227.283862355705;6235.657038862906;6007.759689612367;6098.26820781509;6105.206323177958;6113;6116.29662588808;5901.114832775118;5916.852078698067;5918.437748413894;5929.407059294036;5931.614445061057;6056.66688332146;6100.2984656763265;6117.755289788359;6120.632762034292;6130.969345153246;5937.139114828412;5937.614055086406;5946.405359464019;5951.256092988377;5956.612820166675 22:30:19 INFO - PROCESS | 1648 | |1;ext.html;472.70692170854073;487.7512468640993;510.86717453462137;516.8191133103412;518.0105997544689;30362.54456183159;40244.59755402449;41216.58783412165;41995.37006944898;43382.349264761055;42622.78688606553;43012.13975720484;43043.78478107613;43182.35226471606;43197.7840110799;8617.052124266325;9664.80670386592;9858.457784821832;9860.30977831552;9869.802603947917;8724.040355560883;9622.230221582256;11826.231294965799;11868.940655296712;12220.388980550937;11911.463984120735;11982.640520784347;11983.64049078529;12000.7599848003;12007.279563226191;11870.169088163831;11951.940240298787;11953.22304050234;11966.880331196664;11972.820407693891;9985.550650220757;11741.236819606784;11843.348615826126;11854.110941679322;11860.703482412922;39369.40945885814;39613.80193099016;39695.60304396948;39757.80121099376;39837.20325593472;3541.8406171722336;3587.0494319005484;3687.520622319108;3764.3224219640456;4008.118213992917;25482.617760733603;31131;31140.844295778377;31457.685423145704;31892.681073189437;3200.9359812803614;3246.2533617267923;3328.8169150696704;3358.2108204571905;3368.865245390206;486184;487273.5636321796;488236;488829;489292;56521;56906;57171.71414142986;57192.714036430385;57211.427885721445;61747;61842;61939;61995.690021549606;62033.37966620366;16009.199540022952;16222.513324600044;16231.918840405722;16240.269187886573;16259.349626015064;11536.538538458535;11730.237534560227;12663.430145643284;12736.299503527394;12746.80879786804;12823.487060517662;14916.254187290591;15488.148151851756;15643.843561564467;15734.527964161094;6327.303996560375;6341.334159913143;6354.555181137276;6415.647139407378;6421.422072013448;869.2046777198792;905.4657751926395;935.6397786852111;971.946542940145;1079.4548752879746;29263.414731705674;29852.104436866928;29866.850665746966;30392;30460.08619741411;957.5308099031424;1059.9364038157735;1062.8671416072991;1107.4573459005192;1109.944502774858;13321.933390332986;14856.851431485762;14907.776383354258;15335.30991105403;15398.922075454835;633389;637928;638361;639485.8025709934;639514;58647.120293195636;58997.41002589919;59008.70495647494;59009.114863277086;59064.70467647634;1235.8331625230658;1345.9528916487875;1366.9726605467836;1387.7432674955166;1395.783653533704;30193.69806301909;31025.534616981215;31077.689223108384;31320.632548873116;32404.67595324111;48760.51239487559;49111;49443.01113977772;49524;49534.50465495299;548387;549907;553543;554305;556089;25756.356091097125;25788.61317080283;26118.86940565285;26465.412075275544;26932.596011059457;47631.52368476271;47833.52166478291;48039.75980120077;48107.27839082487;48587.27119093146;38621;38730.225395492496;38892.6110738889;39883;40010.399844002946;38464.2307153861;38554.80722596369;38590.42114368343;40007.59992400039;40205;37489.4376584346;37640.477251348646;37785.24429511449;38186.61813381831;38449.03877403087;32857;33396.83301583477;33950.81172159062;33968.830155849064;34137.658623413445;24200.819978379728;25386.619200712372;25582.104626338732;25999.480010399308;26185.476290474464 22:30:19 INFO - PROCESS | 1648 | |2;jquery.html;352.22510476950714;370.2798057777622;376.99623003769955;378.78409306695187;399.7561487492631;14977.775333370008;15300.15849128299;20787.792122078776;20824.375268741926;20854.374368768924;20401;20903.47741306468;21044.684329735028;21187.152513899466;21363.6795448068;5658.632188907728;5709.914351284724;5828.213191219194;5963.1055341698875;5965.254343207099;4127.380892866064;5868.178455016301;5978.491828194608;5979.252593425822;5982.491488223506;4241.978790106061;4243.405923170743;4325.091730736549;4521.638268938477;6506.772262970797;6259.87480250397;6470.09418681385;6516.413522782948;6563.803085907407;6566.146400967844;5661.2357331760095;5662.546996240332;5663.631863928831;5666.5183459406;5669.206311116447;25142;26296.8166432511;26301.73698263031;26322.94708211669;26829.597556036675;2306.746257911628;2478.31846242283;2490.4645501217324;2525.5075260324243;2556.578164602838;14578.635534111627;14858.811295096366;15213.31540080688;15864.682706345924;16339.18304084803;1756.6574517969002;1840.9723854142198;1904.1526520698294;2139.882306473143;2167.2739632223224;135872;143183.56816431807;145051.27474362665;145395.27302363422;149069;16104;20418;21240.513164078675;21578.892105539373;21727.13091476323;32177;35467.82266088705;35493;35613.82193089018;35647.64352356495;9009.729708108767;10546.789064218672;10807.67576972692;10884.782304353868;10891.346519208873;8933.553322333857;8938.731838044869;8951.283897288202;9101.317401194805;9109.134632209929;9518;9533.618655253851;9702.913873517227;9727.270454715925;9748.92761796202;3271.934561308808;3730.1420673245307;3733.771078943409;3744.277632289123;3760.172761992357;837.4933165434946;850.566211232276;894.2935081285841;940.7789169545151;942.7595963029521;20665.863377514383;21086.05112769929;21168.894155529124;21261.255856044965;21429.142834286766;903.6295119001178;929.2937367600672;931.2270815223349;937.2127412973135;981.6809536900536;10940.945295273632;11200.271982321123;11246.550137994389;11253.268537545033;11290.830637540443;568712;568913;577903.2209677934;578486;582305;122259;124193;130824.69175308316;131577.34211328882;133851;955.3551352836807;959.3140904253493;992.374803873555;993.4238141877694;993.9403635781731;13039;18749.875007499595;18965;19164.685433440598;19353.612927741648;47924.7603761979;50330.748346258035;55760.16359754688;55819.16271255853;56459.87080258454;80704.59647701724;80857.3828523438;80999;81555;83284;8014.679412823655;8036.196380361917;8140.75577732669;8180;8198.549079800669;10699.983501567338;10924.945375273073;11119.83320250213;13203.933980330037;13240.271785051915;11078.667639970814;11193.720156995814;11428.657140285804;11462.369569673749;11475.713107172054;11185.72035699114;11190.552377905118;11210.215284929976;11575.363355015555;11603.88396116028;11408.771824563628;11560.884391156318;11595.188336816342;11718;11755.412229388325;7971.202879711982;8207.220314070153;8213.3429325655;8392.622331994953;8444.535550544533;11096.33421994683;11107.333559986426;11161.55353785872;11540.538378464767;11543.653690389301 22:30:19 INFO - PROCESS | 1648 | |3;mootools.html;1318.7758081126208;1480.940762369505;1516.0297409657821;1688.9746653800196;1869.2522990803668;7704.576248306341;8385.87421188682;8700.956495217531;8706.12938706129;8819.426737262074;8589.398742088057;8672.392932494713;8676.392652514327;8681.913180868189;8684.652613895436;2291.9083236670513;2322.0479603362623;2325.4883925536355;2327.045911176418;2328.6157783965627;2278.270953294946;2313.8727369994645;2316.8609883406966;2317.0615900560215;2320.0487800002;2269.8524595901295;2293.1973809166743;2293.2088429491882;2306.4695120122383;2310.9768902311016;2267.784560466753;2280.8859557022165;2287.9084836606517;2288.782565656268;2292.5071109711407;2284.862908225512;2285.325828880484;2285.725712914444;2287.553926984239;2287.645414960684;8588.957055214747;8652.134786521297;8690.608922598434;8772.824543509094;8779.122087791233;4702.365180700596;5112.974435127838;5299.072662284085;5303.071962406602;5312.442193569658;3034.226272300549;3443.6900678939014;3640;3656.909911125095;3665.102049997741;921.433318509112;1043.1654676259038;1078.5577913055688;1092.4428541443842;1095.9232853700244;11449.885501145049;11459.942700286529;11529.942350288195;11544.942275288653;11553.19127661064;4292.5492823253735;4363.38912552243;4529.048899731028;4539.0694907543875;4546.74992875395;8980.8652870207;9001.234895033864;9004.8649270261;9007.234385077341;9015.504147271962;5134.922976155361;5156.381234251914;5158.045761534128;5164.354455693038;5165;3938.8818335449982;4355.216061109031;4480.462344518679;4695.95304046962;4847.636427267969;2919.948818425365;3538.628444013393;3594.6944509716445;3668.4680721295263;3704.7962362070343;1148.8621365436202;1163.6334554615269;1164.8019836627793;1175.2126075529416;1177.652592485219;845.8350621628723;898.2275243290765;900.5632268349846;904.4030939579977;908.1962463219966;5622.97188514063;5705.5435565154685;5972.46247837688;5980.431858973478;6057.091436284592;863.456022705704;942.6700654770775;946.2950102173925;957.8323793336277;1005.0903931941605;3895.4156876468755;3944.934867585793;4009.218202450494;4031.274370613259;4066.9629244542452;9831.410115393099;10530.420826854597;10676.839847402296;10689.893101069045;10699.304545204537;10347.793044139074;10657.307275027099;10659.520321585547;10663.573457061631;10668.839967400496;7956.443048986515;8188.713395031145;8191.017077950684;8204.097549269456;8212.384071194685;5766;5802.1296805479515;5924.7037648118285;5934.109883517507;5935.7032148391545;9846.556904939152;9907.950460247941;9922.900770992197;9949.303548751517;9957.651482198089;9575.04249575037;9947.602095916372;9949.204063675033;10113.59545618167;10114.8482772757;6957.095577574883;7160.713571457083;7355.485116041826;7519.774406767806;7536.397088233038;7572.053493313336;7643.694252229849;7806.453548251795;7870.252326029018;7877.291043806085;7282.453815963718;7394.4454165936695;7427.554346738997;7513.098428188652;7583.544987300559;7245.637718114179;7251.093613298338;7274.272572742683;7293.927060729537;7351.8162045949275;6684;6870.381665650116;6964.408025317702;7029.191642961125;7034.648267586498;4974.850754477371;4995.025969935828;5056.544910958031;5064.620153488429;5141.640085194001;4895.157003690967;4943.678660887031;5074.797008119782;5184.351956005499;5193.584513239008 22:30:19 INFO - PROCESS | 1648 | |4;prototype.html;266.46041765425025;285.3622154484726;292.34660533707165;304.6298747022368;311.7599448424713;5057.949420505794;5297.258383826263;5367.302250707407;5375.381831089426;5400.864978375543;4496.6402687784985;5095.617828662856;5207.192885102806;5295.841124766253;5303.151495760679;4033.878983630496;4034.9798251008706;4035.778032208227;4036.8587099451597;4051.1897620475875;3375.7974521528663;3733.8133093345223;4038.676905847525;4069.6540794032544;4081.69387295954;3674.853005879775;3751.2181713686055;3768.7550309229814;3919.392494163409;4073.4704488416464;3329.900102996914;3987.601239875989;3989.3816458448987;3992.201559688074;4015.4980627421573;3423.9043506078065;3430.9313813723834;3431.7940923544666;3434.364642541113;3438.381091403546;4715.750326163569;5176.153115786303;5186.7925282988635;5206.427292997767;5249.396319423277;4326.437563116806;4524.5701658342405;4569.04050149469;4578.527595098642;4579.839910461771;4578.840599985999;4664.533546645308;5641.830745077613;5644.8871022579315;5645.689487078209;3685.4840322354894;3807.904802379935;3837.769733815952;3841.848564803691;3848.0177862156775;4462.732236065846;5590.86022849428;5700.429957004308;5716.314042314907;5901.557383196277;3213.775035747475;3564.108972756811;3706.221693444353;3864.478295430081;3867.7872717000273;3331.283773988604;3932.2528719543197;3937.527496700357;3940.2119576084324;3946.861859834892;4217.894552636148;4376.934345984812;4559.316102584573;4583.289590113538;4588.5585810642015;4712.761438316772;5065.797368105234;5302.655327403706;5550.417206193372;5601.579881508903;1120.8823073577157;1324.993005315967;1328.7143264198244;1334.4662135145825;1338.3642769684323;764.896738940247;831.2477208126655;868.3357231717741;888.4624801994829;896.3462411850126;4179.101493178981;5035.597152227813;5199.506046925536;5201.401838788512;5268.552173065255;5153.819616313486;5298.894022119536;5440;5710.229119068956;5711.171880077365;4152.480939882515;5218.60790486187;5250.007495727475;5259.947400526023;5287.762050707727;2221.677856710768;2464.728879823218;2521.344450442898;2525.330787341356;2536.087008676875;4635.884102897455;4828.8792780180775;4871.683340582851;4877.780499877514;4935.75321233944;4419.574829507158;4427.977860110744;4501.68488205829;4929.211326187793;4992.80028798844;4952.257161425815;4958.975205123951;4961.692812842046;5013.465506817684;5016.867831171738;3923.1172986077904;4682.812687492462;4778.044391121859;4837.895515450649;4858.684185527929;4193.643540299109;4292.720973136736;4726.235152435864;4763.7618119094595;4765.046990601824;4199.454070970754;4403.471583409883;4949.554540091409;5667.773289068391;5846.298444186725;3151.755056752524;3319.5352650629416;3724.1062145085525;3791.8483260669263;3832.1760821423527;5275.788968441219;5371.382291036424;5441.782328706808;5803.535717142534;5814.825555233349;4779.904401912012;5253.474652534716;5519;5646.661200327993;5649.887002259849;4856.562909338035;4880.585150262267;5549.972250138723;5584.078627026495;5588.423409018313;5033.446320904771;5384.865378365572;5469.644473109235;5518.696471694096;5519.420460851713;4965.652404331662;5327.733613319241;5540.836424350931;5550;5564.888702226014;4843.709377437365;5032.647714659939;5418.864528386822;5454.29094217765;5475.972620136874 22:30:19 INFO - PROCESS | 1648 | |5;yui.html;706.6007705646309;720.700909122714;739.2496615934828;766.5439063757062;773.1920143450093;14705.41178352866;16195.91902040491;16210.75683864743;16229.431969881041;16263.430779922704;14823.481178158767;14825.184614846206;14844.257787110628;14858.182799945998;14865.256737163152;662.579262168524;674.733480275291;824.8927639406869;833.0503226321982;846.1158089796147;836.640244694781;855.8288342331558;857.1128881607538;857.3355649371756;857.9918595650117;896.000958930794;897.4974014551839;898.27239935652;898.8696638987343;899.4288626722046;706.4030893894651;879.6261588824788;894.2130924786212;894.7136916186823;896.5203616065373;672.5426709837309;674.0058413839596;674.6053558668185;674.6660403100448;674.9831254218635;14918.776218356734;14946.177960212184;14962.47631332909;14968.850311496963;15023.098614083081;11999.580014699444;13498.730025399534;13679.658008549766;13707.725845483035;13906.652333691638;12484.75030499385;12715.364231788373;12799.808002879965;12863.356832158355;13403.061785675007;4558.407407037097;4561.1105834362315;4580.811588451262;4710.29345598163;4715.1512727709;116163.41918290438;116679.41660291729;117377;118419;118836.40581797122;10957.93812385171;11042.33745975244;11108.222424430212;11162.330260184253;11343.773124537463;21537.784622153576;21563.353099407042;21564.137434502758;21583.13667453284;21587.460313491974;7758.68965241384;7942.364610831235;7999.560024198725;8004.959975200203;8105.797355066053;4326;4443.33349997503;4459.063596644676;4561.771911404417;4572.039871626929;3433.4334834752485;3493.7554371193773;3546.4857595648486;3548.8757893473603;3552.005438477179;637.8915584350672;646.6540400885503;657.1489920552896;658.5521845145347;668.6890595872942;564.4101913500402;592.5674257791836;638.9680665724821;667.2660073918651;678.4233401608692;8997.896157510253;9186.600957645715;9205.51419414596;9519.571619277149;10033.799324013478;567.3843879390824;587.7325816753367;607.8480379905023;629.468099455959;658.3120638932327;4506.954930450719;4687.648426368036;4706.858794236178;4731.810727570927;4802.807887684454;84526.57736711277;84545.6708840674;85736.14263857406;133299;144247;106775;118416;118833;120208;123412;102006;105982;106405.46797265964;108538.45730771296;110229.89770102357;8867.600957957036;9102.033738759887;9147.542622868963;9258.768530786514;9319.30105242096;55703.16445253405;55959.72020139873;56165.43834561602;56345.43654563402;57586.13620795775;79377.60311198638;82219.58890205511;87559;89212;90775.0922490793;10560.260781745204;11899.345535995257;11942.462589183682;11944.283342999448;12079.637610871689;13400.530981415604;13409;13641.86358136406;13901.304934753423;13907.331120265633;12052.819207712068;12354.320512371905;12370.505179793068;12503.56237531682;12838.614841554769;12134.33261170644;12220.144589878622;12649.304288264233;12702.555410560955;12737.93631031839;12088.516459341528;12192.390380480763;12445.066620003718;12607.117501774788;12619.242845429304;10373.429461379697;10578.682639520826;10806.405647689453;10915;11181.72045698864;9074.364794464323;9467.49111485443;9839.852402214116;10127.696169114939;10683 22:30:19 INFO - PROCESS | 1648 | __end_tp_report 22:30:19 INFO - PROCESS | 1648 | __start_cc_report 22:30:19 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2426 22:30:19 INFO - PROCESS | 1648 | __end_cc_report 22:30:19 INFO - PROCESS | 1648 | __startTimestamp1470807019387__endTimestamp 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | ------- Summary: start ------- 22:30:19 INFO - PROCESS | 1648 | Number of tests: 6 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5782.72 Median:5951.26 stddev:658.11 (11.1%) stddev-sans-first:622.30 22:30:19 INFO - PROCESS | 1648 | Values: 2979.5 3347.0 3358.7 3371.4 3423.7 5623.1 5682.1 5714.0 5748.0 5757.3 5729.8 5753.4 5790.8 5840.5 5843.9 5123.3 5123.7 5206.4 5211.9 5362.3 5328.5 5364.9 5373.5 5379.4 5416.9 5153.8 5174.0 5188.9 5253.7 5263.1 4407.2 5202.2 5208.2 5287.9 5319.5 5220.5 5231.0 5258.8 5283.2 5312.8 5628.4 5684.7 5825.0 5857.7 5872.5 6212.4 6265.2 6286.9 6303.5 6329.5 6286.5 6324.1 6329.1 6330.2 6348.3 5710.9 5776.6 5918.2 5963.2 5976.7 6241.3 6284.7 6288.2 6338.4 6341.5 6245.5 6264.2 6265.7 6269.7 6272.3 6152.7 6266.6 6266.9 6339.4 6353.1 6161.7 6162.7 6238.4 6252.2 6305.4 6287.6 6292.6 6299.8 6313.9 6314.3 4992.5 5016.6 5043.1 5044.7 5070.6 4389.3 4398.6 4416.2 4486.1 4512.9 5749.3 5792.4 5805.9 5832.6 5857.4 5354.6 6295.4 6318.3 6330.9 6347.6 5617.1 5642.1 5688.7 5761.7 5776.9 5464.3 5471.3 5482.9 5488.4 5493.4 6206.8 6252.4 6260.7 6282.3 6342.3 6334.1 6340.4 6343.9 6346.7 6386.3 6284.6 6319.6 6339.9 6343.5 6344.4 6141.5 6173.6 6202.6 6265.1 6286.0 6272.4 6310.1 6331.9 6344.9 6346.6 5971.0 6194.2 6215.3 6227.3 6235.7 6007.8 6098.3 6105.2 6113.0 6116.3 5901.1 5916.9 5918.4 5929.4 5931.6 6056.7 6100.3 6117.8 6120.6 6131.0 5937.1 5937.6 5946.4 5951.3 5956.6 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:69435.49 Median:38317.83 stddev:150239.93 (392.1%) stddev-sans-first:150572.17 22:30:19 INFO - PROCESS | 1648 | Values: 472.7 487.8 510.9 516.8 518.0 30362.5 40244.6 41216.6 41995.4 43382.3 42622.8 43012.1 43043.8 43182.4 43197.8 8617.1 9664.8 9858.5 9860.3 9869.8 8724.0 9622.2 11826.2 11868.9 12220.4 11911.5 11982.6 11983.6 12000.8 12007.3 11870.2 11951.9 11953.2 11966.9 11972.8 9985.6 11741.2 11843.3 11854.1 11860.7 39369.4 39613.8 39695.6 39757.8 39837.2 3541.8 3587.0 3687.5 3764.3 4008.1 25482.6 31131.0 31140.8 31457.7 31892.7 3200.9 3246.3 3328.8 3358.2 3368.9 486184.0 487273.6 488236.0 488829.0 489292.0 56521.0 56906.0 57171.7 57192.7 57211.4 61747.0 61842.0 61939.0 61995.7 62033.4 16009.2 16222.5 16231.9 16240.3 16259.3 11536.5 11730.2 12663.4 12736.3 12746.8 12823.5 14916.3 15488.1 15643.8 15734.5 6327.3 6341.3 6354.6 6415.6 6421.4 869.2 905.5 935.6 971.9 1079.5 29263.4 29852.1 29866.9 30392.0 30460.1 957.5 1059.9 1062.9 1107.5 1109.9 13321.9 14856.9 14907.8 15335.3 15398.9 633389.0 637928.0 638361.0 639485.8 639514.0 58647.1 58997.4 59008.7 59009.1 59064.7 1235.8 1346.0 1367.0 1387.7 1395.8 30193.7 31025.5 31077.7 31320.6 32404.7 48760.5 49111.0 49443.0 49524.0 49534.5 548387.0 549907.0 553543.0 554305.0 556089.0 25756.4 25788.6 26118.9 26465.4 26932.6 47631.5 47833.5 48039.8 48107.3 48587.3 38621.0 38730.2 38892.6 39883.0 40010.4 38464.2 38554.8 38590.4 40007.6 40205.0 37489.4 37640.5 37785.2 38186.6 38449.0 32857.0 33396.8 33950.8 33968.8 34137.7 24200.8 25386.6 25582.1 25999.5 26185.5 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:36417.66 Median:1812.08 stddev:96796.07 (5341.7%) stddev-sans-first:97029.73 22:30:19 INFO - PROCESS | 1648 | Values: 352.2 370.3 377.0 378.8 399.8 14977.8 15300.2 20787.8 20824.4 20854.4 20401.0 20903.5 21044.7 21187.2 21363.7 5658.6 5709.9 5828.2 5963.1 5965.3 4127.4 5868.2 5978.5 5979.3 5982.5 4242.0 4243.4 4325.1 4521.6 6506.8 6259.9 6470.1 6516.4 6563.8 6566.1 5661.2 5662.5 5663.6 5666.5 5669.2 25142.0 26296.8 26301.7 26322.9 26829.6 2306.7 2478.3 2490.5 2525.5 2556.6 14578.6 14858.8 15213.3 15864.7 16339.2 1756.7 1841.0 1904.2 2139.9 2167.3 135872.0 143183.6 145051.3 145395.3 149069.0 16104.0 20418.0 21240.5 21578.9 21727.1 32177.0 35467.8 35493.0 35613.8 35647.6 9009.7 10546.8 10807.7 10884.8 10891.3 8933.6 8938.7 8951.3 9101.3 9109.1 9518.0 9533.6 9702.9 9727.3 9748.9 3271.9 3730.1 3733.8 3744.3 3760.2 837.5 850.6 894.3 940.8 942.8 20665.9 21086.1 21168.9 21261.3 21429.1 903.6 929.3 931.2 937.2 981.7 10940.9 11200.3 11246.6 11253.3 11290.8 568712.0 568913.0 577903.2 578486.0 582305.0 122259.0 124193.0 130824.7 131577.3 133851.0 955.4 959.3 992.4 993.4 993.9 13039.0 18749.9 18965.0 19164.7 19353.6 47924.8 50330.7 55760.2 55819.2 56459.9 80704.6 80857.4 80999.0 81555.0 83284.0 8014.7 8036.2 8140.8 8180.0 8198.5 10700.0 10924.9 11119.8 13203.9 13240.3 11078.7 11193.7 11428.7 11462.4 11475.7 11185.7 11190.6 11210.2 11575.4 11603.9 11408.8 11560.9 11595.2 11718.0 11755.4 7971.2 8207.2 8213.3 8392.6 8444.5 11096.3 11107.3 11161.6 11540.5 11543.7 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5590.69 Median:5138.28 stddev:3140.35 (61.1%) stddev-sans-first:3132.75 22:30:19 INFO - PROCESS | 1648 | Values: 1318.8 1480.9 1516.0 1689.0 1869.3 7704.6 8385.9 8701.0 8706.1 8819.4 8589.4 8672.4 8676.4 8681.9 8684.7 2291.9 2322.0 2325.5 2327.0 2328.6 2278.3 2313.9 2316.9 2317.1 2320.0 2269.9 2293.2 2293.2 2306.5 2311.0 2267.8 2280.9 2287.9 2288.8 2292.5 2284.9 2285.3 2285.7 2287.6 2287.6 8589.0 8652.1 8690.6 8772.8 8779.1 4702.4 5113.0 5299.1 5303.1 5312.4 3034.2 3443.7 3640.0 3656.9 3665.1 921.4 1043.2 1078.6 1092.4 1095.9 11449.9 11459.9 11529.9 11544.9 11553.2 4292.5 4363.4 4529.0 4539.1 4546.7 8980.9 9001.2 9004.9 9007.2 9015.5 5134.9 5156.4 5158.0 5164.4 5165.0 3938.9 4355.2 4480.5 4696.0 4847.6 2919.9 3538.6 3594.7 3668.5 3704.8 1148.9 1163.6 1164.8 1175.2 1177.7 845.8 898.2 900.6 904.4 908.2 5623.0 5705.5 5972.5 5980.4 6057.1 863.5 942.7 946.3 957.8 1005.1 3895.4 3944.9 4009.2 4031.3 4067.0 9831.4 10530.4 10676.8 10689.9 10699.3 10347.8 10657.3 10659.5 10663.6 10668.8 7956.4 8188.7 8191.0 8204.1 8212.4 5766.0 5802.1 5924.7 5934.1 5935.7 9846.6 9908.0 9922.9 9949.3 9957.7 9575.0 9947.6 9949.2 10113.6 10114.8 6957.1 7160.7 7355.5 7519.8 7536.4 7572.1 7643.7 7806.5 7870.3 7877.3 7282.5 7394.4 7427.6 7513.1 7583.5 7245.6 7251.1 7274.3 7293.9 7351.8 6684.0 6870.4 6964.4 7029.2 7034.6 4974.9 4995.0 5056.5 5064.6 5141.6 4895.2 4943.7 5074.8 5184.4 5193.6 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4319.66 Median:4771.55 stddev:1319.37 (27.7%) stddev-sans-first:1287.52 22:30:19 INFO - PROCESS | 1648 | Values: 266.5 285.4 292.3 304.6 311.8 5057.9 5297.3 5367.3 5375.4 5400.9 4496.6 5095.6 5207.2 5295.8 5303.2 4033.9 4035.0 4035.8 4036.9 4051.2 3375.8 3733.8 4038.7 4069.7 4081.7 3674.9 3751.2 3768.8 3919.4 4073.5 3329.9 3987.6 3989.4 3992.2 4015.5 3423.9 3430.9 3431.8 3434.4 3438.4 4715.8 5176.2 5186.8 5206.4 5249.4 4326.4 4524.6 4569.0 4578.5 4579.8 4578.8 4664.5 5641.8 5644.9 5645.7 3685.5 3807.9 3837.8 3841.8 3848.0 4462.7 5590.9 5700.4 5716.3 5901.6 3213.8 3564.1 3706.2 3864.5 3867.8 3331.3 3932.3 3937.5 3940.2 3946.9 4217.9 4376.9 4559.3 4583.3 4588.6 4712.8 5065.8 5302.7 5550.4 5601.6 1120.9 1325.0 1328.7 1334.5 1338.4 764.9 831.2 868.3 888.5 896.3 4179.1 5035.6 5199.5 5201.4 5268.6 5153.8 5298.9 5440.0 5710.2 5711.2 4152.5 5218.6 5250.0 5259.9 5287.8 2221.7 2464.7 2521.3 2525.3 2536.1 4635.9 4828.9 4871.7 4877.8 4935.8 4419.6 4428.0 4501.7 4929.2 4992.8 4952.3 4959.0 4961.7 5013.5 5016.9 3923.1 4682.8 4778.0 4837.9 4858.7 4193.6 4292.7 4726.2 4763.8 4765.0 4199.5 4403.5 4949.6 5667.8 5846.3 3151.8 3319.5 3724.1 3791.8 3832.2 5275.8 5371.4 5441.8 5803.5 5814.8 4779.9 5253.5 5519.0 5646.7 5649.9 4856.6 4880.6 5550.0 5584.1 5588.4 5033.4 5384.9 5469.6 5518.7 5519.4 4965.7 5327.7 5540.8 5550.0 5564.9 4843.7 5032.6 5418.9 5454.3 5476.0 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:23018.30 Median:4384.67 stddev:35653.43 (813.1%) stddev-sans-first:35714.09 22:30:19 INFO - PROCESS | 1648 | Values: 706.6 720.7 739.2 766.5 773.2 14705.4 16195.9 16210.8 16229.4 16263.4 14823.5 14825.2 14844.3 14858.2 14865.3 662.6 674.7 824.9 833.1 846.1 836.6 855.8 857.1 857.3 858.0 896.0 897.5 898.3 898.9 899.4 706.4 879.6 894.2 894.7 896.5 672.5 674.0 674.6 674.7 675.0 14918.8 14946.2 14962.5 14968.9 15023.1 11999.6 13498.7 13679.7 13707.7 13906.7 12484.8 12715.4 12799.8 12863.4 13403.1 4558.4 4561.1 4580.8 4710.3 4715.2 116163.4 116679.4 117377.0 118419.0 118836.4 10957.9 11042.3 11108.2 11162.3 11343.8 21537.8 21563.4 21564.1 21583.1 21587.5 7758.7 7942.4 7999.6 8005.0 8105.8 4326.0 4443.3 4459.1 4561.8 4572.0 3433.4 3493.8 3546.5 3548.9 3552.0 637.9 646.7 657.1 658.6 668.7 564.4 592.6 639.0 667.3 678.4 8997.9 9186.6 9205.5 9519.6 10033.8 567.4 587.7 607.8 629.5 658.3 4507.0 4687.6 4706.9 4731.8 4802.8 84526.6 84545.7 85736.1 133299.0 144247.0 106775.0 118416.0 118833.0 120208.0 123412.0 102006.0 105982.0 106405.5 108538.5 110229.9 8867.6 9102.0 9147.5 9258.8 9319.3 55703.2 55959.7 56165.4 56345.4 57586.1 79377.6 82219.6 87559.0 89212.0 90775.1 10560.3 11899.3 11942.5 11944.3 12079.6 13400.5 13409.0 13641.9 13901.3 13907.3 12052.8 12354.3 12370.5 12503.6 12838.6 12134.3 12220.1 12649.3 12702.6 12737.9 12088.5 12192.4 12445.1 12607.1 12619.2 10373.4 10578.7 10806.4 10915.0 11181.7 9074.4 9467.5 9839.9 10127.7 10683.0 22:30:19 INFO - PROCESS | 1648 | -------- Summary: end -------- 22:30:19 INFO - PROCESS | 1648 | 22:30:19 INFO - TEST-INFO | 1648: exit 0 22:30:20 INFO - TEST-OK | dromaeo_css | took 1117836ms 22:30:20 INFO - TEST-START | kraken 22:30:20 INFO - operating with platform_type : mac_ 22:30:20 INFO - Initialising browser for kraken test... 22:30:20 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpL_yAhb/profile http://localhost:49265/getInfo.html) 22:30:22 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 22:30:22 INFO - PROCESS | 1658 | colorDepth:24 22:30:22 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 22:30:22 INFO - PROCESS | 1658 | __metrics 22:30:22 INFO - PROCESS | 1658 | 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" 22:30:22 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:30:22 INFO - TEST-INFO | 1658: exit 0 22:30:22 INFO - Browser initialized. 22:30:22 INFO - Running cycle 1/1 for kraken test... 22:30:22 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpL_yAhb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:34 INFO - PROCESS | 1659 | RSS: Main: 243380224 22:30:34 INFO - PROCESS | 1659 | 22:30:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 22:30:38 INFO - PROCESS | 1659 | RSS: Main: 293126144 22:30:38 INFO - PROCESS | 1659 | 22:30:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 22:30:41 INFO - PROCESS | 1659 | RSS: Main: 298672128 22:30:41 INFO - PROCESS | 1659 | 22:30:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 22:30:45 INFO - PROCESS | 1659 | RSS: Main: 392646656 22:30:45 INFO - PROCESS | 1659 | 22:30:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 22:30:48 INFO - PROCESS | 1659 | RSS: Main: 295723008 22:30:48 INFO - PROCESS | 1659 | 22:30:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:30:51 INFO - PROCESS | 1659 | RSS: Main: 299823104 22:30:51 INFO - PROCESS | 1659 | 22:30:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 22:30:54 INFO - PROCESS | 1659 | RSS: Main: 337342464 22:30:54 INFO - PROCESS | 1659 | 22:30:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 22:30:57 INFO - PROCESS | 1659 | RSS: Main: 339927040 22:30:57 INFO - PROCESS | 1659 | 22:31:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 22:31:01 INFO - PROCESS | 1659 | RSS: Main: 339525632 22:31:01 INFO - PROCESS | 1659 | 22:31:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:31:03 INFO - PROCESS | 1659 | RSS: Main: 313622528 22:31:03 INFO - PROCESS | 1659 | 22:31:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:31:06 INFO - PROCESS | 1659 | RSS: Main: 320765952 22:31:06 INFO - PROCESS | 1659 | 22:31:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:31:08 INFO - PROCESS | 1659 | RSS: Main: 321085440 22:31:08 INFO - PROCESS | 1659 | 22:31:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:31:12 INFO - PROCESS | 1659 | RSS: Main: 329826304 22:31:12 INFO - PROCESS | 1659 | 22:31:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:31:15 INFO - PROCESS | 1659 | RSS: Main: 332210176 22:31:15 INFO - PROCESS | 1659 | 22:31:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 22:31:18 INFO - PROCESS | 1659 | RSS: Main: 375783424 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | __start_tp_report 22:31:18 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 22:31:18 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 22:31:18 INFO - PROCESS | 1659 | |i|pagename|runs| 22:31:18 INFO - PROCESS | 1659 | |0;ai-astar;101;112;112;113;111;112;113;111;112;111 22:31:18 INFO - PROCESS | 1659 | |1;audio-beat-detection;145;108;105;165;107;105;129;104;140;106 22:31:18 INFO - PROCESS | 1659 | |2;audio-dft;184;183;184;183;182;184;180;182;183;181 22:31:18 INFO - PROCESS | 1659 | |3;audio-fft;80;60;76;112;90;68;61;57;99;57 22:31:18 INFO - PROCESS | 1659 | |4;audio-oscillator;88;68;102;71;70;68;98;69;68;101 22:31:18 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;87;84;87;84;86;87;88;84;88;84 22:31:18 INFO - PROCESS | 1659 | |6;imaging-darkroom;94;94;94;94;94;94;94;93;93;94 22:31:18 INFO - PROCESS | 1659 | |7;imaging-desaturate;85;86;89;88;85;86;86;82;85;83 22:31:18 INFO - PROCESS | 1659 | |8;json-parse-financial;56;54;53;55;53;56;53;53;53;52 22:31:18 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;65;67;77;80;76;72;70;71;71;73 22:31:18 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;83;83;80;83;82;83;83;84;87;86 22:31:18 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;137;133;143;150;147;137;137;146;225;135 22:31:18 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;166;166;164;164;170;186;227;172;166;163 22:31:18 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;68;66;66;66;66;69;81;84;83 22:31:18 INFO - PROCESS | 1659 | __end_tp_report 22:31:18 INFO - PROCESS | 1659 | __start_cc_report 22:31:18 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2022 22:31:18 INFO - PROCESS | 1659 | __end_cc_report 22:31:18 INFO - PROCESS | 1659 | __startTimestamp1470807078030__endTimestamp 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | ------- Summary: start ------- 22:31:18 INFO - PROCESS | 1659 | Number of tests: 14 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.52 (3.1%) stddev-sans-first:0.78 22:31:18 INFO - PROCESS | 1659 | Values: 101.0 112.0 112.0 113.0 111.0 112.0 113.0 111.0 112.0 111.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:118.50 stddev:21.93 (18.5%) stddev-sans-first:21.53 22:31:18 INFO - PROCESS | 1659 | Values: 145.0 108.0 105.0 165.0 107.0 105.0 129.0 104.0 140.0 106.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.35 (0.7%) stddev-sans-first:1.33 22:31:18 INFO - PROCESS | 1659 | Values: 184.0 183.0 184.0 183.0 182.0 184.0 180.0 182.0 183.0 181.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:76.00 Median:72.00 stddev:19.16 (26.6%) stddev-sans-first:20.27 22:31:18 INFO - PROCESS | 1659 | Values: 80.0 60.0 76.0 112.0 90.0 68.0 61.0 57.0 99.0 57.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:69.50 stddev:15.08 (21.7%) stddev-sans-first:15.73 22:31:18 INFO - PROCESS | 1659 | Values: 88.0 68.0 102.0 71.0 70.0 68.0 98.0 69.0 68.0 101.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:1.73 (2.0%) stddev-sans-first:1.79 22:31:18 INFO - PROCESS | 1659 | Values: 87.0 84.0 87.0 84.0 86.0 87.0 88.0 84.0 88.0 84.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:31:18 INFO - PROCESS | 1659 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 94.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:2.07 (2.4%) stddev-sans-first:2.19 22:31:18 INFO - PROCESS | 1659 | Values: 85.0 86.0 89.0 88.0 85.0 86.0 86.0 82.0 85.0 83.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:1.40 (2.6%) stddev-sans-first:1.24 22:31:18 INFO - PROCESS | 1659 | Values: 56.0 54.0 53.0 55.0 53.0 56.0 53.0 53.0 53.0 52.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:4.54 (6.3%) stddev-sans-first:4.00 22:31:18 INFO - PROCESS | 1659 | Values: 65.0 67.0 77.0 80.0 76.0 72.0 70.0 71.0 71.0 73.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.00 stddev:1.96 (2.4%) stddev-sans-first:2.07 22:31:18 INFO - PROCESS | 1659 | Values: 83.0 83.0 80.0 83.0 82.0 83.0 83.0 84.0 87.0 86.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:144.50 stddev:27.30 (18.9%) stddev-sans-first:28.61 22:31:18 INFO - PROCESS | 1659 | Values: 137.0 133.0 143.0 150.0 147.0 137.0 137.0 146.0 225.0 135.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.40 Median:168.00 stddev:19.68 (11.7%) stddev-sans-first:20.63 22:31:18 INFO - PROCESS | 1659 | Values: 166.0 166.0 164.0 164.0 170.0 186.0 227.0 172.0 166.0 163.0 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:68.50 stddev:7.73 (11.3%) stddev-sans-first:8.02 22:31:18 INFO - PROCESS | 1659 | Values: 67.0 68.0 66.0 66.0 66.0 66.0 69.0 81.0 84.0 83.0 22:31:18 INFO - PROCESS | 1659 | -------- Summary: end -------- 22:31:18 INFO - PROCESS | 1659 | 22:31:18 INFO - TEST-INFO | 1659: exit 0 22:31:18 INFO - TEST-OK | kraken | took 58853ms 22:31:18 INFO - SUITE-END | took 1176s 22:31:19 INFO - Completed test suite (00:19:36) 22:31:19 INFO - javascript benchmark 22:31:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2979.4785912465327, 3346.995901229631, 3358.66413358664, 3371.4100032494307, 3423.6576342365765, 5623.128415095667, 5682.147677848325, 5714.0, 5747.971260143699, 5757.251557297556, 5729.770809167639, 5753.395893431192, 5790.797322093727, 5840.53275737941, 5843.941560584393, 5123.257127716479, 5123.692578445287, 5206.401263854667, 5211.869703257411, 5362.3029006229135, 5328.46715328466, 5364.892702145955, 5373.489518495737, 5379.35447746271, 5416.91874621881, 5153.793848246066, 5173.9741301293625, 5188.922166167473, 5253.737313134328, 5263.078961181797, 4407.206702793496, 5202.219667049935, 5208.218767184915, 5287.920681189786, 5319.468053194688, 5220.530152286275, 5231.0, 5258.8159414420315, 5283.233931079972, 5312.787488500456, 5628.352739434977, 5684.744186511617, 5825.038868586678, 5857.736401861926, 5872.471477566996, 6212.409821066992, 6265.154204182422, 6286.937130628681, 6303.464205542535, 6329.493640508748, 6286.528510361742, 6324.146240257553, 6329.145565348665, 6330.240371155491, 6348.269948955883, 5710.914336284876, 5776.624519406225, 5918.171455996121, 5963.165156878041, 5976.671283079386, 6241.251049874044, 6284.748610055638, 6288.213973253343, 6338.429541341301, 6341.460975817107, 6245.531585131133, 6264.21697287839, 6265.71804268809, 6269.749210031548, 6272.278687950853, 6152.692365381713, 6266.624002559861, 6266.937330626726, 6339.429451349309, 6353.110564520971, 6161.6611086389785, 6162.7226774795245, 6238.407351301619, 6252.218472690914, 6305.432511074026, 6287.622742635456, 6292.590981586274, 6299.811005669837, 6313.905291420632, 6314.273858506239, 4992.525710057539, 5016.648834581617, 5043.142665746832, 5044.747762611854, 5070.594352451795, 4389.297712366006, 4398.64810815134, 4416.227160246944, 4486.080353527519, 4512.887177820581, 5749.338826034976, 5792.4497172768515, 5805.8548536286935, 5832.62087964281, 5857.4435428634215, 5354.598405119632, 6295.401936815995, 6318.273398559225, 6330.905036424458, 6347.619142851444, 5617.129344951539, 5642.069058605366, 5688.744006519716, 5761.711914404263, 5776.855578610484, 5464.26232458613, 5471.261379713687, 5482.930828488406, 5488.396276409591, 5493.423190565013, 6206.751729930843, 6252.374762523711, 6260.686965651699, 6282.3089460158435, 6342.270638876404, 6334.081558173947, 6340.397662222082, 6343.904841427475, 6346.650934198663, 6386.297507274289, 6284.591501552384, 6319.58922670025, 6339.936600633934, 6343.524235682435, 6344.365563443619, 6141.486007784186, 6173.629582225081, 6202.596831205957, 6265.091561723616, 6285.96857015712, 6272.435480806749, 6310.05349197624, 6331.873362532816, 6344.936550634619, 6346.587471814318, 5971.0, 6194.163787888758, 6215.285242197205, 6227.283862355705, 6235.657038862906, 6007.759689612367, 6098.26820781509, 6105.206323177958, 6113.0, 6116.29662588808, 5901.114832775118, 5916.852078698067, 5918.437748413894, 5929.407059294036, 5931.614445061057, 6056.66688332146, 6100.2984656763265, 6117.755289788359, 6120.632762034292, 6130.969345153246, 5937.139114828412, 5937.614055086406, 5946.405359464019, 5951.256092988377, 5956.612820166675], "value": 5738.84015881009, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.70692170854073, 487.7512468640993, 510.86717453462137, 516.8191133103412, 518.0105997544689, 30362.54456183159, 40244.59755402449, 41216.58783412165, 41995.37006944898, 43382.349264761055, 42622.78688606553, 43012.13975720484, 43043.78478107613, 43182.35226471606, 43197.7840110799, 8617.052124266325, 9664.80670386592, 9858.457784821832, 9860.30977831552, 9869.802603947917, 8724.040355560883, 9622.230221582256, 11826.231294965799, 11868.940655296712, 12220.388980550937, 11911.463984120735, 11982.640520784347, 11983.64049078529, 12000.7599848003, 12007.279563226191, 11870.169088163831, 11951.940240298787, 11953.22304050234, 11966.880331196664, 11972.820407693891, 9985.550650220757, 11741.236819606784, 11843.348615826126, 11854.110941679322, 11860.703482412922, 39369.40945885814, 39613.80193099016, 39695.60304396948, 39757.80121099376, 39837.20325593472, 3541.8406171722336, 3587.0494319005484, 3687.520622319108, 3764.3224219640456, 4008.118213992917, 25482.617760733603, 31131.0, 31140.844295778377, 31457.685423145704, 31892.681073189437, 3200.9359812803614, 3246.2533617267923, 3328.8169150696704, 3358.2108204571905, 3368.865245390206, 486184.0, 487273.5636321796, 488236.0, 488829.0, 489292.0, 56521.0, 56906.0, 57171.71414142986, 57192.714036430385, 57211.427885721445, 61747.0, 61842.0, 61939.0, 61995.690021549606, 62033.37966620366, 16009.199540022952, 16222.513324600044, 16231.918840405722, 16240.269187886573, 16259.349626015064, 11536.538538458535, 11730.237534560227, 12663.430145643284, 12736.299503527394, 12746.80879786804, 12823.487060517662, 14916.254187290591, 15488.148151851756, 15643.843561564467, 15734.527964161094, 6327.303996560375, 6341.334159913143, 6354.555181137276, 6415.647139407378, 6421.422072013448, 869.2046777198792, 905.4657751926395, 935.6397786852111, 971.946542940145, 1079.4548752879746, 29263.414731705674, 29852.104436866928, 29866.850665746966, 30392.0, 30460.08619741411, 957.5308099031424, 1059.9364038157735, 1062.8671416072991, 1107.4573459005192, 1109.944502774858, 13321.933390332986, 14856.851431485762, 14907.776383354258, 15335.30991105403, 15398.922075454835, 633389.0, 637928.0, 638361.0, 639485.8025709934, 639514.0, 58647.120293195636, 58997.41002589919, 59008.70495647494, 59009.114863277086, 59064.70467647634, 1235.8331625230658, 1345.9528916487875, 1366.9726605467836, 1387.7432674955166, 1395.783653533704, 30193.69806301909, 31025.534616981215, 31077.689223108384, 31320.632548873116, 32404.67595324111, 48760.51239487559, 49111.0, 49443.01113977772, 49524.0, 49534.50465495299, 548387.0, 549907.0, 553543.0, 554305.0, 556089.0, 25756.356091097125, 25788.61317080283, 26118.86940565285, 26465.412075275544, 26932.596011059457, 47631.52368476271, 47833.52166478291, 48039.75980120077, 48107.27839082487, 48587.27119093146, 38621.0, 38730.225395492496, 38892.6110738889, 39883.0, 40010.399844002946, 38464.2307153861, 38554.80722596369, 38590.42114368343, 40007.59992400039, 40205.0, 37489.4376584346, 37640.477251348646, 37785.24429511449, 38186.61813381831, 38449.03877403087, 32857.0, 33396.83301583477, 33950.81172159062, 33968.830155849064, 34137.658623413445, 24200.819978379728, 25386.619200712372, 25582.104626338732, 25999.480010399308, 26185.476290474464], "value": 20112.46879674469, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.22510476950714, 370.2798057777622, 376.99623003769955, 378.78409306695187, 399.7561487492631, 14977.775333370008, 15300.15849128299, 20787.792122078776, 20824.375268741926, 20854.374368768924, 20401.0, 20903.47741306468, 21044.684329735028, 21187.152513899466, 21363.6795448068, 5658.632188907728, 5709.914351284724, 5828.213191219194, 5963.1055341698875, 5965.254343207099, 4127.380892866064, 5868.178455016301, 5978.491828194608, 5979.252593425822, 5982.491488223506, 4241.978790106061, 4243.405923170743, 4325.091730736549, 4521.638268938477, 6506.772262970797, 6259.87480250397, 6470.09418681385, 6516.413522782948, 6563.803085907407, 6566.146400967844, 5661.2357331760095, 5662.546996240332, 5663.631863928831, 5666.5183459406, 5669.206311116447, 25142.0, 26296.8166432511, 26301.73698263031, 26322.94708211669, 26829.597556036675, 2306.746257911628, 2478.31846242283, 2490.4645501217324, 2525.5075260324243, 2556.578164602838, 14578.635534111627, 14858.811295096366, 15213.31540080688, 15864.682706345924, 16339.18304084803, 1756.6574517969002, 1840.9723854142198, 1904.1526520698294, 2139.882306473143, 2167.2739632223224, 135872.0, 143183.56816431807, 145051.27474362665, 145395.27302363422, 149069.0, 16104.0, 20418.0, 21240.513164078675, 21578.892105539373, 21727.13091476323, 32177.0, 35467.82266088705, 35493.0, 35613.82193089018, 35647.64352356495, 9009.729708108767, 10546.789064218672, 10807.67576972692, 10884.782304353868, 10891.346519208873, 8933.553322333857, 8938.731838044869, 8951.283897288202, 9101.317401194805, 9109.134632209929, 9518.0, 9533.618655253851, 9702.913873517227, 9727.270454715925, 9748.92761796202, 3271.934561308808, 3730.1420673245307, 3733.771078943409, 3744.277632289123, 3760.172761992357, 837.4933165434946, 850.566211232276, 894.2935081285841, 940.7789169545151, 942.7595963029521, 20665.863377514383, 21086.05112769929, 21168.894155529124, 21261.255856044965, 21429.142834286766, 903.6295119001178, 929.2937367600672, 931.2270815223349, 937.2127412973135, 981.6809536900536, 10940.945295273632, 11200.271982321123, 11246.550137994389, 11253.268537545033, 11290.830637540443, 568712.0, 568913.0, 577903.2209677934, 578486.0, 582305.0, 122259.0, 124193.0, 130824.69175308316, 131577.34211328882, 133851.0, 955.3551352836807, 959.3140904253493, 992.374803873555, 993.4238141877694, 993.9403635781731, 13039.0, 18749.875007499595, 18965.0, 19164.685433440598, 19353.612927741648, 47924.7603761979, 50330.748346258035, 55760.16359754688, 55819.16271255853, 56459.87080258454, 80704.59647701724, 80857.3828523438, 80999.0, 81555.0, 83284.0, 8014.679412823655, 8036.196380361917, 8140.75577732669, 8180.0, 8198.549079800669, 10699.983501567338, 10924.945375273073, 11119.83320250213, 13203.933980330037, 13240.271785051915, 11078.667639970814, 11193.720156995814, 11428.657140285804, 11462.369569673749, 11475.713107172054, 11185.72035699114, 11190.552377905118, 11210.215284929976, 11575.363355015555, 11603.88396116028, 11408.771824563628, 11560.884391156318, 11595.188336816342, 11718.0, 11755.412229388325, 7971.202879711982, 8207.220314070153, 8213.3429325655, 8392.622331994953, 8444.535550544533, 11096.33421994683, 11107.333559986426, 11161.55353785872, 11540.538378464767, 11543.653690389301], "value": 10598.019706300522, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.7758081126208, 1480.940762369505, 1516.0297409657821, 1688.9746653800196, 1869.2522990803668, 7704.576248306341, 8385.87421188682, 8700.956495217531, 8706.12938706129, 8819.426737262074, 8589.398742088057, 8672.392932494713, 8676.392652514327, 8681.913180868189, 8684.652613895436, 2291.9083236670513, 2322.0479603362623, 2325.4883925536355, 2327.045911176418, 2328.6157783965627, 2278.270953294946, 2313.8727369994645, 2316.8609883406966, 2317.0615900560215, 2320.0487800002, 2269.8524595901295, 2293.1973809166743, 2293.2088429491882, 2306.4695120122383, 2310.9768902311016, 2267.784560466753, 2280.8859557022165, 2287.9084836606517, 2288.782565656268, 2292.5071109711407, 2284.862908225512, 2285.325828880484, 2285.725712914444, 2287.553926984239, 2287.645414960684, 8588.957055214747, 8652.134786521297, 8690.608922598434, 8772.824543509094, 8779.122087791233, 4702.365180700596, 5112.974435127838, 5299.072662284085, 5303.071962406602, 5312.442193569658, 3034.226272300549, 3443.6900678939014, 3640.0, 3656.909911125095, 3665.102049997741, 921.433318509112, 1043.1654676259038, 1078.5577913055688, 1092.4428541443842, 1095.9232853700244, 11449.885501145049, 11459.942700286529, 11529.942350288195, 11544.942275288653, 11553.19127661064, 4292.5492823253735, 4363.38912552243, 4529.048899731028, 4539.0694907543875, 4546.74992875395, 8980.8652870207, 9001.234895033864, 9004.8649270261, 9007.234385077341, 9015.504147271962, 5134.922976155361, 5156.381234251914, 5158.045761534128, 5164.354455693038, 5165.0, 3938.8818335449982, 4355.216061109031, 4480.462344518679, 4695.95304046962, 4847.636427267969, 2919.948818425365, 3538.628444013393, 3594.6944509716445, 3668.4680721295263, 3704.7962362070343, 1148.8621365436202, 1163.6334554615269, 1164.8019836627793, 1175.2126075529416, 1177.652592485219, 845.8350621628723, 898.2275243290765, 900.5632268349846, 904.4030939579977, 908.1962463219966, 5622.97188514063, 5705.5435565154685, 5972.46247837688, 5980.431858973478, 6057.091436284592, 863.456022705704, 942.6700654770775, 946.2950102173925, 957.8323793336277, 1005.0903931941605, 3895.4156876468755, 3944.934867585793, 4009.218202450494, 4031.274370613259, 4066.9629244542452, 9831.410115393099, 10530.420826854597, 10676.839847402296, 10689.893101069045, 10699.304545204537, 10347.793044139074, 10657.307275027099, 10659.520321585547, 10663.573457061631, 10668.839967400496, 7956.443048986515, 8188.713395031145, 8191.017077950684, 8204.097549269456, 8212.384071194685, 5766.0, 5802.1296805479515, 5924.7037648118285, 5934.109883517507, 5935.7032148391545, 9846.556904939152, 9907.950460247941, 9922.900770992197, 9949.303548751517, 9957.651482198089, 9575.04249575037, 9947.602095916372, 9949.204063675033, 10113.59545618167, 10114.8482772757, 6957.095577574883, 7160.713571457083, 7355.485116041826, 7519.774406767806, 7536.397088233038, 7572.053493313336, 7643.694252229849, 7806.453548251795, 7870.252326029018, 7877.291043806085, 7282.453815963718, 7394.4454165936695, 7427.554346738997, 7513.098428188652, 7583.544987300559, 7245.637718114179, 7251.093613298338, 7274.272572742683, 7293.927060729537, 7351.8162045949275, 6684.0, 6870.381665650116, 6964.408025317702, 7029.191642961125, 7034.648267586498, 4974.850754477371, 4995.025969935828, 5056.544910958031, 5064.620153488429, 5141.640085194001, 4895.157003690967, 4943.678660887031, 5074.797008119782, 5184.351956005499, 5193.584513239008], "value": 4485.902449780285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.46041765425025, 285.3622154484726, 292.34660533707165, 304.6298747022368, 311.7599448424713, 5057.949420505794, 5297.258383826263, 5367.302250707407, 5375.381831089426, 5400.864978375543, 4496.6402687784985, 5095.617828662856, 5207.192885102806, 5295.841124766253, 5303.151495760679, 4033.878983630496, 4034.9798251008706, 4035.778032208227, 4036.8587099451597, 4051.1897620475875, 3375.7974521528663, 3733.8133093345223, 4038.676905847525, 4069.6540794032544, 4081.69387295954, 3674.853005879775, 3751.2181713686055, 3768.7550309229814, 3919.392494163409, 4073.4704488416464, 3329.900102996914, 3987.601239875989, 3989.3816458448987, 3992.201559688074, 4015.4980627421573, 3423.9043506078065, 3430.9313813723834, 3431.7940923544666, 3434.364642541113, 3438.381091403546, 4715.750326163569, 5176.153115786303, 5186.7925282988635, 5206.427292997767, 5249.396319423277, 4326.437563116806, 4524.5701658342405, 4569.04050149469, 4578.527595098642, 4579.839910461771, 4578.840599985999, 4664.533546645308, 5641.830745077613, 5644.8871022579315, 5645.689487078209, 3685.4840322354894, 3807.904802379935, 3837.769733815952, 3841.848564803691, 3848.0177862156775, 4462.732236065846, 5590.86022849428, 5700.429957004308, 5716.314042314907, 5901.557383196277, 3213.775035747475, 3564.108972756811, 3706.221693444353, 3864.478295430081, 3867.7872717000273, 3331.283773988604, 3932.2528719543197, 3937.527496700357, 3940.2119576084324, 3946.861859834892, 4217.894552636148, 4376.934345984812, 4559.316102584573, 4583.289590113538, 4588.5585810642015, 4712.761438316772, 5065.797368105234, 5302.655327403706, 5550.417206193372, 5601.579881508903, 1120.8823073577157, 1324.993005315967, 1328.7143264198244, 1334.4662135145825, 1338.3642769684323, 764.896738940247, 831.2477208126655, 868.3357231717741, 888.4624801994829, 896.3462411850126, 4179.101493178981, 5035.597152227813, 5199.506046925536, 5201.401838788512, 5268.552173065255, 5153.819616313486, 5298.894022119536, 5440.0, 5710.229119068956, 5711.171880077365, 4152.480939882515, 5218.60790486187, 5250.007495727475, 5259.947400526023, 5287.762050707727, 2221.677856710768, 2464.728879823218, 2521.344450442898, 2525.330787341356, 2536.087008676875, 4635.884102897455, 4828.8792780180775, 4871.683340582851, 4877.780499877514, 4935.75321233944, 4419.574829507158, 4427.977860110744, 4501.68488205829, 4929.211326187793, 4992.80028798844, 4952.257161425815, 4958.975205123951, 4961.692812842046, 5013.465506817684, 5016.867831171738, 3923.1172986077904, 4682.812687492462, 4778.044391121859, 4837.895515450649, 4858.684185527929, 4193.643540299109, 4292.720973136736, 4726.235152435864, 4763.7618119094595, 4765.046990601824, 4199.454070970754, 4403.471583409883, 4949.554540091409, 5667.773289068391, 5846.298444186725, 3151.755056752524, 3319.5352650629416, 3724.1062145085525, 3791.8483260669263, 3832.1760821423527, 5275.788968441219, 5371.382291036424, 5441.782328706808, 5803.535717142534, 5814.825555233349, 4779.904401912012, 5253.474652534716, 5519.0, 5646.661200327993, 5649.887002259849, 4856.562909338035, 4880.585150262267, 5549.972250138723, 5584.078627026495, 5588.423409018313, 5033.446320904771, 5384.865378365572, 5469.644473109235, 5518.696471694096, 5519.420460851713, 4965.652404331662, 5327.733613319241, 5540.836424350931, 5550.0, 5564.888702226014, 4843.709377437365, 5032.647714659939, 5418.864528386822, 5454.29094217765, 5475.972620136874], "value": 3900.1005976519887, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.6007705646309, 720.700909122714, 739.2496615934828, 766.5439063757062, 773.1920143450093, 14705.41178352866, 16195.91902040491, 16210.75683864743, 16229.431969881041, 16263.430779922704, 14823.481178158767, 14825.184614846206, 14844.257787110628, 14858.182799945998, 14865.256737163152, 662.579262168524, 674.733480275291, 824.8927639406869, 833.0503226321982, 846.1158089796147, 836.640244694781, 855.8288342331558, 857.1128881607538, 857.3355649371756, 857.9918595650117, 896.000958930794, 897.4974014551839, 898.27239935652, 898.8696638987343, 899.4288626722046, 706.4030893894651, 879.6261588824788, 894.2130924786212, 894.7136916186823, 896.5203616065373, 672.5426709837309, 674.0058413839596, 674.6053558668185, 674.6660403100448, 674.9831254218635, 14918.776218356734, 14946.177960212184, 14962.47631332909, 14968.850311496963, 15023.098614083081, 11999.580014699444, 13498.730025399534, 13679.658008549766, 13707.725845483035, 13906.652333691638, 12484.75030499385, 12715.364231788373, 12799.808002879965, 12863.356832158355, 13403.061785675007, 4558.407407037097, 4561.1105834362315, 4580.811588451262, 4710.29345598163, 4715.1512727709, 116163.41918290438, 116679.41660291729, 117377.0, 118419.0, 118836.40581797122, 10957.93812385171, 11042.33745975244, 11108.222424430212, 11162.330260184253, 11343.773124537463, 21537.784622153576, 21563.353099407042, 21564.137434502758, 21583.13667453284, 21587.460313491974, 7758.68965241384, 7942.364610831235, 7999.560024198725, 8004.959975200203, 8105.797355066053, 4326.0, 4443.33349997503, 4459.063596644676, 4561.771911404417, 4572.039871626929, 3433.4334834752485, 3493.7554371193773, 3546.4857595648486, 3548.8757893473603, 3552.005438477179, 637.8915584350672, 646.6540400885503, 657.1489920552896, 658.5521845145347, 668.6890595872942, 564.4101913500402, 592.5674257791836, 638.9680665724821, 667.2660073918651, 678.4233401608692, 8997.896157510253, 9186.600957645715, 9205.51419414596, 9519.571619277149, 10033.799324013478, 567.3843879390824, 587.7325816753367, 607.8480379905023, 629.468099455959, 658.3120638932327, 4506.954930450719, 4687.648426368036, 4706.858794236178, 4731.810727570927, 4802.807887684454, 84526.57736711277, 84545.6708840674, 85736.14263857406, 133299.0, 144247.0, 106775.0, 118416.0, 118833.0, 120208.0, 123412.0, 102006.0, 105982.0, 106405.46797265964, 108538.45730771296, 110229.89770102357, 8867.600957957036, 9102.033738759887, 9147.542622868963, 9258.768530786514, 9319.30105242096, 55703.16445253405, 55959.72020139873, 56165.43834561602, 56345.43654563402, 57586.13620795775, 79377.60311198638, 82219.58890205511, 87559.0, 89212.0, 90775.0922490793, 10560.260781745204, 11899.345535995257, 11942.462589183682, 11944.283342999448, 12079.637610871689, 13400.530981415604, 13409.0, 13641.86358136406, 13901.304934753423, 13907.331120265633, 12052.819207712068, 12354.320512371905, 12370.505179793068, 12503.56237531682, 12838.614841554769, 12134.33261170644, 12220.144589878622, 12649.304288264233, 12702.555410560955, 12737.93631031839, 12088.516459341528, 12192.390380480763, 12445.066620003718, 12607.117501774788, 12619.242845429304, 10373.429461379697, 10578.682639520826, 10806.405647689453, 10915.0, 11181.72045698864, 9074.364794464323, 9467.49111485443, 9839.852402214116, 10127.696169114939, 10683.0], "value": 7607.922595380819, "unit": "score"}], "name": "dromaeo_css", "value": 7389.688580524052, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 113.0, 111.0, 112.0, 113.0, 111.0, 112.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 105.0, 165.0, 107.0, 105.0, 129.0, 104.0, 140.0, 106.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 183.0, 182.0, 184.0, 180.0, 182.0, 183.0, 181.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 76.0, 112.0, 90.0, 68.0, 61.0, 57.0, 99.0, 57.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 102.0, 71.0, 70.0, 68.0, 98.0, 69.0, 68.0, 101.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 87.0, 88.0, 84.0, 88.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 89.0, 88.0, 85.0, 86.0, 86.0, 82.0, 85.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 55.0, 53.0, 56.0, 53.0, 53.0, 53.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 77.0, 80.0, 76.0, 72.0, 70.0, 71.0, 71.0, 73.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 80.0, 83.0, 82.0, 83.0, 83.0, 84.0, 87.0, 86.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 133.0, 143.0, 150.0, 147.0, 137.0, 137.0, 146.0, 225.0, 135.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 164.0, 164.0, 170.0, 186.0, 227.0, 172.0, 166.0, 163.0], "value": 174.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 66.0, 66.0, 66.0, 69.0, 81.0, 84.0, 83.0], "value": 71.6, "unit": "score"}], "name": "kraken", "value": 1440.6999999999998, "alertThreshold": 2.0}]} 22:31:19 INFO - Return code: 0 22:31:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:31:19 INFO - # TBPL SUCCESS # 22:31:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:31:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:31:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:31:19 INFO - 2016-08-09 22:31:19.293 screenresolution[1663:9577] starting screenresolution argv=screenresolution get 22:31:19 INFO - 2016-08-09 22:31:19.296 screenresolution[1663:9577] Display 0: 1600x1200x32@60 22:31:19 INFO - 2016-08-09 22:31:19.302 screenresolution[1664:9580] starting screenresolution argv=screenresolution list 22:31:19 INFO - Available Modes on Display 0 22:31:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:31:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:31:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:31:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:31:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:31:19 INFO - Intel Iris: 22:31:19 INFO - Chipset Model: Intel Iris 22:31:19 INFO - Type: GPU 22:31:19 INFO - Bus: Built-In 22:31:19 INFO - VRAM (Dynamic, Max): 1536 MB 22:31:19 INFO - Vendor: Intel (0x8086) 22:31:19 INFO - Device ID: 0x0a2e 22:31:19 INFO - Revision ID: 0x0009 22:31:19 INFO - Displays: 22:31:19 INFO - Display: 22:31:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:31:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:31:19 INFO - Display Serial Number: 1600x1200 60 22:31:19 INFO - Main Display: Yes 22:31:19 INFO - Mirror: Off 22:31:19 INFO - Online: Yes 22:31:19 INFO - Rotation: Supported 22:31:19 INFO - Return code: 0 22:31:19 INFO - Running post-action listener: _resource_record_post_action 22:31:19 INFO - [mozharness: 2016-08-10 05:31:19.540335Z] Finished run-tests step (success) 22:31:19 INFO - Running post-run listener: _resource_record_post_run 22:31:19 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37143552; Write bytes: 701217792; Read time: 236; Write time: 1957 22:31:19 INFO - TinderboxPrint: CPU usage
53.5% 22:31:19 INFO - TinderboxPrint: I/O read bytes / time
37,143,552 / 236 22:31:19 INFO - TinderboxPrint: I/O write bytes / time
701,217,792 / 1,957 22:31:19 INFO - TinderboxPrint: CPU idle
2,255.0 (46.5%) 22:31:19 INFO - TinderboxPrint: CPU system
964.4 (19.9%) 22:31:19 INFO - TinderboxPrint: CPU user
1,626.2 (33.6%) 22:31:19 INFO - TinderboxPrint: Swap in / out
440,365,056 / 0 22:31:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404244992; Write bytes: 405866496; Read time: 29782; Write time: 875 22:31:19 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 34489344; Write bytes: 293884928; Read time: 214; Write time: 1075 22:31:19 INFO - Running post-run listener: _upload_blobber_files 22:31:19 INFO - Blob upload gear active. 22:31:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:31:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:31:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:31:20 INFO - (blobuploader) - INFO - Open directory for files ... 22:31:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:31:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:31:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:21 INFO - (blobuploader) - INFO - Done attempting. 22:31:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:31:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:31:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:21 INFO - (blobuploader) - INFO - Done attempting. 22:31:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:31:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:31:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:24 INFO - (blobuploader) - INFO - Done attempting. 22:31:24 INFO - (blobuploader) - INFO - Iteration through files over. 22:31:24 INFO - Return code: 0 22:31:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:31:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:31:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/292e129c2fee0731265357dafc15f66650707dfdf9f8e1dbd5236f956bbd8da6cbf6e6e7471357b9762550b4e40ee3b9fb9ec3aa9670edd3ac4317a1ed121570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faad2792f8aceb3db75e4f6e5388e310819c20018d7be5d3ccc16b0444e9303dd1e43e06f521f6c98756477fb4be7255a0650a6e4a7c4a1d059d3e5ea4b097c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb05df9ef91e15f1322431c41a100a75d50079dc4ad51ebc54f0306119e1dd2010625b3e63d4fd3f1a89c1565a4ccf73435a4a38c3deaa652566213a4436dc09"} 22:31:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:31:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:31:24 INFO - Contents: 22:31:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/292e129c2fee0731265357dafc15f66650707dfdf9f8e1dbd5236f956bbd8da6cbf6e6e7471357b9762550b4e40ee3b9fb9ec3aa9670edd3ac4317a1ed121570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faad2792f8aceb3db75e4f6e5388e310819c20018d7be5d3ccc16b0444e9303dd1e43e06f521f6c98756477fb4be7255a0650a6e4a7c4a1d059d3e5ea4b097c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb05df9ef91e15f1322431c41a100a75d50079dc4ad51ebc54f0306119e1dd2010625b3e63d4fd3f1a89c1565a4ccf73435a4a38c3deaa652566213a4436dc09"} 22:31:24 INFO - Running post-run listener: copy_logs_to_upload_dir 22:31:24 INFO - Copying logs to upload dir... 22:31:24 INFO - mkdir: /builds/slave/test/build/upload/logs 22:31:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.604093 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-08-09 22:31:24.434430) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 22:31:24.438469) ========= 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.6H1xqLG5bo/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.APmR9NvPZD/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/292e129c2fee0731265357dafc15f66650707dfdf9f8e1dbd5236f956bbd8da6cbf6e6e7471357b9762550b4e40ee3b9fb9ec3aa9670edd3ac4317a1ed121570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faad2792f8aceb3db75e4f6e5388e310819c20018d7be5d3ccc16b0444e9303dd1e43e06f521f6c98756477fb4be7255a0650a6e4a7c4a1d059d3e5ea4b097c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb05df9ef91e15f1322431c41a100a75d50079dc4ad51ebc54f0306119e1dd2010625b3e63d4fd3f1a89c1565a4ccf73435a4a38c3deaa652566213a4436dc09"} build_url:https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/QyFAWGfpSqSNu409WHJizg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/292e129c2fee0731265357dafc15f66650707dfdf9f8e1dbd5236f956bbd8da6cbf6e6e7471357b9762550b4e40ee3b9fb9ec3aa9670edd3ac4317a1ed121570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/faad2792f8aceb3db75e4f6e5388e310819c20018d7be5d3ccc16b0444e9303dd1e43e06f521f6c98756477fb4be7255a0650a6e4a7c4a1d059d3e5ea4b097c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/eb05df9ef91e15f1322431c41a100a75d50079dc4ad51ebc54f0306119e1dd2010625b3e63d4fd3f1a89c1565a4ccf73435a4a38c3deaa652566213a4436dc09"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 22:31:24.471718) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:31:24.472082) ========= 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.6H1xqLG5bo/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.APmR9NvPZD/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.005046 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:31:24.520892) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 22:31:24.521231) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 22:31:24.521563) ========= ========= Total master_lag: 0.35 =========