builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0229 starttime: 1486511835.4 results: success (0) buildid: 20170207144856 builduid: 991c37783c7e4e0587236ecdb4172ff8 revision: bbaed5a7faece9003c1403d1fc5dc0025629b8d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.404339) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.404793) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.405094) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.428108) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.428481) ========= 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.pcIRgpifQf/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.inJBSO77uG/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 --2017-02-07 15:57:15-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 181M=0s 2017-02-07 15:57:15 (181 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092087 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.544674) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.545003) ========= 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.pcIRgpifQf/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.inJBSO77uG/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.040410 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:15.606666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-07 15:57:15.607043) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bbaed5a7faece9003c1403d1fc5dc0025629b8d2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bbaed5a7faece9003c1403d1fc5dc0025629b8d2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pcIRgpifQf/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.inJBSO77uG/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 2017-02-07 15:57:15,719 truncating revision to first 12 chars 2017-02-07 15:57:15,719 Setting DEBUG logging. 2017-02-07 15:57:15,720 attempt 1/10 2017-02-07 15:57:15,720 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bbaed5a7faec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 15:57:18,058 unpacking tar archive at: mozilla-aurora-bbaed5a7faec/testing/mozharness/ program finished with exit code 0 elapsedTime=2.603448 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-07 15:57:18.233502) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:18.233833) ========= script_repo_revision: bbaed5a7faece9003c1403d1fc5dc0025629b8d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:18.234218) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:18.234477) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 15:57:18.253888) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2017-02-07 15:57:18.254252) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pcIRgpifQf/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.inJBSO77uG/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:57:18 INFO - MultiFileLogger online at 20170207 15:57:18 in /builds/slave/test 15:57:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:57:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:57:18 INFO - {'append_to_log': False, 15:57:18 INFO - 'base_work_dir': '/builds/slave/test', 15:57:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:57:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:57:18 INFO - 'branch': 'Mozilla-Aurora', 15:57:18 INFO - 'buildbot_json_path': 'buildprops.json', 15:57:18 INFO - 'config_files': ('talos/mac_config.py',), 15:57:18 INFO - 'default_actions': ('clobber', 15:57:18 INFO - 'read-buildbot-config', 15:57:18 INFO - 'download-and-extract', 15:57:18 INFO - 'populate-webroot', 15:57:18 INFO - 'create-virtualenv', 15:57:18 INFO - 'install', 15:57:18 INFO - 'run-tests'), 15:57:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:57:18 INFO - 'download_minidump_stackwalk': True, 15:57:18 INFO - 'download_symbols': 'ondemand', 15:57:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:57:18 INFO - 'tooltool.py': '/tools/tooltool.py', 15:57:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:57:18 INFO - '/tools/misc-python/virtualenv.py')}, 15:57:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:57:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:57:18 INFO - 'installer_path': 'installer.exe', 15:57:18 INFO - 'log_level': 'info', 15:57:18 INFO - 'log_name': 'talos', 15:57:18 INFO - 'log_to_console': True, 15:57:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:57:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:57:18 INFO - 'opt_config_files': (), 15:57:18 INFO - 'pip_index': False, 15:57:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:57:18 INFO - 'cmd': ('bash', 15:57:18 INFO - '-c', 15:57:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:57:18 INFO - 'enabled': True, 15:57:18 INFO - 'halt_on_failure': False, 15:57:18 INFO - 'name': 'check_screen_resolution'},), 15:57:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:57:18 INFO - 'cmd': ('bash', 15:57:18 INFO - '-c', 15:57:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:57:18 INFO - 'enabled': True, 15:57:18 INFO - 'halt_on_failure': False, 15:57:18 INFO - 'name': 'check_screen_resolution'},), 15:57:18 INFO - 'run_cmd_checks_enabled': True, 15:57:18 INFO - 'sps_profile': False, 15:57:18 INFO - 'sps_profile_interval': 0, 15:57:18 INFO - 'suite': 'dromaeojs', 15:57:18 INFO - 'system_bits': '32', 15:57:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:57:18 INFO - 'title': 't-yosemite-r7-0229', 15:57:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:57:18 INFO - 'use_talos_json': True, 15:57:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:57:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:57:18 INFO - 'work_dir': 'build'} 15:57:18 INFO - [mozharness: 2017-02-07 23:57:18.474395Z] Running clobber step. 15:57:18 INFO - Running pre-action listener: _resource_record_pre_action 15:57:18 INFO - Running main action method: clobber 15:57:18 INFO - rmtree: /builds/slave/test/build 15:57:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:57:20 INFO - Running post-action listener: _resource_record_post_action 15:57:20 INFO - [mozharness: 2017-02-07 23:57:20.821940Z] Finished clobber step (success) 15:57:20 INFO - [mozharness: 2017-02-07 23:57:20.822107Z] Running read-buildbot-config step. 15:57:20 INFO - Running pre-action listener: _resource_record_pre_action 15:57:20 INFO - Running main action method: read_buildbot_config 15:57:20 INFO - Using buildbot properties: 15:57:20 INFO - { 15:57:20 INFO - "project": "", 15:57:20 INFO - "product": "firefox", 15:57:20 INFO - "script_repo_revision": "production", 15:57:20 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:57:20 INFO - "repository": "", 15:57:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:57:20 INFO - "buildid": "20170207144856", 15:57:20 INFO - "pgo_build": "False", 15:57:20 INFO - "basedir": "/builds/slave/test", 15:57:20 INFO - "buildnumber": 56, 15:57:20 INFO - "slavename": "t-yosemite-r7-0229", 15:57:20 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:57:20 INFO - "platform": "yosemite_r7", 15:57:20 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:57:20 INFO - "branch": "mozilla-aurora", 15:57:20 INFO - "repo_path": "releases/mozilla-aurora", 15:57:20 INFO - "revision": "bbaed5a7faece9003c1403d1fc5dc0025629b8d2", 15:57:20 INFO - "stage_platform": "macosx64", 15:57:20 INFO - "builduid": "991c37783c7e4e0587236ecdb4172ff8", 15:57:20 INFO - "slavebuilddir": "test" 15:57:20 INFO - } 15:57:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 15:57:20 INFO - Running post-action listener: _resource_record_post_action 15:57:20 INFO - [mozharness: 2017-02-07 23:57:20.825479Z] Finished read-buildbot-config step (success) 15:57:20 INFO - [mozharness: 2017-02-07 23:57:20.825586Z] Running download-and-extract step. 15:57:20 INFO - Running pre-action listener: _resource_record_pre_action 15:57:20 INFO - Running main action method: download_and_extract 15:57:20 INFO - mkdir: /builds/slave/test/build/tests 15:57:20 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')]} 15:57:20 INFO - trying https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 15:57:20 INFO - Downloading https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 15:57:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 15:57:22 INFO - Downloaded 1199 bytes. 15:57:22 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 15:57:22 INFO - Using the following test package requirements: 15:57:22 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 15:57:22 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 15:57:22 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 15:57:22 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'jsshell-mac.zip'], 15:57:22 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 15:57:22 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 15:57:22 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 15:57:22 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 15:57:22 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 15:57:22 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 15:57:22 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 15:57:22 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:57:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 15:57:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:57:22 INFO - Fetch https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 15:57:30 INFO - Expected file size: 16357232 15:57:30 INFO - Obtained file size: 16357232 15:57:37 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:57:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 15:57:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:57:37 INFO - Fetch https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 15:57:40 INFO - Expected file size: 16357232 15:57:40 INFO - Obtained file size: 16357232 15:57:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 15:57:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:57:46 INFO - Fetch https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 15:58:00 INFO - Expected file size: 14403485 15:58:00 INFO - Obtained file size: 14403485 15:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:58:00 INFO - trying https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 15:58:00 INFO - Downloading https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 15:58:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 15:58:46 INFO - Downloaded 73819143 bytes. 15:58:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 15:58:46 INFO - mkdir: /builds/slave/test/properties 15:58:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:58:46 INFO - Writing to file /builds/slave/test/properties/build_url 15:58:46 INFO - Contents: 15:58:46 INFO - build_url:https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 15:58:48 INFO - Running post-action listener: _resource_record_post_action 15:58:48 INFO - Running post-action listener: set_extra_try_arguments 15:58:48 INFO - [mozharness: 2017-02-07 23:58:48.104694Z] Finished download-and-extract step (success) 15:58:48 INFO - [mozharness: 2017-02-07 23:58:48.104814Z] Running populate-webroot step. 15:58:48 INFO - Running pre-action listener: _resource_record_pre_action 15:58:48 INFO - Running main action method: populate_webroot 15:58:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:58:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:58:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:58:48 INFO - '--tppagecycles', 15:58:48 INFO - '7'], 15:58:48 INFO - 'tests': ['tsvgm']}}, 15:58:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:58:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:58:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:58:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:58:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:58:48 INFO - 'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:58:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:58:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:58:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:58:48 INFO - 'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['damp', 'tps']}, 15:58:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:58:48 INFO - 'tests': ['damp', 'tps']}, 15:58:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['dromaeo_dom']}, 15:58:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:58:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['basic_compositor_video']}, 15:58:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:58:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['a11yr', 15:58:48 INFO - 'ts_paint', 15:58:48 INFO - 'tpaint', 15:58:48 INFO - 'sessionrestore', 15:58:48 INFO - 'sessionrestore_no_auto_restore', 15:58:48 INFO - 'tabpaint']}, 15:58:48 INFO - 'other-e10s': {'tests': ['a11yr', 15:58:48 INFO - 'ts_paint', 15:58:48 INFO - 'tpaint', 15:58:48 INFO - 'sessionrestore', 15:58:48 INFO - 'sessionrestore_no_auto_restore', 15:58:48 INFO - 'tabpaint']}, 15:58:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:58:48 INFO - 'ts_paint', 15:58:48 INFO - 'tpaint', 15:58:48 INFO - 'sessionrestore', 15:58:48 INFO - 'sessionrestore_no_auto_restore', 15:58:48 INFO - 'tabpaint']}, 15:58:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:58:48 INFO - 'ts_paint', 15:58:48 INFO - 'tpaint', 15:58:48 INFO - 'sessionrestore', 15:58:48 INFO - 'sessionrestore_no_auto_restore', 15:58:48 INFO - 'tabpaint']}, 15:58:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['a11yr', 15:58:48 INFO - 'ts_paint', 15:58:48 INFO - 'tpaint', 15:58:48 INFO - 'sessionrestore', 15:58:48 INFO - 'sessionrestore_no_auto_restore', 15:58:48 INFO - 'tabpaint']}, 15:58:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['a11yr', 15:58:48 INFO - 'ts_paint', 15:58:48 INFO - 'tpaint', 15:58:48 INFO - 'sessionrestore', 15:58:48 INFO - 'sessionrestore_no_auto_restore', 15:58:48 INFO - 'tabpaint']}, 15:58:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['tsvgx', 15:58:48 INFO - 'tsvgr_opacity', 15:58:48 INFO - 'tart', 15:58:48 INFO - 'tscrollx', 15:58:48 INFO - 'cart', 15:58:48 INFO - 'tsvg_static']}, 15:58:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:58:48 INFO - 'tsvgr_opacity', 15:58:48 INFO - 'tart', 15:58:48 INFO - 'tscrollx', 15:58:48 INFO - 'cart', 15:58:48 INFO - 'tsvg_static']}, 15:58:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:58:48 INFO - 'talos_options': ['--disable-e10s'], 15:58:48 INFO - 'tests': ['tp5o']}, 15:58:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:58:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:58:48 INFO - 'talos_options': ['--disable-e10s', 15:58:48 INFO - '--xperf_path', 15:58:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:58:48 INFO - 'tests': ['tp5n']}, 15:58:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:58:48 INFO - 'talos_options': ['--xperf_path', 15:58:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:58:48 INFO - 'tests': ['tp5n']}}, 15:58:48 INFO - 'talos.zip': {'path': '', 15:58:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:58:48 INFO - Running post-action listener: _resource_record_post_action 15:58:48 INFO - [mozharness: 2017-02-07 23:58:48.123531Z] Finished populate-webroot step (success) 15:58:48 INFO - [mozharness: 2017-02-07 23:58:48.123646Z] Running create-virtualenv step. 15:58:48 INFO - Running pre-action listener: _resource_record_pre_action 15:58:48 INFO - Running main action method: create_virtualenv 15:58:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:58:48 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 15:58:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:58:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:58:48 INFO - Using real prefix '/tools/python27' 15:58:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:58:48 INFO - Installing distribute.............................................................................................................................................................................................done. 15:58:51 INFO - Installing pip.................done. 15:58:51 INFO - Return code: 0 15:58:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:58:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:58:52 INFO - Reading from file tmpfile_stdout 15:58:52 INFO - Output received: 15:58:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:58:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:58: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', 'pip>=1.5'] in /builds/slave/test/build 15:58: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 pip>=1.5 15:58:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:58:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:52 INFO - 'HOME': '/Users/cltbld', 15:58:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:52 INFO - 'LOGNAME': 'cltbld', 15:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:52 INFO - 'MOZ_NO_REMOTE': '1', 15:58:52 INFO - 'NO_EM_RESTART': '1', 15:58:52 INFO - 'PAGER': '/bin/cat', 15:58:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:52 INFO - 'PWD': '/builds/slave/test', 15:58:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:52 INFO - 'SHELL': '/bin/bash', 15:58:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:58:52 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:58:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:52 INFO - 'USER': 'cltbld', 15:58:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:52 INFO - 'XPC_FLAGS': '0x0', 15:58:52 INFO - 'XPC_SERVICE_NAME': '0', 15:58:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:52 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 15:58:52 INFO - Cleaning up... 15:58:52 INFO - Return code: 0 15:58:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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', '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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:58: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:58: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 psutil>=3.1.1 15:58:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:58:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:52 INFO - 'HOME': '/Users/cltbld', 15:58:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:52 INFO - 'LOGNAME': 'cltbld', 15:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:52 INFO - 'MOZ_NO_REMOTE': '1', 15:58:52 INFO - 'NO_EM_RESTART': '1', 15:58:52 INFO - 'PAGER': '/bin/cat', 15:58:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:52 INFO - 'PWD': '/builds/slave/test', 15:58:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:52 INFO - 'SHELL': '/bin/bash', 15:58:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:58:52 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:58:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:52 INFO - 'USER': 'cltbld', 15:58:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:52 INFO - 'XPC_FLAGS': '0x0', 15:58:52 INFO - 'XPC_SERVICE_NAME': '0', 15:58:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:52 INFO - Downloading/unpacking psutil>=3.1.1 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58: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 15:58:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:58:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:58:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:58:56 INFO - Installing collected packages: psutil 15:58:56 INFO - Running setup.py install for psutil 15:58:56 INFO - building 'psutil._psutil_osx' extension 15:58:56 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 15:58:57 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 15:58:57 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 15:58:57 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 15:58:57 INFO - building 'psutil._psutil_posix' extension 15:58:57 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 15:58:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:58:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:58:57 INFO - ^ 15:58:57 INFO - 1 warning generated. 15:58:57 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 15:58:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:58:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:58:57 INFO - Successfully installed psutil 15:58:57 INFO - Cleaning up... 15:58:57 INFO - Return code: 0 15:58:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:58: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')]} 15:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58: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')]} 15:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:57 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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:58:57 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 15:58:57 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 15:58:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:58:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:58:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:58:57 INFO - 'HOME': '/Users/cltbld', 15:58:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:58:57 INFO - 'LOGNAME': 'cltbld', 15:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:57 INFO - 'MOZ_NO_REMOTE': '1', 15:58:57 INFO - 'NO_EM_RESTART': '1', 15:58:57 INFO - 'PAGER': '/bin/cat', 15:58:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:57 INFO - 'PWD': '/builds/slave/test', 15:58:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:58:57 INFO - 'SHELL': '/bin/bash', 15:58:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:58:57 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:58:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:58:57 INFO - 'USER': 'cltbld', 15:58:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:58:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:57 INFO - 'XPC_FLAGS': '0x0', 15:58:57 INFO - 'XPC_SERVICE_NAME': '0', 15:58:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:59:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:59:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:59:00 INFO - Installing collected packages: mozsystemmonitor 15:59:00 INFO - Running setup.py install for mozsystemmonitor 15:59:00 INFO - Successfully installed mozsystemmonitor 15:59:00 INFO - Cleaning up... 15:59:00 INFO - Return code: 0 15:59:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:59:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:59:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:00 INFO - 'HOME': '/Users/cltbld', 15:59:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:00 INFO - 'LOGNAME': 'cltbld', 15:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:00 INFO - 'MOZ_NO_REMOTE': '1', 15:59:00 INFO - 'NO_EM_RESTART': '1', 15:59:00 INFO - 'PAGER': '/bin/cat', 15:59:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:00 INFO - 'PWD': '/builds/slave/test', 15:59:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:00 INFO - 'SHELL': '/bin/bash', 15:59:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:00 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:00 INFO - 'USER': 'cltbld', 15:59:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:00 INFO - 'XPC_FLAGS': '0x0', 15:59:00 INFO - 'XPC_SERVICE_NAME': '0', 15:59:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:01 INFO - Downloading/unpacking jsonschema==2.5.1 15:59:01 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 15:59:01 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 15:59:01 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 15:59:01 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 15:59:01 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 15:59:01 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 15:59:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:59:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59: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 15:59:04 INFO - Downloading functools32-3.2.3-2.tar.gz 15:59:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:59:04 INFO - warning: no files found matching '*.txt' 15:59:04 INFO - no previously-included directories found matching 'build' 15:59:04 INFO - no previously-included directories found matching 'dist' 15:59:04 INFO - no previously-included directories found matching '.git*' 15:59:04 INFO - Installing collected packages: jsonschema, functools32 15:59:04 INFO - Running setup.py install for functools32 15:59:04 INFO - warning: no files found matching '*.txt' 15:59:04 INFO - no previously-included directories found matching 'build' 15:59:04 INFO - no previously-included directories found matching 'dist' 15:59:04 INFO - no previously-included directories found matching '.git*' 15:59:04 INFO - Successfully installed jsonschema functools32 15:59:04 INFO - Cleaning up... 15:59:04 INFO - Return code: 0 15:59:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:59:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:04 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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:59:04 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 15:59:04 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 15:59:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:04 INFO - 'HOME': '/Users/cltbld', 15:59:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:04 INFO - 'LOGNAME': 'cltbld', 15:59:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:04 INFO - 'MOZ_NO_REMOTE': '1', 15:59:04 INFO - 'NO_EM_RESTART': '1', 15:59:04 INFO - 'PAGER': '/bin/cat', 15:59:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:04 INFO - 'PWD': '/builds/slave/test', 15:59:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:04 INFO - 'SHELL': '/bin/bash', 15:59:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:04 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:04 INFO - 'USER': 'cltbld', 15:59:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:04 INFO - 'XPC_FLAGS': '0x0', 15:59:04 INFO - 'XPC_SERVICE_NAME': '0', 15:59:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:59:04 INFO - Cleaning up... 15:59:04 INFO - Return code: 0 15:59:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:59:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:04 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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:59:04 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 15:59:04 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 15:59:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:04 INFO - 'HOME': '/Users/cltbld', 15:59:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:04 INFO - 'LOGNAME': 'cltbld', 15:59:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:04 INFO - 'MOZ_NO_REMOTE': '1', 15:59:04 INFO - 'NO_EM_RESTART': '1', 15:59:04 INFO - 'PAGER': '/bin/cat', 15:59:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:04 INFO - 'PWD': '/builds/slave/test', 15:59:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:04 INFO - 'SHELL': '/bin/bash', 15:59:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:04 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:04 INFO - 'USER': 'cltbld', 15:59:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:04 INFO - 'XPC_FLAGS': '0x0', 15:59:04 INFO - 'XPC_SERVICE_NAME': '0', 15:59:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:05 INFO - Downloading/unpacking blobuploader==1.2.4 15:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:07 INFO - Downloading blobuploader-1.2.4.tar.gz 15:59:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:59:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:59:07 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 15:59:07 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 15:59:07 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 15:59:07 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 15:59:07 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 15:59:07 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 15:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:59:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:59:08 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 15:59:08 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 15:59:08 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 15:59:08 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 15:59:08 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 15:59:08 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 15:59:08 INFO - Downloading docopt-0.6.1.tar.gz 15:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:59:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:59:09 INFO - Running setup.py install for blobuploader 15:59:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:59:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:59:09 INFO - Running setup.py install for requests 15:59:09 INFO - Running setup.py install for docopt 15:59:09 INFO - Successfully installed blobuploader requests docopt 15:59:09 INFO - Cleaning up... 15:59:09 INFO - Return code: 0 15:59:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:09 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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:59:09 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 15:59:09 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 15:59:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:09 INFO - 'HOME': '/Users/cltbld', 15:59:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:09 INFO - 'LOGNAME': 'cltbld', 15:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:09 INFO - 'MOZ_NO_REMOTE': '1', 15:59:09 INFO - 'NO_EM_RESTART': '1', 15:59:09 INFO - 'PAGER': '/bin/cat', 15:59:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:09 INFO - 'PWD': '/builds/slave/test', 15:59:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:09 INFO - 'SHELL': '/bin/bash', 15:59:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:09 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:09 INFO - 'USER': 'cltbld', 15:59:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:09 INFO - 'XPC_FLAGS': '0x0', 15:59:09 INFO - 'XPC_SERVICE_NAME': '0', 15:59:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-tQYENc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-GnPFTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-oRLRRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-cawFZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-WKLNe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-1D3LvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-M0KlQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:10 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-k3yPsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-v3YoIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-UBevDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-n3UJtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-AtFFra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-pZDSGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-AYr0Pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-CYMJrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-EnOL4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:11 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-VQbbrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:59:12 INFO - Running setup.py install for manifestparser 15:59:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:59:12 INFO - Running setup.py install for mozcrash 15:59:12 INFO - Running setup.py install for mozdebug 15:59:12 INFO - Running setup.py install for mozdevice 15:59:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:59:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:59:12 INFO - Running setup.py install for mozfile 15:59:12 INFO - Running setup.py install for mozhttpd 15:59:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:59:12 INFO - Running setup.py install for mozinfo 15:59:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Running setup.py install for mozInstall 15:59:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Running setup.py install for mozleak 15:59:13 INFO - Running setup.py install for mozlog 15:59:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Running setup.py install for moznetwork 15:59:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Running setup.py install for mozprocess 15:59:13 INFO - Running setup.py install for mozprofile 15:59:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:59:13 INFO - Running setup.py install for mozrunner 15:59:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:59:14 INFO - Running setup.py install for mozscreenshot 15:59:14 INFO - Running setup.py install for moztest 15:59:14 INFO - Running setup.py install for mozversion 15:59:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:59:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:59:14 INFO - Cleaning up... 15:59:14 INFO - Return code: 0 15:59:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:14 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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:59:14 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 15:59:14 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 15:59:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:14 INFO - 'HOME': '/Users/cltbld', 15:59:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:14 INFO - 'LOGNAME': 'cltbld', 15:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:14 INFO - 'MOZ_NO_REMOTE': '1', 15:59:14 INFO - 'NO_EM_RESTART': '1', 15:59:14 INFO - 'PAGER': '/bin/cat', 15:59:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:14 INFO - 'PWD': '/builds/slave/test', 15:59:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:14 INFO - 'SHELL': '/bin/bash', 15:59:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:14 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:14 INFO - 'USER': 'cltbld', 15:59:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:14 INFO - 'XPC_FLAGS': '0x0', 15:59:14 INFO - 'XPC_SERVICE_NAME': '0', 15:59:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:14 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-7z7ZWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:14 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)) 15:59:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:14 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-09Ywr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-A7J8h9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:15 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-wL_hZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Wijmbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:15 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Wl3kxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:15 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-9zf9V5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:15 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-CWgeOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:15 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ukOrxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:15 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-bH6CgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 15:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:15 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qqBmRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:16 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)) 15:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-cCUCv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 15:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-aRq1lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:16 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)) 15:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-eIBzNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 15:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Tqbm_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:16 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)) 15:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-2BQfBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 15:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:16 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-DVUuMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:16 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)) 15:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:59:16 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)) 15:59:16 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:59:16 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:59:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:59:16 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 15:59:16 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 15:59:16 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 15:59:16 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 15:59:16 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 15:59:16 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 15:59:19 INFO - Downloading blessings-1.6.tar.gz 15:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:59:19 INFO - Installing collected packages: blessings 15:59:19 INFO - Running setup.py install for blessings 15:59:19 INFO - Successfully installed blessings 15:59:19 INFO - Cleaning up... 15:59:19 INFO - Return code: 0 15:59:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:59:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:19 INFO - Reading from file tmpfile_stdout 15:59:19 INFO - Current package versions: 15:59:19 INFO - blessings == 1.6 15:59:19 INFO - blobuploader == 1.2.4 15:59:19 INFO - docopt == 0.6.1 15:59:19 INFO - functools32 == 3.2.3-2 15:59:19 INFO - jsonschema == 2.5.1 15:59:19 INFO - manifestparser == 1.1 15:59:19 INFO - mozInstall == 1.12 15:59:19 INFO - mozcrash == 1.0 15:59:19 INFO - mozdebug == 0.1 15:59:19 INFO - mozdevice == 0.49 15:59:19 INFO - mozfile == 1.2 15:59:19 INFO - mozhttpd == 0.7 15:59:19 INFO - mozinfo == 0.9 15:59:19 INFO - mozleak == 0.1 15:59:19 INFO - mozlog == 3.4 15:59:19 INFO - moznetwork == 0.27 15:59:19 INFO - mozprocess == 0.24 15:59:19 INFO - mozprofile == 0.28 15:59:19 INFO - mozrunner == 6.13 15:59:19 INFO - mozscreenshot == 0.1 15:59:19 INFO - mozsystemmonitor == 0.3 15:59:19 INFO - moztest == 0.8 15:59:19 INFO - mozversion == 1.4 15:59:19 INFO - psutil == 3.1.1 15:59:19 INFO - requests == 1.2.3 15:59:19 INFO - wsgiref == 0.1.2 15:59:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:19 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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:59:19 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 15:59:19 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 15:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:19 INFO - 'HOME': '/Users/cltbld', 15:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:19 INFO - 'LOGNAME': 'cltbld', 15:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:19 INFO - 'MOZ_NO_REMOTE': '1', 15:59:19 INFO - 'NO_EM_RESTART': '1', 15:59:19 INFO - 'PAGER': '/bin/cat', 15:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:19 INFO - 'PWD': '/builds/slave/test', 15:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:19 INFO - 'SHELL': '/bin/bash', 15:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:19 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:19 INFO - 'USER': 'cltbld', 15:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:19 INFO - 'XPC_FLAGS': '0x0', 15:59:19 INFO - 'XPC_SERVICE_NAME': '0', 15:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 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)) 15:59:20 INFO - Cleaning up... 15:59:20 INFO - Return code: 0 15:59:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:59:20 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')]} 15:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:20 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')]} 15:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:59:20 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 0x10c1ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c202768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb729c78e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, '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.pcIRgpifQf/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.inJBSO77uG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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 15:59:20 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 15:59:20 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 15:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:20 INFO - 'HOME': '/Users/cltbld', 15:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:20 INFO - 'LOGNAME': 'cltbld', 15:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:20 INFO - 'MOZ_NO_REMOTE': '1', 15:59:20 INFO - 'NO_EM_RESTART': '1', 15:59:20 INFO - 'PAGER': '/bin/cat', 15:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:20 INFO - 'PWD': '/builds/slave/test', 15:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:20 INFO - 'SHELL': '/bin/bash', 15:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:20 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:20 INFO - 'USER': 'cltbld', 15:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:20 INFO - 'XPC_FLAGS': '0x0', 15:59:20 INFO - 'XPC_SERVICE_NAME': '0', 15:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:59:20 INFO - Cleaning up... 15:59:20 INFO - Return code: 0 15:59:20 INFO - Running post-action listener: _resource_record_post_action 15:59:20 INFO - Running post-action listener: _start_resource_monitoring 15:59:20 INFO - Starting resource monitoring. 15:59:20 INFO - [mozharness: 2017-02-07 23:59:20.488039Z] Finished create-virtualenv step (success) 15:59:20 INFO - [mozharness: 2017-02-07 23:59:20.489052Z] Running install step. 15:59:20 INFO - Running pre-action listener: _resource_record_pre_action 15:59:20 INFO - Running main action method: install 15:59:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:20 INFO - Reading from file tmpfile_stdout 15:59:20 INFO - Detecting whether we're running mozinstall >=1.0... 15:59:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:59:20 INFO - Reading from file tmpfile_stdout 15:59:20 INFO - Output received: 15:59:20 INFO - Usage: mozinstall [options] installer 15:59:20 INFO - Options: 15:59:20 INFO - -h, --help show this help message and exit 15:59:20 INFO - -d DEST, --destination=DEST 15:59:20 INFO - Directory to install application into. [default: 15:59:20 INFO - "/builds/slave/test"] 15:59:20 INFO - --app=APP Application being installed. [default: firefox] 15:59:20 INFO - mkdir: /builds/slave/test/build/application 15:59:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:59:40 INFO - Reading from file tmpfile_stdout 15:59:40 INFO - Output received: 15:59:40 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:59:40 INFO - Running post-action listener: _resource_record_post_action 15:59:40 INFO - [mozharness: 2017-02-07 23:59:40.785636Z] Finished install step (success) 15:59:40 INFO - [mozharness: 2017-02-07 23:59:40.785751Z] Running run-tests step. 15:59:40 INFO - Running pre-action listener: _resource_record_pre_action 15:59:40 INFO - Running main action method: run_tests 15:59:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:59:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:59:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:59:40 INFO - 2017-02-07 15:59:40.798 screenresolution[1629:7649] starting screenresolution argv=screenresolution get 15:59:40 INFO - 2017-02-07 15:59:40.800 screenresolution[1629:7649] Display 0: 1600x1200x32@60 15:59:40 INFO - 2017-02-07 15:59:40.805 screenresolution[1630:7652] starting screenresolution argv=screenresolution list 15:59:40 INFO - Available Modes on Display 0 15:59:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:59:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:59:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:59:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:59:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:59:41 INFO - Intel Iris: 15:59:41 INFO - Chipset Model: Intel Iris 15:59:41 INFO - Type: GPU 15:59:41 INFO - Bus: Built-In 15:59:41 INFO - VRAM (Dynamic, Max): 1536 MB 15:59:41 INFO - Vendor: Intel (0x8086) 15:59:41 INFO - Device ID: 0x0a2e 15:59:41 INFO - Revision ID: 0x0009 15:59:41 INFO - Displays: 15:59:41 INFO - Display: 15:59:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:59:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:59:41 INFO - Display Serial Number: 1600x1200 60 15:59:41 INFO - Main Display: Yes 15:59:41 INFO - Mirror: Off 15:59:41 INFO - Online: Yes 15:59:41 INFO - Rotation: Supported 15:59:41 INFO - Return code: 0 15:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:59:41 INFO - Python 2.7.3 15:59:41 INFO - Return code: 0 15:59:41 INFO - grabbing minidump binary from tooltool 15:59: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')]} 15:59: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 0x10bfddd30>, '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 0x10bf04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bfd2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:59: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 15:59: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 15:59: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 15:59:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:59:41 INFO - Return code: 0 15:59:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:59:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:59:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:59:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:59:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--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 15:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0229 --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 15:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pcIRgpifQf/Render', 15:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:41 INFO - 'HOME': '/Users/cltbld', 15:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:41 INFO - 'LOGNAME': 'cltbld', 15:59:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:59:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:41 INFO - 'MOZ_NO_REMOTE': '1', 15:59:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:59:41 INFO - 'NO_EM_RESTART': '1', 15:59:41 INFO - 'PAGER': '/bin/cat', 15:59:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:41 INFO - 'PWD': '/builds/slave/test', 15:59:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:41 INFO - 'SHELL': '/bin/bash', 15:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.inJBSO77uG/Listeners', 15:59:41 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 15:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:41 INFO - 'USER': 'cltbld', 15:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:41 INFO - 'XPC_FLAGS': '0x0', 15:59:41 INFO - 'XPC_SERVICE_NAME': '0', 15:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--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 15:59:41 INFO - mozversion application_buildid: 20170207144856 15:59:41 INFO - mozversion application_changeset: bbaed5a7faece9003c1403d1fc5dc0025629b8d2 15:59:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:59:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:59:41 INFO - mozversion application_name: Firefox 15:59:41 INFO - mozversion application_remotingname: firefox-dev 15:59:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:59:41 INFO - mozversion application_vendor: Mozilla 15:59:41 INFO - mozversion application_version: 53.0a2 15:59:41 INFO - mozversion platform_buildid: 20170207144856 15:59:41 INFO - mozversion platform_changeset: bbaed5a7faece9003c1403d1fc5dc0025629b8d2 15:59:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:59:41 INFO - mozversion platform_version: 53.0a2 15:59:41 INFO - using testdate: 1486511981 15:59:41 INFO - actual date: 1486511981 15:59:41 INFO - starting webserver on 'localhost:49241' 15:59:41 INFO - SUITE-START | Running 2 tests 15:59:41 INFO - TEST-START | dromaeo_css 15:59:41 INFO - operating with platform_type : mac_ 15:59:41 INFO - Initialising browser for dromaeo_css test... 15:59:41 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmppPhiEr/profile http://localhost:49241/getInfo.html) 15:59:43 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 15:59:43 INFO - PROCESS | 1640 | colorDepth:24 15:59:43 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 15:59:43 INFO - PROCESS | 1640 | __metrics 15:59:43 INFO - PROCESS | 1640 | 1486511983769 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 15:59:43 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:59:43 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:59:44 INFO - TEST-INFO | 1640: exit 0 15:59:44 INFO - Browser initialized. 15:59:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:59:44 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmppPhiEr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:56 INFO - PROCESS | 1642 | RSS: Main: 213102592 15:59:56 INFO - PROCESS | 1642 | 16:02:59 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 16:03:01 INFO - PROCESS | 1642 | RSS: Main: 332107776 16:03:01 INFO - PROCESS | 1642 | 16:04:46 INFO - PROCESS | 1642 | ************************* 16:04:46 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 16:04:46 INFO - PROCESS | 1642 | 16:04:46 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:04:46 INFO - PROCESS | 1642 | Full stack: 16:04:46 INFO - PROCESS | 1642 | ************************* 16:06:04 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 16:06:04 INFO - PROCESS | 1642 | RSS: Main: 346980352 16:06:04 INFO - PROCESS | 1642 | 16:09:07 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 16:09:07 INFO - PROCESS | 1642 | RSS: Main: 333963264 16:09:07 INFO - PROCESS | 1642 | 16:12:09 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 16:12:10 INFO - PROCESS | 1642 | RSS: Main: 318267392 16:12:10 INFO - PROCESS | 1642 | 16:15:14 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 16:15:14 INFO - PROCESS | 1642 | RSS: Main: 311443456 16:15:14 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 16:18:17 INFO - PROCESS | 1642 | RSS: Main: 472338432 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | __start_tp_report 16:18:17 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 16:18:17 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 16:18:17 INFO - PROCESS | 1642 | |i|pagename|runs| 16:18:17 INFO - PROCESS | 1642 | |0;dojo.html;2831.943361132777;3326.4511355626323;3336.549565808615;3343.715784158346;3373.1567108222944;4689.742064186468;5720.6853623050765;5724.828255152342;5763.942360576393;5769.596128271028;5695.77216911323;5762.654240745548;5766.221560089387;5768.048272035119;5807.767689292423;5179.19722443022;5191.325127733389;5229.3201883755055;5231.346081739783;5288.0745869472885;4761.40482439695;4828.903421931577;5174.275601415805;5310.7610157543;5345.812896548621;5187.870303242411;5252.474752524736;5273.314469119009;5293.6294459387855;5314.840554783344;5294.788208471676;5307.416184219733;5307.761150748206;5329.147336426153;5349.625526213166;5031.698098114125;5133.743312834381;5232.319798426218;5259.184826351883;5267.341582302212;5589.692566908819;5677.432256774289;5682.715864206814;5723.427657234243;5762.510186634141;5995.010823214165;6205.0692396140485;6271;6292.834269410744;6327.683615819236;6226.501879849601;6317.494600432;6318.684065796738;6320.589161704521;6323.715432805535;5790.218320526717;5812.186293918855;5827.504662103684;5840.678762668052;5876.706164691748;6213.875722485525;6279.623222606659;6288.182536270347;6294.559380843297;6307.432331090224;6202.658853762996;6202.844928876724;6215.285242197115;6216.067589861466;6228;6181.165542651775;6204.255489341308;6231.283402408691;6235.563510554218;6292.937070629235;6245.937540624626;6305.369463053658;6307.148534947723;6313.9368606314265;6315.936840631627;6328.746850125944;6331.746730130835;6340.651264180422;6349.777757778455;6357.4596159326975;4862.6839255448285;4938.358013458227;4954.20732682769;5016.272640467112;5054.570361519312;4438.267685831866;4470.2177119004045;4493.168763778646;4525.547445255448;4530.954690453119;5702.771889124471;5799.6230245034785;5804.216430781875;5817.621854579439;5825.621334613236;5235.528802407725;6157.784477543264;6161.7227224775;6162.753489860355;6206.782762603287;5754.079347304411;5762.481376676119;5792.623479473821;5802;5806.0129777937855;5412.539934105566;5469.535089517356;5478.616496845262;5478.945210547923;5495.890082198413;6218.9378106218355;6295.874082518232;6298.937010629835;6330.8100756977365;6344.873102538016;6209.844753881189;6242.126102345766;6269.905951410824;6272.090546870587;6321.905171422524;6312.715927783169;6312.905306420315;6312.905306420315;6325.05124231369;6334.144890439732;6190.721417536131;6253.749850006131;6256.530760192913;6257.530685198537;6277.591956522811;6143.447089761943;6160.291566469913;6187.041008643668;6199.56603037783;6240.032794916795;6087.560528301496;6118.497354391859;6149.754009839557;6166.445019948226;6175.505959523317;6062.333143354315;6109.009944784373;6114.784027539525;6121;6141.301904043321;4485.035717320791;5822.650640961556;5872.324682661378;5877;6012.098185272244;5915.645061296336;6108.7670647404;6117.3402608778015;6180.381961803783;6199.876002479835;6021.712763207513;6025.487833534199;6033.306169790355;6054.666993315233;6057.151998720271 16:18:17 INFO - PROCESS | 1642 | |1;ext.html;437.7570448401138;485.764404263932;501.1480483178597;501.30569161711065;503.3808415648752;31206.53190202149;41514.58485415145;42986.355204671876;43040.784796075975;45244.54755452445;44188.55811441885;44485.555144448546;44694.32958505625;44789.99999999992;45514;9263.397879137852;9462.290328225376;9464.148226659634;9487.478188699619;9498.335116541844;8507.872381914276;8747.431416957908;11216.719582010473;11512.848715128504;11518.884811151866;11435.542578296858;11515.827262591069;11521.942390288037;11523.711907202345;11544.538218471293;8527.957360213159;11407.714807129847;11477.483513241925;11487.655370338904;11495.425228738572;8484.406091573592;11124.443777811077;11415.657530274022;11422.600208992728;11630.418479076097;41755.791221044004;42063.36904946428;42101.736947891615;42165;42274.78862605698;4028.47629808126;4039.818208180639;4041;4055.3105971984833;4056.8580099696665;22813;29266.70733292661;30462.69537304621;30470.54294185589;31522.054338369657;3169.9207519811957;3254.1864533866533;3294.901152965415;3401.9319613607836;3405.625381208065;467992;475194;476156.6192169017;477515;477668.6116569395;61914;62344.75310493765;62391.6880415595;62443.375566244664;62571.687141564;65027;65284.673576631816;65358.34641653523;65370.34629653642;65639;16543.420980265873;16592.419265325654;16916.069616170982;16919.830801691827;16930.238139283763;11652.184347095623;11999.400029998465;13037.087403881636;13139.934300328438;13197.73604527904;14344.354504047344;18916.09911607024;22417;22592.66111008336;23117.422064448525;7541.886871696929;8808.955955220312;8880.333974951904;8972.564389697616;9022.278217742565;819.7499762572472;851.6933903794629;868.405142477405;873.4191762477881;926.7173512078746;24328;29646.110616681533;30108.096757097323;30278.84860575727;30981.69018309833;857.0872021297298;961.9658866718306;1067.2262609607972;1081.7133459633203;1222.2299951030793;14799;15052.322645480761;15563.922180389025;15710.293036813371;15863.920680396524;642435;648313;650721;653022;653130;61112;61530;61627.38372616306;61664;62091;1444.205686872237;1447.6201776894407;1462.436961769705;1476.2249818844978;1489.858463445971;28532.714672853006;29992.25019374533;30063.549046764754;30760.692393075784;30823.84588077135;51730.74134629303;52635.47364526306;52683;52792.47207528029;52892.73553632207;594986;600327;600375;603367;603741;22438.77561224432;27348.863255683595;27924;28202.71797282001;28223.294417639725;46825.53174468348;47678.28482572833;48208;48741.75629121974;48826.75586622044;38444.2311153781;39344.01639959024;39470.60529394784;39718.80140599279;40766.59233407747;35835.82289869946;36069.45895811617;37857.2428551433;38376.80811595924;39077.82766517009;35813.1046723834;36103.45844812277;36242.63757362393;36973.44539831851;37069.44395834119;30352.598873162828;32232.83883580567;32435.513467298482;32933.83533082319;32940.83529582433;22783;23304.6504302439;23609.881950590137;24179.911903964716;24412.63381049321 16:18:17 INFO - PROCESS | 1642 | |2;jquery.html;391.10632205410633;395.17014270032934;398.72687209261676;406.86776797540807;423.84953341563744;20256.99999999998;20778.753274803483;21492.140314387405;21617.891910540446;21670.99999999998;12156.209846360003;12442.751144977095;19720.506987325323;21536.923153842323;21683.67474487884;4576.816927322903;4941.184704523749;6068.969655151718;6136.47839933604;6140.877182456348;3975.185087057146;4182.289010868145;6038.215032045828;6132.63204207749;6136.815895523141;4200.957990420087;4209.831606735727;6825.283345248752;6885.277045910182;6915.1701795784575;6637.336266373372;6784.880494718365;6869.896951545731;6892.483063770237;6901.827454313632;5950.6132101413705;5957.404259574051;6081.847953801146;6131.662758548278;6138.202033735586;13455.596332110052;13605.31973401326;13723.313834308345;13764.380602872894;19940;2686.3552747340495;2707.56678931372;2708.8510131942735;2754.7107553706774;2814.704456032108;8857.867131993025;9761.560729767178;10342.948285258526;10693.946530267376;10766.084882784973;1844.2715127524687;1890.3383815664545;1893.8200870917242;1902.781180164285;1933.7389452423884;118199;120060.59878802462;126047.36976315151;126229;126243;10194.286399951932;11594.246373985832;12048.108453970985;12080.456379462945;12119.818202726967;13735.55776643438;15041.924790375979;15635.530934071996;16049;16289.51131466058;8700.477971321614;8893.28853691703;9111.863322050174;9228.907710922938;9236.214921731727;8576.44253123545;8615.870761938577;8729.869051964226;8872.73381798547;8911.465312081296;4073.9000469872967;4811.133995880705;4816.555033489939;4821.566059054702;4869.14789911764;2044.427560283123;2072.171131547363;2081.4172031831113;2089.4358523198653;2095.130520833867;812.8252425728497;877.9157740190986;878.0560897035712;879.9208071273488;903.4669544968368;17734.822651773316;20949.476263093544;21661.133554657954;21734.239301624366;21900;901.2519608724742;905.8822353094056;912.5802131019806;912.8037471943431;921.7924518880288;10715.142788576895;11031.724206894893;11043.613473528389;11056;11065.446727663746;724892.751072493;727780;744806;762151;805115;149967.50032499753;153034;156449;161174;161394;985.482621623639;994.8010397920444;1003.9397636141708;1009;1014.5383850348074;18815.811841881405;19287.421377358703;19644.410667679993;19681.507962300486;19686.704699429807;59969;60942.390576095444;61511.6924415375;61720.38279617146;61879.071813921924;88660.5566972161;89613.72703910664;91082;92656.53671731598;93758.89570858555;7865.095514015961;7971.3224375926375;8171.223733745284;8329.83340333202;8780.912190878009;9396.483193424427;12106.910378066017;13008.349582520646;13109.016823738448;13244.735105297646;10033.44816035125;11291.3789741565;11414.771704565695;11699.2980421175;11791;10857.891421085687;10986.176036797113;11252.493637786483;11447;11486.368249746343;10090.545925433225;11090.500927458444;11339;11691.298522088702;11757.941210293893;8362.163783621832;8392.958035209786;8550.315974721883;8558.443701159405;8615.870761938451;10298.845517317395;11103.38931358783;11220.607278745205;11436.82844757346;11537.538498459968 16:18:17 INFO - PROCESS | 1642 | |3;mootools.html;1330.5409633676384;1445.1329202478514;1468.8017117689108;1474.4544518528146;1475.2255066090304;7507.512011719235;8152.91847081529;8392.454490458123;8398.91601083989;8444.366672499571;7564.886526702104;7732.033495813023;8048.436609437342;8269.503829770218;8272.71045513407;2310.3300042987557;2343.379004563792;2349.048635302705;2353.9058437662557;2363.2673871100014;2264;2357;2371.063429945171;2372.418757404438;2374.2639781667654;2053.4558342039372;2127.9148834046696;2130.7762684918093;2260.9095636174534;2346.307839187435;2326.278853555403;2345.812335013195;2349.459624286415;2352.741198468167;2357.893894774722;2278.7721227877246;2378.393509655044;2384.7257565379855;2385.8687772172525;2386.904523819045;7279.636018199122;8434.704785332546;8518.40371174018;8546.572671366406;8560.957195214047;4302.526722060571;5159.432462429129;5212.765425555859;5213.2701421800975;5221.73891305437;3335.8332083395735;3404.097914052778;3474.322507111114;3525.7179425645886;3590.407582748843;1250.4685508658874;1271.89188918942;1732.6274850907116;1763.5150333658219;1840.9631807363778;10169.9491502542;10253;10254.384736915808;10391.688249352532;10680.519376627994;4624.930626040612;4729.43246810385;4779.450363208206;4779.71321720691;4787.066522028241;9527;9535.618575256913;9537.427754334762;9564.378315409615;9566.521673916277;4979.228219625964;5212.035773381936;5218.6868787872845;5223.268742376071;5225.085610018307;4653.9498651753265;4737.24204127338;4785.856424307276;4796.496367881392;4797.160496913027;2976.732094111497;3621.420572708354;3705.7220708446976;3760.8683696070507;3765.8681946131755;1276.6489215465635;1294.0359432223054;1297.4485843516468;1311.731095125497;1312.6324629103867;739.0650826704224;796.7052190689482;808.3775492870575;809.2716555100457;820.4175035724702;5140.203268493315;5595.664260144405;5597.552195824405;5609.326880774333;5627.071533197059;992.1219720547367;992.9722736967349;1008.5108722269694;1035.963741269052;1065.8561094252332;3616.9819150904077;3623.456481527739;3690.6678398944223;3790.298794722985;3899.512560929884;9306.72079837606;9809;9861.506924653739;9948.850767238497;9986.0013998601;8509.70216042448;9865.260105491972;9871.950640246896;9880.209583233323;9942.552585133686;7209.675564599605;7928.4450088494405;7936.920630793734;7948.920510794934;7975.282224599698;5328.8401347959625;5450.809221677222;5469.453054694499;5566.610337276514;5642.379338272704;8947.328950328621;9137.725868223964;9208.12522810332;9218.861717074384;9294;9227.538623068955;9369.531523423939;9398.765030874009;9511.143997040299;9541.666041688508;7203.171635262012;7334.596597187206;7336.596487193256;7342.339189472973;7345.522541034816;7595.202503737028;7887.487313324616;7986.520808751494;8070.435069545076;8087.8382432349845;7128.607926564089;7173.318534739192;7242.96378518104;7251.709931602677;7368.410527157922;7049.718011279491;7110.893336600058;7117.501774875709;7129.108861392326;7132.215456299907;6360.045993101072;6400.583962042452;6433.324500927522;6495.2855185928565;6508.08886755864;4939.506049395031;4951.084049450863;4976.253561965734;5024.070546948826;5024.748762561931;4868.537488938545;4869.878253043702;4915.43472500667;5020.874478138076;5054.368203974504 16:18:17 INFO - PROCESS | 1642 | |4;prototype.html;360.47010893985845;366.87342866710975;373.44356908206765;383.36170276489645;383.7585411194786;4599.3560901473775;5139.717315547643;5194.415853061385;5208.828013696926;5240.528352448279;4866.493755715809;4886.389201349832;5012.9749351253195;5064.594317699091;5294.1529355303155;4045.8179381927885;4115.444415003981;4124.99937530299;4154.792260386968;4168.374743788441;3808.9238215235678;3809.676177524914;4068.7151899367054;4069.572694867041;4092.85675001375;3634.8000859952826;3750.1686226898655;3809.895130412177;3835.6766915414137;4149.502059752834;3442.862285508577;3771.509703738524;4067.7559346439307;4119.114390406062;4124.793760311973;3551.8579256829694;3568.6074531801482;3619.4208926571623;3620.6922411595046;3640.8725694600826;3847.8644352384417;4992.40091189056;5100.311457953203;5120.462351453119;5144.4083930348115;4481.901934026152;4497.662675299365;4544.409226800495;4549.181147393468;4603.792829322689;5102.275339002512;5379.300690910196;5417.729113544307;5536.283752036841;5553.416891226403;3678.6321367863266;3777.3578491656485;3809.3333666608232;3818.4654148419245;3822;5487.890242195173;5737.569682273805;5751.539876809845;5764.308283006066;5812.82561523155;3686.382668305533;3715.606647507185;3824.7513911596216;3829.7936150112623;3871.922561548753;3328.6005679318637;3358;3917.7257591968287;3943.2310699413338;3954.387070004154;3864.574896761354;4255.574442555781;4297.5702429756775;4465.218586747306;4522.412086428743;4964.627652926045;5369.973150134302;5554.139872788373;5573.247611572386;5617.494425501724;1086.3101930274174;1118.8713297970676;1196.3898411809867;1226.5707002549216;1249.6188662457855;805.1586092533316;815.8898548695969;828.7099515169642;850.4174640371366;855.619249433996;4239.40648309237;4406.876246557107;4465.665075119379;4769.809207631725;4801.303810947463;4459.977700111479;4773.615651461107;5326.786928522815;5365.222042803772;5430;3995.1210733638554;4211.696644775888;4372.737635741865;4512.909741805145;4846.684965477232;2415.7221919479134;2590.611408288734;2746.3408781892203;2762.3646561291016;2769.3492029373483;4776.546228108324;5362.517373436332;5446.754896029688;5459.672419654833;5595.5731288521165;4548.363229147922;4621.636617197934;4870.975645121752;4905.664148379136;5449.3188351456065;4902.264660300983;5412.671207207539;5437.374701909331;5465.180222966586;5530.768961744269;4534.89127555675;4768.61851051922;5054.545090941833;5269.314989051399;5333.759980800795;4693.0613877224005;4795.808167673254;5057.342545469212;5170.457102004236;5176.188334083191;5252.842414727565;5369.328833895763;5404.297441332759;5406.8648283791645;5439.129739241703;3538.823623471598;3558.8220588970967;3577.195131095483;3577.3024260269;3614.331348700499;5195.9735703075075;5254.080535906278;5440.836774896759;5507.7676207602135;5684.147377893349;5488.643238189665;5518.862028449321;5520.806771762969;5524.751386187711;5541.501264886018;4874.8537543873745;5224.2947202127225;5324.790212061951;5544.972275138599;5567.387587365306;4234.78826058702;5355.277037599874;5417.370361857644;5452.591055670761;5454.590905682169;5306.151015837448;5324.281222034976;5338.946610534001;5494.862628434321;5508.669479831223;5341.798095428498;5342.732863356739;5378.784848606012;5394.271773310553;5419.918701219406 16:18:17 INFO - PROCESS | 1642 | |5;yui.html;725.8294300839306;730.0655161393415;777.5723352156316;834.0658462521976;850.3707256630091;14951.626209344759;15643.687126257468;16101.355945762185;16118.597035074128;16129.354825806968;14535.49125780598;14554.708905821877;14588.489402870902;14728.85271147291;14732.0424172429;694.0318256032843;725.960072196029;732.3408931961225;871.298604623278;907.0430695616122;908.3641450984304;917.4907926101012;920.1212841736126;920.1580553793272;926.735880274122;981.9591233292697;985.6599473181741;987.6641941739807;988.5106872098321;989.5596459575492;736.6243215959845;765.043695380774;921.7649499377616;981.6024510073431;983.3509883476906;719.4316489972915;725.408791834653;726.0815068937798;727.8107692000065;732.4506620034974;15157.317920693595;15528.456504022304;15543.300551475211;15549.378024878992;15590.908636395458;13824.516141935084;13963;13967.79048314276;14015.719685606333;14236.928815355857;12321.568745093879;12423.689407764788;12674.248618647656;12853.22880627156;13036.543720969721;4213.51544572375;4329.805158767863;4549.977250113729;4664.320844495979;4703.459102203256;99958;103248.93502129916;105321.94678053199;110637.89362106356;110724;11061.281016733887;11066.833997490045;11097.38964356952;11154.832677509843;11214;21841.453963650718;22296.777032229795;22365.329040128498;22382.55234895293;22410.99150538197;7650.655720492591;7878.409119316074;7949.475114795162;7955.562444065632;8054.073781515083;4268.957310426918;4277.91444171121;4320.071184695243;4526.95473045272;4532.0482698632995;3468.497067925137;3545.0782796472586;3551.8046507441823;3551.8756843510355;3559.4482855157494;629.4806784402795;631.0534198701947;652.7813182583888;662.29134825736;663.3300366629642;629.380060640269;650.6421468192569;660.577230572434;662.8972509261072;681.4412182010703;7624.5425274482595;9087;9415.340926135104;9545.856812147824;9607.663731769355;588.6526949100052;593.6942474625571;614.846288427893;621.6549814852709;645.4255712415953;4408.713433626804;4774.021325628238;4779.139754844162;4823.614110871122;4843.491433399442;85086;98324.50837745909;101377.42424166594;102180.97819021864;104132.47933760282;70151.64924175346;86802.69795953066;88665;89671.55164224267;90441;63420.68289658614;76847;79253.60373198097;79293;80760;8847.867281990904;9229.446233226028;9328.300377471582;9462.195713364441;9549.47477888695;41928.58071419247;50167.74916125396;50502.747486262335;51021.744891276794;51736.965260693825;64903;64921;66005;66795.33204668085;68090;10677.893221068001;10819.621313253998;10912.781744365227;10930.508127134139;11425.257358271685;11816.527338906348;11872.826626924987;11964.58123965657;12064.095192860746;12114.939425302817;11161.944190278997;11455.656330310105;11538.88461115378;11762;11828.23116497425;11110.44447777624;11187.888121118685;11635.767284654428;11636.952674259357;11769.764604707687;11200.21598488098;11535.307881527135;11544.88455115438;11603.245789023686;11622.128340374336;9481.336306458481;9635.662751803653;9649.372790768577;9702.75743106428;10281.12610428122;9302.395344302598;9426.340156189275;9437.150656440954;9461.33770636049;9537.14165725088 16:18:17 INFO - PROCESS | 1642 | __end_tp_report 16:18:17 INFO - PROCESS | 1642 | __start_cc_report 16:18:17 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1764 16:18:17 INFO - PROCESS | 1642 | __end_cc_report 16:18:17 INFO - PROCESS | 1642 | __startTimestamp1486513097908__endTimestamp 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | ------- Summary: start ------- 16:18:17 INFO - PROCESS | 1642 | Number of tests: 6 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5751.75 Median:6012.10 stddev:668.98 (11.1%) stddev-sans-first:630.60 16:18:17 INFO - PROCESS | 1642 | Values: 2831.9 3326.5 3336.5 3343.7 3373.2 4689.7 5720.7 5724.8 5763.9 5769.6 5695.8 5762.7 5766.2 5768.0 5807.8 5179.2 5191.3 5229.3 5231.3 5288.1 4761.4 4828.9 5174.3 5310.8 5345.8 5187.9 5252.5 5273.3 5293.6 5314.8 5294.8 5307.4 5307.8 5329.1 5349.6 5031.7 5133.7 5232.3 5259.2 5267.3 5589.7 5677.4 5682.7 5723.4 5762.5 5995.0 6205.1 6271.0 6292.8 6327.7 6226.5 6317.5 6318.7 6320.6 6323.7 5790.2 5812.2 5827.5 5840.7 5876.7 6213.9 6279.6 6288.2 6294.6 6307.4 6202.7 6202.8 6215.3 6216.1 6228.0 6181.2 6204.3 6231.3 6235.6 6292.9 6245.9 6305.4 6307.1 6313.9 6315.9 6328.7 6331.7 6340.7 6349.8 6357.5 4862.7 4938.4 4954.2 5016.3 5054.6 4438.3 4470.2 4493.2 4525.5 4531.0 5702.8 5799.6 5804.2 5817.6 5825.6 5235.5 6157.8 6161.7 6162.8 6206.8 5754.1 5762.5 5792.6 5802.0 5806.0 5412.5 5469.5 5478.6 5478.9 5495.9 6218.9 6295.9 6298.9 6330.8 6344.9 6209.8 6242.1 6269.9 6272.1 6321.9 6312.7 6312.9 6312.9 6325.1 6334.1 6190.7 6253.7 6256.5 6257.5 6277.6 6143.4 6160.3 6187.0 6199.6 6240.0 6087.6 6118.5 6149.8 6166.4 6175.5 6062.3 6109.0 6114.8 6121.0 6141.3 4485.0 5822.7 5872.3 5877.0 6012.1 5915.6 6108.8 6117.3 6180.4 6199.9 6021.7 6025.5 6033.3 6054.7 6057.2 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:71234.14 Median:39210.92 stddev:154894.42 (395.0%) stddev-sans-first:155237.73 16:18:17 INFO - PROCESS | 1642 | Values: 437.8 485.8 501.1 501.3 503.4 31206.5 41514.6 42986.4 43040.8 45244.5 44188.6 44485.6 44694.3 44790.0 45514.0 9263.4 9462.3 9464.1 9487.5 9498.3 8507.9 8747.4 11216.7 11512.8 11518.9 11435.5 11515.8 11521.9 11523.7 11544.5 8528.0 11407.7 11477.5 11487.7 11495.4 8484.4 11124.4 11415.7 11422.6 11630.4 41755.8 42063.4 42101.7 42165.0 42274.8 4028.5 4039.8 4041.0 4055.3 4056.9 22813.0 29266.7 30462.7 30470.5 31522.1 3169.9 3254.2 3294.9 3401.9 3405.6 467992.0 475194.0 476156.6 477515.0 477668.6 61914.0 62344.8 62391.7 62443.4 62571.7 65027.0 65284.7 65358.3 65370.3 65639.0 16543.4 16592.4 16916.1 16919.8 16930.2 11652.2 11999.4 13037.1 13139.9 13197.7 14344.4 18916.1 22417.0 22592.7 23117.4 7541.9 8809.0 8880.3 8972.6 9022.3 819.7 851.7 868.4 873.4 926.7 24328.0 29646.1 30108.1 30278.8 30981.7 857.1 962.0 1067.2 1081.7 1222.2 14799.0 15052.3 15563.9 15710.3 15863.9 642435.0 648313.0 650721.0 653022.0 653130.0 61112.0 61530.0 61627.4 61664.0 62091.0 1444.2 1447.6 1462.4 1476.2 1489.9 28532.7 29992.3 30063.5 30760.7 30823.8 51730.7 52635.5 52683.0 52792.5 52892.7 594986.0 600327.0 600375.0 603367.0 603741.0 22438.8 27348.9 27924.0 28202.7 28223.3 46825.5 47678.3 48208.0 48741.8 48826.8 38444.2 39344.0 39470.6 39718.8 40766.6 35835.8 36069.5 37857.2 38376.8 39077.8 35813.1 36103.5 36242.6 36973.4 37069.4 30352.6 32232.8 32435.5 32933.8 32940.8 22783.0 23304.7 23609.9 24179.9 24412.6 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:40622.90 Median:2696.96 stddev:125485.75 (4652.9%) stddev-sans-first:125801.41 16:18:17 INFO - PROCESS | 1642 | Values: 391.1 395.2 398.7 406.9 423.8 20257.0 20778.8 21492.1 21617.9 21671.0 12156.2 12442.8 19720.5 21536.9 21683.7 4576.8 4941.2 6069.0 6136.5 6140.9 3975.2 4182.3 6038.2 6132.6 6136.8 4201.0 4209.8 6825.3 6885.3 6915.2 6637.3 6784.9 6869.9 6892.5 6901.8 5950.6 5957.4 6081.8 6131.7 6138.2 13455.6 13605.3 13723.3 13764.4 19940.0 2686.4 2707.6 2708.9 2754.7 2814.7 8857.9 9761.6 10342.9 10693.9 10766.1 1844.3 1890.3 1893.8 1902.8 1933.7 118199.0 120060.6 126047.4 126229.0 126243.0 10194.3 11594.2 12048.1 12080.5 12119.8 13735.6 15041.9 15635.5 16049.0 16289.5 8700.5 8893.3 9111.9 9228.9 9236.2 8576.4 8615.9 8729.9 8872.7 8911.5 4073.9 4811.1 4816.6 4821.6 4869.1 2044.4 2072.2 2081.4 2089.4 2095.1 812.8 877.9 878.1 879.9 903.5 17734.8 20949.5 21661.1 21734.2 21900.0 901.3 905.9 912.6 912.8 921.8 10715.1 11031.7 11043.6 11056.0 11065.4 724892.8 727780.0 744806.0 762151.0 805115.0 149967.5 153034.0 156449.0 161174.0 161394.0 985.5 994.8 1003.9 1009.0 1014.5 18815.8 19287.4 19644.4 19681.5 19686.7 59969.0 60942.4 61511.7 61720.4 61879.1 88660.6 89613.7 91082.0 92656.5 93758.9 7865.1 7971.3 8171.2 8329.8 8780.9 9396.5 12106.9 13008.3 13109.0 13244.7 10033.4 11291.4 11414.8 11699.3 11791.0 10857.9 10986.2 11252.5 11447.0 11486.4 10090.5 11090.5 11339.0 11691.3 11757.9 8362.2 8393.0 8550.3 8558.4 8615.9 10298.8 11103.4 11220.6 11436.8 11537.5 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5422.03 Median:5220.21 stddev:2906.78 (55.7%) stddev-sans-first:2898.67 16:18:17 INFO - PROCESS | 1642 | Values: 1330.5 1445.1 1468.8 1474.5 1475.2 7507.5 8152.9 8392.5 8398.9 8444.4 7564.9 7732.0 8048.4 8269.5 8272.7 2310.3 2343.4 2349.0 2353.9 2363.3 2264.0 2357.0 2371.1 2372.4 2374.3 2053.5 2127.9 2130.8 2260.9 2346.3 2326.3 2345.8 2349.5 2352.7 2357.9 2278.8 2378.4 2384.7 2385.9 2386.9 7279.6 8434.7 8518.4 8546.6 8561.0 4302.5 5159.4 5212.8 5213.3 5221.7 3335.8 3404.1 3474.3 3525.7 3590.4 1250.5 1271.9 1732.6 1763.5 1841.0 10169.9 10253.0 10254.4 10391.7 10680.5 4624.9 4729.4 4779.5 4779.7 4787.1 9527.0 9535.6 9537.4 9564.4 9566.5 4979.2 5212.0 5218.7 5223.3 5225.1 4653.9 4737.2 4785.9 4796.5 4797.2 2976.7 3621.4 3705.7 3760.9 3765.9 1276.6 1294.0 1297.4 1311.7 1312.6 739.1 796.7 808.4 809.3 820.4 5140.2 5595.7 5597.6 5609.3 5627.1 992.1 993.0 1008.5 1036.0 1065.9 3617.0 3623.5 3690.7 3790.3 3899.5 9306.7 9809.0 9861.5 9948.9 9986.0 8509.7 9865.3 9872.0 9880.2 9942.6 7209.7 7928.4 7936.9 7948.9 7975.3 5328.8 5450.8 5469.5 5566.6 5642.4 8947.3 9137.7 9208.1 9218.9 9294.0 9227.5 9369.5 9398.8 9511.1 9541.7 7203.2 7334.6 7336.6 7342.3 7345.5 7595.2 7887.5 7986.5 8070.4 8087.8 7128.6 7173.3 7243.0 7251.7 7368.4 7049.7 7110.9 7117.5 7129.1 7132.2 6360.0 6400.6 6433.3 6495.3 6508.1 4939.5 4951.1 4976.3 5024.1 5024.7 4868.5 4869.9 4915.4 5020.9 5054.4 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4352.45 Median:4823.99 stddev:1315.77 (27.3%) stddev-sans-first:1284.89 16:18:17 INFO - PROCESS | 1642 | Values: 360.5 366.9 373.4 383.4 383.8 4599.4 5139.7 5194.4 5208.8 5240.5 4866.5 4886.4 5013.0 5064.6 5294.2 4045.8 4115.4 4125.0 4154.8 4168.4 3808.9 3809.7 4068.7 4069.6 4092.9 3634.8 3750.2 3809.9 3835.7 4149.5 3442.9 3771.5 4067.8 4119.1 4124.8 3551.9 3568.6 3619.4 3620.7 3640.9 3847.9 4992.4 5100.3 5120.5 5144.4 4481.9 4497.7 4544.4 4549.2 4603.8 5102.3 5379.3 5417.7 5536.3 5553.4 3678.6 3777.4 3809.3 3818.5 3822.0 5487.9 5737.6 5751.5 5764.3 5812.8 3686.4 3715.6 3824.8 3829.8 3871.9 3328.6 3358.0 3917.7 3943.2 3954.4 3864.6 4255.6 4297.6 4465.2 4522.4 4964.6 5370.0 5554.1 5573.2 5617.5 1086.3 1118.9 1196.4 1226.6 1249.6 805.2 815.9 828.7 850.4 855.6 4239.4 4406.9 4465.7 4769.8 4801.3 4460.0 4773.6 5326.8 5365.2 5430.0 3995.1 4211.7 4372.7 4512.9 4846.7 2415.7 2590.6 2746.3 2762.4 2769.3 4776.5 5362.5 5446.8 5459.7 5595.6 4548.4 4621.6 4871.0 4905.7 5449.3 4902.3 5412.7 5437.4 5465.2 5530.8 4534.9 4768.6 5054.5 5269.3 5333.8 4693.1 4795.8 5057.3 5170.5 5176.2 5252.8 5369.3 5404.3 5406.9 5439.1 3538.8 3558.8 3577.2 3577.3 3614.3 5196.0 5254.1 5440.8 5507.8 5684.1 5488.6 5518.9 5520.8 5524.8 5541.5 4874.9 5224.3 5324.8 5545.0 5567.4 4234.8 5355.3 5417.4 5452.6 5454.6 5306.2 5324.3 5338.9 5494.9 5508.7 5341.8 5342.7 5378.8 5394.3 5419.9 16:18:17 INFO - PROCESS | 1642 | 16:18:17 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19816.67 Median:50762.25 stddev:28729.48 (56.6%) stddev-sans-first:28774.31 16:18:17 INFO - PROCESS | 1642 | Values: 725.8 730.1 777.6 834.1 850.4 14951.6 15643.7 16101.4 16118.6 16129.4 14535.5 14554.7 14588.5 14728.9 14732.0 694.0 726.0 732.3 871.3 907.0 908.4 917.5 920.1 920.2 926.7 982.0 985.7 987.7 988.5 989.6 736.6 765.0 921.8 981.6 983.4 719.4 725.4 726.1 727.8 732.5 15157.3 15528.5 15543.3 15549.4 15590.9 13824.5 13963.0 13967.8 14015.7 14236.9 12321.6 12423.7 12674.2 12853.2 13036.5 4213.5 4329.8 4550.0 4664.3 4703.5 99958.0 103248.9 105321.9 110637.9 110724.0 11061.3 11066.8 11097.4 11154.8 11214.0 21841.5 22296.8 22365.3 22382.6 22411.0 7650.7 7878.4 7949.5 7955.6 8054.1 4269.0 4277.9 4320.1 4527.0 4532.0 3468.5 3545.1 3551.8 3551.9 3559.4 629.5 631.1 652.8 662.3 663.3 629.4 650.6 660.6 662.9 681.4 7624.5 9087.0 9415.3 9545.9 9607.7 588.7 593.7 614.8 621.7 645.4 4408.7 4774.0 4779.1 4823.6 4843.5 85086.0 98324.5 101377.4 102181.0 104132.5 70151.6 86802.7 88665.0 89671.6 90441.0 63420.7 76847.0 79253.6 79293.0 80760.0 8847.9 9229.4 9328.3 9462.2 9549.5 41928.6 50167.7 50502.7 51021.7 51737.0 64903.0 64921.0 66005.0 66795.3 68090.0 10677.9 10819.6 10912.8 10930.5 11425.3 11816.5 11872.8 11964.6 12064.1 12114.9 11161.9 11455.7 11538.9 11762.0 11828.2 11110.4 11187.9 11635.8 11637.0 11769.8 11200.2 11535.3 11544.9 11603.2 11622.1 9481.3 9635.7 9649.4 9702.8 10281.1 9302.4 9426.3 9437.2 9461.3 9537.1 16:18:17 INFO - PROCESS | 1642 | -------- Summary: end -------- 16:18:17 INFO - PROCESS | 1642 | 16:18:18 INFO - TEST-INFO | 1642: exit 0 16:18:18 INFO - TEST-OK | dromaeo_css | took 1117154ms 16:18:18 INFO - TEST-START | kraken 16:18:18 INFO - operating with platform_type : mac_ 16:18:18 INFO - Initialising browser for kraken test... 16:18:18 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpNHwsj0/profile http://localhost:49241/getInfo.html) 16:18:20 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 16:18:20 INFO - PROCESS | 1649 | colorDepth:24 16:18:20 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 16:18:20 INFO - PROCESS | 1649 | __metrics 16:18:20 INFO - PROCESS | 1649 | 1486513100796 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 16:18:20 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:18:20 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:21 INFO - TEST-INFO | 1649: exit 0 16:18:21 INFO - Browser initialized. 16:18:21 INFO - Running cycle 1/1 for kraken test... 16:18:21 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpNHwsj0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:33 INFO - PROCESS | 1650 | RSS: Main: 213061632 16:18:33 INFO - PROCESS | 1650 | 16:18:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 16:18:37 INFO - PROCESS | 1650 | RSS: Main: 265990144 16:18:37 INFO - PROCESS | 1650 | 16:18:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 16:18:40 INFO - PROCESS | 1650 | RSS: Main: 275369984 16:18:40 INFO - PROCESS | 1650 | 16:18:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 16:18:44 INFO - PROCESS | 1650 | RSS: Main: 371949568 16:18:44 INFO - PROCESS | 1650 | 16:18:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 16:18:46 INFO - PROCESS | 1650 | RSS: Main: 275828736 16:18:46 INFO - PROCESS | 1650 | 16:18:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:18:49 INFO - PROCESS | 1650 | RSS: Main: 277057536 16:18:49 INFO - PROCESS | 1650 | 16:18:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 16:18:52 INFO - PROCESS | 1650 | RSS: Main: 316805120 16:18:52 INFO - PROCESS | 1650 | 16:18:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 16:18:56 INFO - PROCESS | 1650 | RSS: Main: 317947904 16:18:56 INFO - PROCESS | 1650 | 16:18:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 16:18:59 INFO - PROCESS | 1650 | RSS: Main: 315473920 16:18:59 INFO - PROCESS | 1650 | 16:19:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:19:02 INFO - PROCESS | 1650 | RSS: Main: 294236160 16:19:02 INFO - PROCESS | 1650 | 16:19:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:19:04 INFO - PROCESS | 1650 | RSS: Main: 294375424 16:19:04 INFO - PROCESS | 1650 | 16:19:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:19:07 INFO - PROCESS | 1650 | RSS: Main: 297766912 16:19:07 INFO - PROCESS | 1650 | 16:19:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:19:11 INFO - PROCESS | 1650 | RSS: Main: 315428864 16:19:11 INFO - PROCESS | 1650 | 16:19:13 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:19:14 INFO - PROCESS | 1650 | RSS: Main: 309137408 16:19:14 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 16:19:16 INFO - PROCESS | 1650 | RSS: Main: 353804288 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | __start_tp_report 16:19:16 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 16:19:16 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 16:19:16 INFO - PROCESS | 1650 | |i|pagename|runs| 16:19:16 INFO - PROCESS | 1650 | |0;ai-astar;118;115;113;114;114;115;113;113;113;114 16:19:16 INFO - PROCESS | 1650 | |1;audio-beat-detection;142;108;105;136;122;110;122;108;127;108 16:19:16 INFO - PROCESS | 1650 | |2;audio-dft;183;182;185;185;185;184;182;183;183;181 16:19:16 INFO - PROCESS | 1650 | |3;audio-fft;81;57;56;88;63;59;56;58;70;58 16:19:16 INFO - PROCESS | 1650 | |4;audio-oscillator;83;71;108;101;97;74;82;67;69;83 16:19:16 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;86;83;85;83;86;83;87;84;86;87 16:19:16 INFO - PROCESS | 1650 | |6;imaging-darkroom;92;93;92;93;92;92;92;92;91;92 16:19:16 INFO - PROCESS | 1650 | |7;imaging-desaturate;124;131;91;87;89;85;85;86;86;86 16:19:16 INFO - PROCESS | 1650 | |8;json-parse-financial;56;53;55;55;55;56;56;58;57;57 16:19:16 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;65;76;81;73;72;68;70;69;70;72 16:19:16 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;83;82;79;81;84;81;82;84;84;83 16:19:16 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;142;140;142;135;152;139;143;140;183;152 16:19:16 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;164;164;165;166;166;165;164;164;163;165 16:19:16 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;66;66;66;64;65;67;65;67;65;65 16:19:16 INFO - PROCESS | 1650 | __end_tp_report 16:19:16 INFO - PROCESS | 1650 | __start_cc_report 16:19:16 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2050 16:19:16 INFO - PROCESS | 1650 | __end_cc_report 16:19:16 INFO - PROCESS | 1650 | __startTimestamp1486513156517__endTimestamp 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | ------- Summary: start ------- 16:19:16 INFO - PROCESS | 1650 | Number of tests: 14 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.00 stddev:1.55 (1.4%) stddev-sans-first:0.83 16:19:16 INFO - PROCESS | 1650 | Values: 118.0 115.0 113.0 114.0 114.0 115.0 113.0 113.0 113.0 114.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:122.00 stddev:13.08 (10.7%) stddev-sans-first:10.85 16:19:16 INFO - PROCESS | 1650 | Values: 142.0 108.0 105.0 136.0 122.0 110.0 122.0 108.0 127.0 108.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.42 (0.8%) stddev-sans-first:1.50 16:19:16 INFO - PROCESS | 1650 | Values: 183.0 182.0 185.0 185.0 185.0 184.0 182.0 183.0 183.0 181.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:64.60 Median:61.00 stddev:11.41 (18.7%) stddev-sans-first:10.45 16:19:16 INFO - PROCESS | 1650 | Values: 81.0 57.0 56.0 88.0 63.0 59.0 56.0 58.0 70.0 58.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:78.00 stddev:14.22 (18.2%) stddev-sans-first:15.08 16:19:16 INFO - PROCESS | 1650 | Values: 83.0 71.0 108.0 101.0 97.0 74.0 82.0 67.0 69.0 83.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.63 (1.9%) stddev-sans-first:1.69 16:19:16 INFO - PROCESS | 1650 | Values: 86.0 83.0 85.0 83.0 86.0 83.0 87.0 84.0 86.0 87.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:19:16 INFO - PROCESS | 1650 | Values: 92.0 93.0 92.0 93.0 92.0 92.0 92.0 92.0 91.0 92.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:86.00 stddev:17.31 (20.1%) stddev-sans-first:14.84 16:19:16 INFO - PROCESS | 1650 | Values: 124.0 131.0 91.0 87.0 89.0 85.0 85.0 86.0 86.0 86.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.40 (2.5%) stddev-sans-first:1.48 16:19:16 INFO - PROCESS | 1650 | Values: 56.0 53.0 55.0 55.0 55.0 56.0 56.0 58.0 57.0 57.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:4.45 (6.2%) stddev-sans-first:4.03 16:19:16 INFO - PROCESS | 1650 | Values: 65.0 76.0 81.0 73.0 72.0 68.0 70.0 69.0 70.0 72.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:1.64 (2.0%) stddev-sans-first:1.72 16:19:16 INFO - PROCESS | 1650 | Values: 83.0 82.0 79.0 81.0 84.0 81.0 82.0 84.0 84.0 83.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:142.50 stddev:13.81 (9.7%) stddev-sans-first:14.54 16:19:16 INFO - PROCESS | 1650 | Values: 142.0 140.0 142.0 135.0 152.0 139.0 143.0 140.0 183.0 152.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:165.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 16:19:16 INFO - PROCESS | 1650 | Values: 164.0 164.0 165.0 166.0 166.0 165.0 164.0 164.0 163.0 165.0 16:19:16 INFO - PROCESS | 1650 | 16:19:16 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 16:19:16 INFO - PROCESS | 1650 | Values: 66.0 66.0 66.0 64.0 65.0 67.0 65.0 67.0 65.0 65.0 16:19:16 INFO - PROCESS | 1650 | -------- Summary: end -------- 16:19:16 INFO - PROCESS | 1650 | 16:19:17 INFO - TEST-INFO | 1650: exit 0 16:19:17 INFO - TEST-OK | kraken | took 58869ms 16:19:17 INFO - SUITE-END | took 1176s 16:19:17 INFO - Completed test suite (00:19:36) 16:19:17 INFO - javascript benchmark 16:19:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2831.943361132777, 3326.4511355626323, 3336.549565808615, 3343.715784158346, 3373.1567108222944, 4689.742064186468, 5720.6853623050765, 5724.828255152342, 5763.942360576393, 5769.596128271028, 5695.77216911323, 5762.654240745548, 5766.221560089387, 5768.048272035119, 5807.767689292423, 5179.19722443022, 5191.325127733389, 5229.3201883755055, 5231.346081739783, 5288.0745869472885, 4761.40482439695, 4828.903421931577, 5174.275601415805, 5310.7610157543, 5345.812896548621, 5187.870303242411, 5252.474752524736, 5273.314469119009, 5293.6294459387855, 5314.840554783344, 5294.788208471676, 5307.416184219733, 5307.761150748206, 5329.147336426153, 5349.625526213166, 5031.698098114125, 5133.743312834381, 5232.319798426218, 5259.184826351883, 5267.341582302212, 5589.692566908819, 5677.432256774289, 5682.715864206814, 5723.427657234243, 5762.510186634141, 5995.010823214165, 6205.0692396140485, 6271.0, 6292.834269410744, 6327.683615819236, 6226.501879849601, 6317.494600432, 6318.684065796738, 6320.589161704521, 6323.715432805535, 5790.218320526717, 5812.186293918855, 5827.504662103684, 5840.678762668052, 5876.706164691748, 6213.875722485525, 6279.623222606659, 6288.182536270347, 6294.559380843297, 6307.432331090224, 6202.658853762996, 6202.844928876724, 6215.285242197115, 6216.067589861466, 6228.0, 6181.165542651775, 6204.255489341308, 6231.283402408691, 6235.563510554218, 6292.937070629235, 6245.937540624626, 6305.369463053658, 6307.148534947723, 6313.9368606314265, 6315.936840631627, 6328.746850125944, 6331.746730130835, 6340.651264180422, 6349.777757778455, 6357.4596159326975, 4862.6839255448285, 4938.358013458227, 4954.20732682769, 5016.272640467112, 5054.570361519312, 4438.267685831866, 4470.2177119004045, 4493.168763778646, 4525.547445255448, 4530.954690453119, 5702.771889124471, 5799.6230245034785, 5804.216430781875, 5817.621854579439, 5825.621334613236, 5235.528802407725, 6157.784477543264, 6161.7227224775, 6162.753489860355, 6206.782762603287, 5754.079347304411, 5762.481376676119, 5792.623479473821, 5802.0, 5806.0129777937855, 5412.539934105566, 5469.535089517356, 5478.616496845262, 5478.945210547923, 5495.890082198413, 6218.9378106218355, 6295.874082518232, 6298.937010629835, 6330.8100756977365, 6344.873102538016, 6209.844753881189, 6242.126102345766, 6269.905951410824, 6272.090546870587, 6321.905171422524, 6312.715927783169, 6312.905306420315, 6312.905306420315, 6325.05124231369, 6334.144890439732, 6190.721417536131, 6253.749850006131, 6256.530760192913, 6257.530685198537, 6277.591956522811, 6143.447089761943, 6160.291566469913, 6187.041008643668, 6199.56603037783, 6240.032794916795, 6087.560528301496, 6118.497354391859, 6149.754009839557, 6166.445019948226, 6175.505959523317, 6062.333143354315, 6109.009944784373, 6114.784027539525, 6121.0, 6141.301904043321, 4485.035717320791, 5822.650640961556, 5872.324682661378, 5877.0, 6012.098185272244, 5915.645061296336, 6108.7670647404, 6117.3402608778015, 6180.381961803783, 6199.876002479835, 6021.712763207513, 6025.487833534199, 6033.306169790355, 6054.666993315233, 6057.151998720271], "value": 5707.462280068954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.7570448401138, 485.764404263932, 501.1480483178597, 501.30569161711065, 503.3808415648752, 31206.53190202149, 41514.58485415145, 42986.355204671876, 43040.784796075975, 45244.54755452445, 44188.55811441885, 44485.555144448546, 44694.32958505625, 44789.99999999992, 45514.0, 9263.397879137852, 9462.290328225376, 9464.148226659634, 9487.478188699619, 9498.335116541844, 8507.872381914276, 8747.431416957908, 11216.719582010473, 11512.848715128504, 11518.884811151866, 11435.542578296858, 11515.827262591069, 11521.942390288037, 11523.711907202345, 11544.538218471293, 8527.957360213159, 11407.714807129847, 11477.483513241925, 11487.655370338904, 11495.425228738572, 8484.406091573592, 11124.443777811077, 11415.657530274022, 11422.600208992728, 11630.418479076097, 41755.791221044004, 42063.36904946428, 42101.736947891615, 42165.0, 42274.78862605698, 4028.47629808126, 4039.818208180639, 4041.0, 4055.3105971984833, 4056.8580099696665, 22813.0, 29266.70733292661, 30462.69537304621, 30470.54294185589, 31522.054338369657, 3169.9207519811957, 3254.1864533866533, 3294.901152965415, 3401.9319613607836, 3405.625381208065, 467992.0, 475194.0, 476156.6192169017, 477515.0, 477668.6116569395, 61914.0, 62344.75310493765, 62391.6880415595, 62443.375566244664, 62571.687141564, 65027.0, 65284.673576631816, 65358.34641653523, 65370.34629653642, 65639.0, 16543.420980265873, 16592.419265325654, 16916.069616170982, 16919.830801691827, 16930.238139283763, 11652.184347095623, 11999.400029998465, 13037.087403881636, 13139.934300328438, 13197.73604527904, 14344.354504047344, 18916.09911607024, 22417.0, 22592.66111008336, 23117.422064448525, 7541.886871696929, 8808.955955220312, 8880.333974951904, 8972.564389697616, 9022.278217742565, 819.7499762572472, 851.6933903794629, 868.405142477405, 873.4191762477881, 926.7173512078746, 24328.0, 29646.110616681533, 30108.096757097323, 30278.84860575727, 30981.69018309833, 857.0872021297298, 961.9658866718306, 1067.2262609607972, 1081.7133459633203, 1222.2299951030793, 14799.0, 15052.322645480761, 15563.922180389025, 15710.293036813371, 15863.920680396524, 642435.0, 648313.0, 650721.0, 653022.0, 653130.0, 61112.0, 61530.0, 61627.38372616306, 61664.0, 62091.0, 1444.205686872237, 1447.6201776894407, 1462.436961769705, 1476.2249818844978, 1489.858463445971, 28532.714672853006, 29992.25019374533, 30063.549046764754, 30760.692393075784, 30823.84588077135, 51730.74134629303, 52635.47364526306, 52683.0, 52792.47207528029, 52892.73553632207, 594986.0, 600327.0, 600375.0, 603367.0, 603741.0, 22438.77561224432, 27348.863255683595, 27924.0, 28202.71797282001, 28223.294417639725, 46825.53174468348, 47678.28482572833, 48208.0, 48741.75629121974, 48826.75586622044, 38444.2311153781, 39344.01639959024, 39470.60529394784, 39718.80140599279, 40766.59233407747, 35835.82289869946, 36069.45895811617, 37857.2428551433, 38376.80811595924, 39077.82766517009, 35813.1046723834, 36103.45844812277, 36242.63757362393, 36973.44539831851, 37069.44395834119, 30352.598873162828, 32232.83883580567, 32435.513467298482, 32933.83533082319, 32940.83529582433, 22783.0, 23304.6504302439, 23609.881950590137, 24179.911903964716, 24412.63381049321], "value": 20482.771694534706, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.10632205410633, 395.17014270032934, 398.72687209261676, 406.86776797540807, 423.84953341563744, 20256.99999999998, 20778.753274803483, 21492.140314387405, 21617.891910540446, 21670.99999999998, 12156.209846360003, 12442.751144977095, 19720.506987325323, 21536.923153842323, 21683.67474487884, 4576.816927322903, 4941.184704523749, 6068.969655151718, 6136.47839933604, 6140.877182456348, 3975.185087057146, 4182.289010868145, 6038.215032045828, 6132.63204207749, 6136.815895523141, 4200.957990420087, 4209.831606735727, 6825.283345248752, 6885.277045910182, 6915.1701795784575, 6637.336266373372, 6784.880494718365, 6869.896951545731, 6892.483063770237, 6901.827454313632, 5950.6132101413705, 5957.404259574051, 6081.847953801146, 6131.662758548278, 6138.202033735586, 13455.596332110052, 13605.31973401326, 13723.313834308345, 13764.380602872894, 19940.0, 2686.3552747340495, 2707.56678931372, 2708.8510131942735, 2754.7107553706774, 2814.704456032108, 8857.867131993025, 9761.560729767178, 10342.948285258526, 10693.946530267376, 10766.084882784973, 1844.2715127524687, 1890.3383815664545, 1893.8200870917242, 1902.781180164285, 1933.7389452423884, 118199.0, 120060.59878802462, 126047.36976315151, 126229.0, 126243.0, 10194.286399951932, 11594.246373985832, 12048.108453970985, 12080.456379462945, 12119.818202726967, 13735.55776643438, 15041.924790375979, 15635.530934071996, 16049.0, 16289.51131466058, 8700.477971321614, 8893.28853691703, 9111.863322050174, 9228.907710922938, 9236.214921731727, 8576.44253123545, 8615.870761938577, 8729.869051964226, 8872.73381798547, 8911.465312081296, 4073.9000469872967, 4811.133995880705, 4816.555033489939, 4821.566059054702, 4869.14789911764, 2044.427560283123, 2072.171131547363, 2081.4172031831113, 2089.4358523198653, 2095.130520833867, 812.8252425728497, 877.9157740190986, 878.0560897035712, 879.9208071273488, 903.4669544968368, 17734.822651773316, 20949.476263093544, 21661.133554657954, 21734.239301624366, 21900.0, 901.2519608724742, 905.8822353094056, 912.5802131019806, 912.8037471943431, 921.7924518880288, 10715.142788576895, 11031.724206894893, 11043.613473528389, 11056.0, 11065.446727663746, 724892.751072493, 727780.0, 744806.0, 762151.0, 805115.0, 149967.50032499753, 153034.0, 156449.0, 161174.0, 161394.0, 985.482621623639, 994.8010397920444, 1003.9397636141708, 1009.0, 1014.5383850348074, 18815.811841881405, 19287.421377358703, 19644.410667679993, 19681.507962300486, 19686.704699429807, 59969.0, 60942.390576095444, 61511.6924415375, 61720.38279617146, 61879.071813921924, 88660.5566972161, 89613.72703910664, 91082.0, 92656.53671731598, 93758.89570858555, 7865.095514015961, 7971.3224375926375, 8171.223733745284, 8329.83340333202, 8780.912190878009, 9396.483193424427, 12106.910378066017, 13008.349582520646, 13109.016823738448, 13244.735105297646, 10033.44816035125, 11291.3789741565, 11414.771704565695, 11699.2980421175, 11791.0, 10857.891421085687, 10986.176036797113, 11252.493637786483, 11447.0, 11486.368249746343, 10090.545925433225, 11090.500927458444, 11339.0, 11691.298522088702, 11757.941210293893, 8362.163783621832, 8392.958035209786, 8550.315974721883, 8558.443701159405, 8615.870761938451, 10298.845517317395, 11103.38931358783, 11220.607278745205, 11436.82844757346, 11537.538498459968], "value": 9768.30392905293, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.5409633676384, 1445.1329202478514, 1468.8017117689108, 1474.4544518528146, 1475.2255066090304, 7507.512011719235, 8152.91847081529, 8392.454490458123, 8398.91601083989, 8444.366672499571, 7564.886526702104, 7732.033495813023, 8048.436609437342, 8269.503829770218, 8272.71045513407, 2310.3300042987557, 2343.379004563792, 2349.048635302705, 2353.9058437662557, 2363.2673871100014, 2264.0, 2357.0, 2371.063429945171, 2372.418757404438, 2374.2639781667654, 2053.4558342039372, 2127.9148834046696, 2130.7762684918093, 2260.9095636174534, 2346.307839187435, 2326.278853555403, 2345.812335013195, 2349.459624286415, 2352.741198468167, 2357.893894774722, 2278.7721227877246, 2378.393509655044, 2384.7257565379855, 2385.8687772172525, 2386.904523819045, 7279.636018199122, 8434.704785332546, 8518.40371174018, 8546.572671366406, 8560.957195214047, 4302.526722060571, 5159.432462429129, 5212.765425555859, 5213.2701421800975, 5221.73891305437, 3335.8332083395735, 3404.097914052778, 3474.322507111114, 3525.7179425645886, 3590.407582748843, 1250.4685508658874, 1271.89188918942, 1732.6274850907116, 1763.5150333658219, 1840.9631807363778, 10169.9491502542, 10253.0, 10254.384736915808, 10391.688249352532, 10680.519376627994, 4624.930626040612, 4729.43246810385, 4779.450363208206, 4779.71321720691, 4787.066522028241, 9527.0, 9535.618575256913, 9537.427754334762, 9564.378315409615, 9566.521673916277, 4979.228219625964, 5212.035773381936, 5218.6868787872845, 5223.268742376071, 5225.085610018307, 4653.9498651753265, 4737.24204127338, 4785.856424307276, 4796.496367881392, 4797.160496913027, 2976.732094111497, 3621.420572708354, 3705.7220708446976, 3760.8683696070507, 3765.8681946131755, 1276.6489215465635, 1294.0359432223054, 1297.4485843516468, 1311.731095125497, 1312.6324629103867, 739.0650826704224, 796.7052190689482, 808.3775492870575, 809.2716555100457, 820.4175035724702, 5140.203268493315, 5595.664260144405, 5597.552195824405, 5609.326880774333, 5627.071533197059, 992.1219720547367, 992.9722736967349, 1008.5108722269694, 1035.963741269052, 1065.8561094252332, 3616.9819150904077, 3623.456481527739, 3690.6678398944223, 3790.298794722985, 3899.512560929884, 9306.72079837606, 9809.0, 9861.506924653739, 9948.850767238497, 9986.0013998601, 8509.70216042448, 9865.260105491972, 9871.950640246896, 9880.209583233323, 9942.552585133686, 7209.675564599605, 7928.4450088494405, 7936.920630793734, 7948.920510794934, 7975.282224599698, 5328.8401347959625, 5450.809221677222, 5469.453054694499, 5566.610337276514, 5642.379338272704, 8947.328950328621, 9137.725868223964, 9208.12522810332, 9218.861717074384, 9294.0, 9227.538623068955, 9369.531523423939, 9398.765030874009, 9511.143997040299, 9541.666041688508, 7203.171635262012, 7334.596597187206, 7336.596487193256, 7342.339189472973, 7345.522541034816, 7595.202503737028, 7887.487313324616, 7986.520808751494, 8070.435069545076, 8087.8382432349845, 7128.607926564089, 7173.318534739192, 7242.96378518104, 7251.709931602677, 7368.410527157922, 7049.718011279491, 7110.893336600058, 7117.501774875709, 7129.108861392326, 7132.215456299907, 6360.045993101072, 6400.583962042452, 6433.324500927522, 6495.2855185928565, 6508.08886755864, 4939.506049395031, 4951.084049450863, 4976.253561965734, 5024.070546948826, 5024.748762561931, 4868.537488938545, 4869.878253043702, 4915.43472500667, 5020.874478138076, 5054.368203974504], "value": 4442.675951336982, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.47010893985845, 366.87342866710975, 373.44356908206765, 383.36170276489645, 383.7585411194786, 4599.3560901473775, 5139.717315547643, 5194.415853061385, 5208.828013696926, 5240.528352448279, 4866.493755715809, 4886.389201349832, 5012.9749351253195, 5064.594317699091, 5294.1529355303155, 4045.8179381927885, 4115.444415003981, 4124.99937530299, 4154.792260386968, 4168.374743788441, 3808.9238215235678, 3809.676177524914, 4068.7151899367054, 4069.572694867041, 4092.85675001375, 3634.8000859952826, 3750.1686226898655, 3809.895130412177, 3835.6766915414137, 4149.502059752834, 3442.862285508577, 3771.509703738524, 4067.7559346439307, 4119.114390406062, 4124.793760311973, 3551.8579256829694, 3568.6074531801482, 3619.4208926571623, 3620.6922411595046, 3640.8725694600826, 3847.8644352384417, 4992.40091189056, 5100.311457953203, 5120.462351453119, 5144.4083930348115, 4481.901934026152, 4497.662675299365, 4544.409226800495, 4549.181147393468, 4603.792829322689, 5102.275339002512, 5379.300690910196, 5417.729113544307, 5536.283752036841, 5553.416891226403, 3678.6321367863266, 3777.3578491656485, 3809.3333666608232, 3818.4654148419245, 3822.0, 5487.890242195173, 5737.569682273805, 5751.539876809845, 5764.308283006066, 5812.82561523155, 3686.382668305533, 3715.606647507185, 3824.7513911596216, 3829.7936150112623, 3871.922561548753, 3328.6005679318637, 3358.0, 3917.7257591968287, 3943.2310699413338, 3954.387070004154, 3864.574896761354, 4255.574442555781, 4297.5702429756775, 4465.218586747306, 4522.412086428743, 4964.627652926045, 5369.973150134302, 5554.139872788373, 5573.247611572386, 5617.494425501724, 1086.3101930274174, 1118.8713297970676, 1196.3898411809867, 1226.5707002549216, 1249.6188662457855, 805.1586092533316, 815.8898548695969, 828.7099515169642, 850.4174640371366, 855.619249433996, 4239.40648309237, 4406.876246557107, 4465.665075119379, 4769.809207631725, 4801.303810947463, 4459.977700111479, 4773.615651461107, 5326.786928522815, 5365.222042803772, 5430.0, 3995.1210733638554, 4211.696644775888, 4372.737635741865, 4512.909741805145, 4846.684965477232, 2415.7221919479134, 2590.611408288734, 2746.3408781892203, 2762.3646561291016, 2769.3492029373483, 4776.546228108324, 5362.517373436332, 5446.754896029688, 5459.672419654833, 5595.5731288521165, 4548.363229147922, 4621.636617197934, 4870.975645121752, 4905.664148379136, 5449.3188351456065, 4902.264660300983, 5412.671207207539, 5437.374701909331, 5465.180222966586, 5530.768961744269, 4534.89127555675, 4768.61851051922, 5054.545090941833, 5269.314989051399, 5333.759980800795, 4693.0613877224005, 4795.808167673254, 5057.342545469212, 5170.457102004236, 5176.188334083191, 5252.842414727565, 5369.328833895763, 5404.297441332759, 5406.8648283791645, 5439.129739241703, 3538.823623471598, 3558.8220588970967, 3577.195131095483, 3577.3024260269, 3614.331348700499, 5195.9735703075075, 5254.080535906278, 5440.836774896759, 5507.7676207602135, 5684.147377893349, 5488.643238189665, 5518.862028449321, 5520.806771762969, 5524.751386187711, 5541.501264886018, 4874.8537543873745, 5224.2947202127225, 5324.790212061951, 5544.972275138599, 5567.387587365306, 4234.78826058702, 5355.277037599874, 5417.370361857644, 5452.591055670761, 5454.590905682169, 5306.151015837448, 5324.281222034976, 5338.946610534001, 5494.862628434321, 5508.669479831223, 5341.798095428498, 5342.732863356739, 5378.784848606012, 5394.271773310553, 5419.918701219406], "value": 3946.499550916621, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.8294300839306, 730.0655161393415, 777.5723352156316, 834.0658462521976, 850.3707256630091, 14951.626209344759, 15643.687126257468, 16101.355945762185, 16118.597035074128, 16129.354825806968, 14535.49125780598, 14554.708905821877, 14588.489402870902, 14728.85271147291, 14732.0424172429, 694.0318256032843, 725.960072196029, 732.3408931961225, 871.298604623278, 907.0430695616122, 908.3641450984304, 917.4907926101012, 920.1212841736126, 920.1580553793272, 926.735880274122, 981.9591233292697, 985.6599473181741, 987.6641941739807, 988.5106872098321, 989.5596459575492, 736.6243215959845, 765.043695380774, 921.7649499377616, 981.6024510073431, 983.3509883476906, 719.4316489972915, 725.408791834653, 726.0815068937798, 727.8107692000065, 732.4506620034974, 15157.317920693595, 15528.456504022304, 15543.300551475211, 15549.378024878992, 15590.908636395458, 13824.516141935084, 13963.0, 13967.79048314276, 14015.719685606333, 14236.928815355857, 12321.568745093879, 12423.689407764788, 12674.248618647656, 12853.22880627156, 13036.543720969721, 4213.51544572375, 4329.805158767863, 4549.977250113729, 4664.320844495979, 4703.459102203256, 99958.0, 103248.93502129916, 105321.94678053199, 110637.89362106356, 110724.0, 11061.281016733887, 11066.833997490045, 11097.38964356952, 11154.832677509843, 11214.0, 21841.453963650718, 22296.777032229795, 22365.329040128498, 22382.55234895293, 22410.99150538197, 7650.655720492591, 7878.409119316074, 7949.475114795162, 7955.562444065632, 8054.073781515083, 4268.957310426918, 4277.91444171121, 4320.071184695243, 4526.95473045272, 4532.0482698632995, 3468.497067925137, 3545.0782796472586, 3551.8046507441823, 3551.8756843510355, 3559.4482855157494, 629.4806784402795, 631.0534198701947, 652.7813182583888, 662.29134825736, 663.3300366629642, 629.380060640269, 650.6421468192569, 660.577230572434, 662.8972509261072, 681.4412182010703, 7624.5425274482595, 9087.0, 9415.340926135104, 9545.856812147824, 9607.663731769355, 588.6526949100052, 593.6942474625571, 614.846288427893, 621.6549814852709, 645.4255712415953, 4408.713433626804, 4774.021325628238, 4779.139754844162, 4823.614110871122, 4843.491433399442, 85086.0, 98324.50837745909, 101377.42424166594, 102180.97819021864, 104132.47933760282, 70151.64924175346, 86802.69795953066, 88665.0, 89671.55164224267, 90441.0, 63420.68289658614, 76847.0, 79253.60373198097, 79293.0, 80760.0, 8847.867281990904, 9229.446233226028, 9328.300377471582, 9462.195713364441, 9549.47477888695, 41928.58071419247, 50167.74916125396, 50502.747486262335, 51021.744891276794, 51736.965260693825, 64903.0, 64921.0, 66005.0, 66795.33204668085, 68090.0, 10677.893221068001, 10819.621313253998, 10912.781744365227, 10930.508127134139, 11425.257358271685, 11816.527338906348, 11872.826626924987, 11964.58123965657, 12064.095192860746, 12114.939425302817, 11161.944190278997, 11455.656330310105, 11538.88461115378, 11762.0, 11828.23116497425, 11110.44447777624, 11187.888121118685, 11635.767284654428, 11636.952674259357, 11769.764604707687, 11200.21598488098, 11535.307881527135, 11544.88455115438, 11603.245789023686, 11622.128340374336, 9481.336306458481, 9635.662751803653, 9649.372790768577, 9702.75743106428, 10281.12610428122, 9302.395344302598, 9426.340156189275, 9437.150656440954, 9461.33770636049, 9537.14165725088], "value": 7314.097726238318, "unit": "score"}], "name": "dromaeo_css", "value": 7260.274938312488, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 113.0, 114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 108.0, 105.0, 136.0, 122.0, 110.0, 122.0, 108.0, 127.0, 108.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 185.0, 185.0, 185.0, 184.0, 182.0, 183.0, 183.0, 181.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 56.0, 88.0, 63.0, 59.0, 56.0, 58.0, 70.0, 58.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 108.0, 101.0, 97.0, 74.0, 82.0, 67.0, 69.0, 83.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 83.0, 86.0, 83.0, 87.0, 84.0, 86.0, 87.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 92.0, 92.0, 92.0, 92.0, 91.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 131.0, 91.0, 87.0, 89.0, 85.0, 85.0, 86.0, 86.0, 86.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 55.0, 55.0, 55.0, 56.0, 56.0, 58.0, 57.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 81.0, 73.0, 72.0, 68.0, 70.0, 69.0, 70.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 79.0, 81.0, 84.0, 81.0, 82.0, 84.0, 84.0, 83.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 142.0, 135.0, 152.0, 139.0, 143.0, 140.0, 183.0, 152.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 165.0, 166.0, 166.0, 165.0, 164.0, 164.0, 163.0, 165.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 64.0, 65.0, 67.0, 65.0, 67.0, 65.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1423.1999999999998, "alertThreshold": 2.0}]} 16:19:17 INFO - Return code: 0 16:19:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:19:17 ERROR - # TBPL SUCCESS # 16:19:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:19:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:19:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:19:17 INFO - 2017-02-07 16:19:17.826 screenresolution[1654:9968] starting screenresolution argv=screenresolution get 16:19:17 INFO - 2017-02-07 16:19:17.829 screenresolution[1654:9968] Display 0: 1600x1200x32@60 16:19:17 INFO - 2017-02-07 16:19:17.835 screenresolution[1655:9972] starting screenresolution argv=screenresolution list 16:19:17 INFO - Available Modes on Display 0 16:19:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:19:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:19:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:19:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:19:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:19:18 INFO - Intel Iris: 16:19:18 INFO - Chipset Model: Intel Iris 16:19:18 INFO - Type: GPU 16:19:18 INFO - Bus: Built-In 16:19:18 INFO - VRAM (Dynamic, Max): 1536 MB 16:19:18 INFO - Vendor: Intel (0x8086) 16:19:18 INFO - Device ID: 0x0a2e 16:19:18 INFO - Revision ID: 0x0009 16:19:18 INFO - Displays: 16:19:18 INFO - Display: 16:19:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:19:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:19:18 INFO - Display Serial Number: 1600x1200 60 16:19:18 INFO - Main Display: Yes 16:19:18 INFO - Mirror: Off 16:19:18 INFO - Online: Yes 16:19:18 INFO - Rotation: Supported 16:19:18 INFO - Return code: 0 16:19:18 INFO - Running post-action listener: _resource_record_post_action 16:19:18 INFO - [mozharness: 2017-02-08 00:19:18.070745Z] Finished run-tests step (success) 16:19:18 INFO - Running post-run listener: _resource_record_post_run 16:19:18 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 37815296; Write bytes: 507420160; Read time: 242; Write time: 1552 16:19:18 INFO - TinderboxPrint: CPU usage
53.8% 16:19:18 INFO - TinderboxPrint: I/O read bytes / time
37,815,296 / 242 16:19:18 INFO - TinderboxPrint: I/O write bytes / time
507,420,160 / 1,552 16:19:18 INFO - TinderboxPrint: CPU idle
2,211.0 (46.2%) 16:19:18 INFO - TinderboxPrint: CPU system
964.9 (20.1%) 16:19:18 INFO - TinderboxPrint: CPU user
1,613.9 (33.7%) 16:19:18 INFO - TinderboxPrint: Swap in / out
245,338,112 / 0 16:19:18 INFO - install - Wall time: 20s; CPU: 55.0%; Read bytes: 210692608; Write bytes: 198828032; Read time: 15941; Write time: 424 16:19:18 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30350336; Write bytes: 297508352; Read time: 179; Write time: 1091 16:19:18 INFO - Running post-run listener: _upload_blobber_files 16:19:18 INFO - Blob upload gear active. 16:19:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:19:18 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 16:19:18 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:19:18 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:19:18 INFO - (blobuploader) - INFO - Open directory for files ... 16:19:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:19:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:19:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:19:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:19:20 INFO - (blobuploader) - INFO - Done attempting. 16:19:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:19:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:19:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:19:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:19:21 INFO - (blobuploader) - INFO - Done attempting. 16:19:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:19:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:19:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:19:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:19:22 INFO - (blobuploader) - INFO - Done attempting. 16:19:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:19:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:19:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:19:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:19:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:19:24 INFO - (blobuploader) - INFO - Done attempting. 16:19:24 INFO - (blobuploader) - INFO - Iteration through files over. 16:19:24 INFO - Return code: 0 16:19:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:19:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:19:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c5117c7b4bcf533703c7e576d648dcffa120bb03527d35cae6ada712f6c137ca80aab4009aa1d70a906d207ab7739422f8f10b4d2ce0b9bf9d9dad1a4e8284e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a61d785bcc92e447e2e6df060c1a960eac36347235b3f504b03171b6bb6eedc5705fbba573f2cb28a751ae3d0721ad3f5068d794440326b15ce347551915e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b290f581018281d0d9b3bd281cb6a80e62191487d2d635422842c30d0d3511e4784c288a89d4857ecd49f6b50cee9637014ada835d3ec11627574680454b4096", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fd7b24369970c9094f5975d6098ae389245cdb02a13440a1fe1dd48ad0af3b45264811582e4cb9bb6b05178b8c99c369380670ff150089d87d8be60e15c91923"} 16:19:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:19:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:19:24 INFO - Contents: 16:19:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c5117c7b4bcf533703c7e576d648dcffa120bb03527d35cae6ada712f6c137ca80aab4009aa1d70a906d207ab7739422f8f10b4d2ce0b9bf9d9dad1a4e8284e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a61d785bcc92e447e2e6df060c1a960eac36347235b3f504b03171b6bb6eedc5705fbba573f2cb28a751ae3d0721ad3f5068d794440326b15ce347551915e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b290f581018281d0d9b3bd281cb6a80e62191487d2d635422842c30d0d3511e4784c288a89d4857ecd49f6b50cee9637014ada835d3ec11627574680454b4096", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fd7b24369970c9094f5975d6098ae389245cdb02a13440a1fe1dd48ad0af3b45264811582e4cb9bb6b05178b8c99c369380670ff150089d87d8be60e15c91923"} 16:19:24 INFO - Running post-run listener: copy_logs_to_upload_dir 16:19:24 INFO - Copying logs to upload dir... 16:19:24 INFO - mkdir: /builds/slave/test/build/upload/logs 16:19:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1326.194278 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2017-02-07 16:19:24.501408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 16:19:24.509276) ========= 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.pcIRgpifQf/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.inJBSO77uG/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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/Mozilla-Aurora/sha512/8c5117c7b4bcf533703c7e576d648dcffa120bb03527d35cae6ada712f6c137ca80aab4009aa1d70a906d207ab7739422f8f10b4d2ce0b9bf9d9dad1a4e8284e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a61d785bcc92e447e2e6df060c1a960eac36347235b3f504b03171b6bb6eedc5705fbba573f2cb28a751ae3d0721ad3f5068d794440326b15ce347551915e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b290f581018281d0d9b3bd281cb6a80e62191487d2d635422842c30d0d3511e4784c288a89d4857ecd49f6b50cee9637014ada835d3ec11627574680454b4096", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fd7b24369970c9094f5975d6098ae389245cdb02a13440a1fe1dd48ad0af3b45264811582e4cb9bb6b05178b8c99c369380670ff150089d87d8be60e15c91923"} build_url:https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010513 build_url: 'https://queue.taskcluster.net/v1/task/PbMbn5UjRburaePICgNXqw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c5117c7b4bcf533703c7e576d648dcffa120bb03527d35cae6ada712f6c137ca80aab4009aa1d70a906d207ab7739422f8f10b4d2ce0b9bf9d9dad1a4e8284e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a61d785bcc92e447e2e6df060c1a960eac36347235b3f504b03171b6bb6eedc5705fbba573f2cb28a751ae3d0721ad3f5068d794440326b15ce347551915e18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b290f581018281d0d9b3bd281cb6a80e62191487d2d635422842c30d0d3511e4784c288a89d4857ecd49f6b50cee9637014ada835d3ec11627574680454b4096", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fd7b24369970c9094f5975d6098ae389245cdb02a13440a1fe1dd48ad0af3b45264811582e4cb9bb6b05178b8c99c369380670ff150089d87d8be60e15c91923"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 16:19:24.544210) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 16:19:24.544539) ========= 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.pcIRgpifQf/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.inJBSO77uG/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.005102 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 16:19:24.592592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 16:19:24.595930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 16:19:24.596380) ========= ========= Total master_lag: 0.19 =========