builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0101 starttime: 1481470510.03 results: success (0) buildid: 20161211063750 builduid: de268f3660034ccb88708df13b52ee48 revision: 327307b7933666ef32b964865f2d853ece5744e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.029096) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.029584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.029899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.056641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.056976) ========= 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.WtGi3S6YqO/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.xtyrC6sovM/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-11 07:35:10-- 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% 67.5M=0s 2016-12-11 07:35:10 (67.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.615358 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.697681) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.698107) ========= 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.WtGi3S6YqO/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.xtyrC6sovM/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.019470 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:10.778594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-11 07:35:10.778936) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 327307b7933666ef32b964865f2d853ece5744e1 --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 327307b7933666ef32b964865f2d853ece5744e1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WtGi3S6YqO/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.xtyrC6sovM/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-11 07:35:10,863 truncating revision to first 12 chars 2016-12-11 07:35:10,863 Setting DEBUG logging. 2016-12-11 07:35:10,863 attempt 1/10 2016-12-11 07:35:10,863 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/327307b79336?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-11 07:35:11,941 unpacking tar archive at: mozilla-aurora-327307b79336/testing/mozharness/ program finished with exit code 0 elapsedTime=1.903314 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-11 07:35:12.711764) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:12.712119) ========= script_repo_revision: 327307b7933666ef32b964865f2d853ece5744e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:12.712545) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:12.712845) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 07:35:12.733706) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-11 07:35:12.734019) ========= /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.WtGi3S6YqO/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.xtyrC6sovM/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:12 INFO - MultiFileLogger online at 20161211 07:35:12 in /builds/slave/test 07:35:12 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 07:35:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:35:12 INFO - {'append_to_log': False, 07:35:12 INFO - 'base_work_dir': '/builds/slave/test', 07:35:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:35:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:35:12 INFO - 'branch': 'Mozilla-Aurora', 07:35:12 INFO - 'buildbot_json_path': 'buildprops.json', 07:35:12 INFO - 'config_files': ('talos/mac_config.py',), 07:35:12 INFO - 'default_actions': ('clobber', 07:35:12 INFO - 'read-buildbot-config', 07:35:12 INFO - 'download-and-extract', 07:35:12 INFO - 'populate-webroot', 07:35:12 INFO - 'create-virtualenv', 07:35:12 INFO - 'install', 07:35:12 INFO - 'run-tests'), 07:35:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:35:12 INFO - 'download_minidump_stackwalk': True, 07:35:12 INFO - 'download_symbols': 'ondemand', 07:35:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:35:12 INFO - 'tooltool.py': '/tools/tooltool.py', 07:35:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:35:12 INFO - '/tools/misc-python/virtualenv.py')}, 07:35:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:35:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:35:12 INFO - 'installer_path': 'installer.exe', 07:35:12 INFO - 'log_level': 'info', 07:35:12 INFO - 'log_name': 'talos', 07:35:12 INFO - 'log_to_console': True, 07:35:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:35:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:35:12 INFO - 'opt_config_files': (), 07:35:12 INFO - 'pip_index': False, 07:35:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:35:12 INFO - 'cmd': ('bash', 07:35:12 INFO - '-c', 07:35:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:35:12 INFO - 'enabled': True, 07:35:12 INFO - 'halt_on_failure': False, 07:35:12 INFO - 'name': 'check_screen_resolution'},), 07:35:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:35:12 INFO - 'cmd': ('bash', 07:35:12 INFO - '-c', 07:35:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:35:12 INFO - 'enabled': True, 07:35:12 INFO - 'halt_on_failure': False, 07:35:12 INFO - 'name': 'check_screen_resolution'},), 07:35:12 INFO - 'run_cmd_checks_enabled': True, 07:35:12 INFO - 'sps_profile': False, 07:35:12 INFO - 'sps_profile_interval': 0, 07:35:12 INFO - 'suite': 'dromaeojs', 07:35:12 INFO - 'system_bits': '32', 07:35:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:35:12 INFO - 'title': 't-yosemite-r7-0101', 07:35:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:35:12 INFO - 'use_talos_json': True, 07:35:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:35:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:35:12 INFO - 'work_dir': 'build'} 07:35:12 INFO - [mozharness: 2016-12-11 15:35:12.937142Z] Running clobber step. 07:35:12 INFO - Running pre-action listener: _resource_record_pre_action 07:35:12 INFO - Running main action method: clobber 07:35:12 INFO - rmtree: /builds/slave/test/build 07:35:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:35:13 INFO - Running post-action listener: _resource_record_post_action 07:35:13 INFO - [mozharness: 2016-12-11 15:35:13.099065Z] Finished clobber step (success) 07:35:13 INFO - [mozharness: 2016-12-11 15:35:13.099200Z] Running read-buildbot-config step. 07:35:13 INFO - Running pre-action listener: _resource_record_pre_action 07:35:13 INFO - Running main action method: read_buildbot_config 07:35:13 INFO - Using buildbot properties: 07:35:13 INFO - { 07:35:13 INFO - "project": "", 07:35:13 INFO - "product": "firefox", 07:35:13 INFO - "script_repo_revision": "production", 07:35:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:35:13 INFO - "repository": "", 07:35:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:35:13 INFO - "buildid": "20161211063750", 07:35:13 INFO - "pgo_build": "False", 07:35:13 INFO - "basedir": "/builds/slave/test", 07:35:13 INFO - "buildnumber": 46, 07:35:13 INFO - "slavename": "t-yosemite-r7-0101", 07:35:13 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:35:13 INFO - "platform": "yosemite_r7", 07:35:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:35:13 INFO - "branch": "mozilla-aurora", 07:35:13 INFO - "repo_path": "releases/mozilla-aurora", 07:35:13 INFO - "revision": "327307b7933666ef32b964865f2d853ece5744e1", 07:35:13 INFO - "stage_platform": "macosx64", 07:35:13 INFO - "builduid": "de268f3660034ccb88708df13b52ee48", 07:35:13 INFO - "slavebuilddir": "test" 07:35:13 INFO - } 07:35:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:35:13 INFO - Running post-action listener: _resource_record_post_action 07:35:13 INFO - [mozharness: 2016-12-11 15:35:13.102423Z] Finished read-buildbot-config step (success) 07:35:13 INFO - [mozharness: 2016-12-11 15:35:13.102553Z] Running download-and-extract step. 07:35:13 INFO - Running pre-action listener: _resource_record_pre_action 07:35:13 INFO - Running main action method: download_and_extract 07:35:13 INFO - mkdir: /builds/slave/test/build/tests 07:35:13 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')]} 07:35:13 INFO - trying https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:35:13 INFO - Downloading https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:35:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 07:35:14 INFO - Downloaded 1199 bytes. 07:35:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:35:14 INFO - Using the following test package requirements: 07:35:14 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:35:14 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:35:14 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:35:14 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'jsshell-mac.zip'], 07:35:14 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:35:14 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:35:14 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:35:14 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:35:14 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:35:14 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:35:14 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:35:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:35:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:35:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:35:14 INFO - Fetch https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:35:15 INFO - Http code: 200 07:35:15 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 07:35:15 INFO - x-amz-cf-id: O4mVs45vBUbo5EWSj8eC4H5aOuvaSPFvCm7vRPIXkqDuvf1BWY6_FA== 07:35:15 INFO - x-amz-version-id: _CEcU6m2aTZG_7VriUCkOmrUwOzNst_f 07:35:15 INFO - Expected file size: 16280906 07:35:15 INFO - Obtained file size: 16280906 07:35:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:35:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:35:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:35:22 INFO - Fetch https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:35:22 INFO - Http code: 200 07:35:22 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 07:35:22 INFO - x-amz-cf-id: os-a9NbOAmCUs7TeG1bTdEsdHSbyH15u6V4aU6h8eJN_DaICTGBWtg== 07:35:22 INFO - x-amz-version-id: _CEcU6m2aTZG_7VriUCkOmrUwOzNst_f 07:35:23 INFO - Expected file size: 16280906 07:35:23 INFO - Obtained file size: 16280906 07:35:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:35:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:35:29 INFO - Fetch https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:35:30 INFO - Http code: 200 07:35:30 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 07:35:30 INFO - x-amz-cf-id: q39YncfB50mbLFWniACj0YMZ6zISGLJLs1JzyqHuWMHfEGL_FnTUAA== 07:35:30 INFO - x-amz-version-id: ONhGC4xDSKOpOIH_SOJtKw4pZ9CRy1aa 07:35:30 INFO - Expected file size: 14409038 07:35:30 INFO - Obtained file size: 14409038 07:35:30 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')]} 07:35:30 INFO - trying https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:35:30 INFO - Downloading https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:35:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 07:35:33 INFO - Downloaded 109780096 bytes. 07:35:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:35:33 INFO - mkdir: /builds/slave/test/properties 07:35:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:35:33 INFO - Writing to file /builds/slave/test/properties/build_url 07:35:33 INFO - Contents: 07:35:33 INFO - build_url:https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:35:34 INFO - Running post-action listener: _resource_record_post_action 07:35:34 INFO - Running post-action listener: set_extra_try_arguments 07:35:34 INFO - [mozharness: 2016-12-11 15:35:34.634901Z] Finished download-and-extract step (success) 07:35:34 INFO - [mozharness: 2016-12-11 15:35:34.635027Z] Running populate-webroot step. 07:35:34 INFO - Running pre-action listener: _resource_record_pre_action 07:35:34 INFO - Running main action method: populate_webroot 07:35:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:35:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:35:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:35:34 INFO - '--tppagecycles', 07:35:34 INFO - '7'], 07:35:34 INFO - 'tests': ['tsvgm']}}, 07:35:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:35:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:35:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:35:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:35:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:35:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:35:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['damp', 'tps']}, 07:35:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'tests': ['damp', 'tps']}, 07:35:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['dromaeo_dom']}, 07:35:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:35:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['basic_compositor_video']}, 07:35:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:35:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['a11yr', 07:35:34 INFO - 'ts_paint', 07:35:34 INFO - 'tpaint', 07:35:34 INFO - 'sessionrestore', 07:35:34 INFO - 'sessionrestore_no_auto_restore', 07:35:34 INFO - 'tabpaint']}, 07:35:34 INFO - 'other-e10s': {'tests': ['a11yr', 07:35:34 INFO - 'ts_paint', 07:35:34 INFO - 'tpaint', 07:35:34 INFO - 'sessionrestore', 07:35:34 INFO - 'sessionrestore_no_auto_restore', 07:35:34 INFO - 'tabpaint']}, 07:35:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:35:34 INFO - 'ts_paint', 07:35:34 INFO - 'tpaint', 07:35:34 INFO - 'sessionrestore', 07:35:34 INFO - 'sessionrestore_no_auto_restore', 07:35:34 INFO - 'tabpaint']}, 07:35:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:35:34 INFO - 'ts_paint', 07:35:34 INFO - 'tpaint', 07:35:34 INFO - 'sessionrestore', 07:35:34 INFO - 'sessionrestore_no_auto_restore', 07:35:34 INFO - 'tabpaint']}, 07:35:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['a11yr', 07:35:34 INFO - 'ts_paint', 07:35:34 INFO - 'tpaint', 07:35:34 INFO - 'sessionrestore', 07:35:34 INFO - 'sessionrestore_no_auto_restore', 07:35:34 INFO - 'tabpaint']}, 07:35:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['a11yr', 07:35:34 INFO - 'ts_paint', 07:35:34 INFO - 'tpaint', 07:35:34 INFO - 'sessionrestore', 07:35:34 INFO - 'sessionrestore_no_auto_restore', 07:35:34 INFO - 'tabpaint']}, 07:35:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['tsvgx', 07:35:34 INFO - 'tsvgr_opacity', 07:35:34 INFO - 'tart', 07:35:34 INFO - 'tscrollx', 07:35:34 INFO - 'cart']}, 07:35:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:35:34 INFO - 'tsvgr_opacity', 07:35:34 INFO - 'tart', 07:35:34 INFO - 'tscrollx', 07:35:34 INFO - 'cart']}, 07:35:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'talos_options': ['--disable-e10s'], 07:35:34 INFO - 'tests': ['tp5o']}, 07:35:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'tests': ['tp5o']}, 07:35:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'talos_options': ['--disable-e10s', 07:35:34 INFO - '--xperf_path', 07:35:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:35:34 INFO - 'tests': ['tp5n']}, 07:35:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:35:34 INFO - 'talos_options': ['--xperf_path', 07:35:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:35:34 INFO - 'tests': ['tp5n']}}, 07:35:34 INFO - 'talos.zip': {'path': '', 07:35:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:35:34 INFO - Running post-action listener: _resource_record_post_action 07:35:34 INFO - [mozharness: 2016-12-11 15:35:34.653828Z] Finished populate-webroot step (success) 07:35:34 INFO - [mozharness: 2016-12-11 15:35:34.653961Z] Running create-virtualenv step. 07:35:34 INFO - Running pre-action listener: _resource_record_pre_action 07:35:34 INFO - Running main action method: create_virtualenv 07:35:34 INFO - Creating virtualenv /builds/slave/test/build/venv 07:35:34 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 07:35:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:35:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:35:34 INFO - Using real prefix '/tools/python27' 07:35:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:35:35 INFO - Installing distribute.............................................................................................................................................................................................done. 07:35:38 INFO - Installing pip.................done. 07:35:38 INFO - Return code: 0 07:35:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:35:38 INFO - Reading from file tmpfile_stdout 07:35:38 INFO - Output received: 07:35:38 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) 07:35:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:35:38 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')]} 07:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:38 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')]} 07:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:38 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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:38 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 07:35:38 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 07:35:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:35:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:38 INFO - 'HOME': '/Users/cltbld', 07:35:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:38 INFO - 'LOGNAME': 'cltbld', 07:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:38 INFO - 'MOZ_NO_REMOTE': '1', 07:35:38 INFO - 'NO_EM_RESTART': '1', 07:35:38 INFO - 'PAGER': '/bin/cat', 07:35:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:38 INFO - 'PWD': '/builds/slave/test', 07:35:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:38 INFO - 'SHELL': '/bin/bash', 07:35:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:35:38 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:35:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:38 INFO - 'USER': 'cltbld', 07:35:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:38 INFO - 'XPC_FLAGS': '0x0', 07:35:38 INFO - 'XPC_SERVICE_NAME': '0', 07:35:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:38 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 07:35:38 INFO - Cleaning up... 07:35:38 INFO - Return code: 0 07:35:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:35:38 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')]} 07:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:38 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')]} 07:35:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:38 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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:38 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 07:35:38 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 07:35:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:35:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:38 INFO - 'HOME': '/Users/cltbld', 07:35:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:38 INFO - 'LOGNAME': 'cltbld', 07:35:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:38 INFO - 'MOZ_NO_REMOTE': '1', 07:35:38 INFO - 'NO_EM_RESTART': '1', 07:35:38 INFO - 'PAGER': '/bin/cat', 07:35:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:38 INFO - 'PWD': '/builds/slave/test', 07:35:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:38 INFO - 'SHELL': '/bin/bash', 07:35:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:35:38 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:35:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:38 INFO - 'USER': 'cltbld', 07:35:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:38 INFO - 'XPC_FLAGS': '0x0', 07:35:38 INFO - 'XPC_SERVICE_NAME': '0', 07:35:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:39 INFO - Downloading/unpacking psutil>=3.1.1 07:35:39 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 07:35:39 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 07:35:39 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 07:35:39 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 07:35:39 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 07:35:39 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 07:35:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:35:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:35:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:35:42 INFO - Installing collected packages: psutil 07:35:42 INFO - Running setup.py install for psutil 07:35:43 INFO - building 'psutil._psutil_osx' extension 07:35:43 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 07:35:43 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 07:35:43 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 07:35:43 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 07:35:43 INFO - building 'psutil._psutil_posix' extension 07:35:43 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 07:35:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:35:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:35:43 INFO - ^ 07:35:43 INFO - 1 warning generated. 07:35:43 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 07:35:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:35:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:35:43 INFO - Successfully installed psutil 07:35:43 INFO - Cleaning up... 07:35:43 INFO - Return code: 0 07:35:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:35:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:35:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:35:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:43 INFO - 'HOME': '/Users/cltbld', 07:35:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:43 INFO - 'LOGNAME': 'cltbld', 07:35:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:43 INFO - 'MOZ_NO_REMOTE': '1', 07:35:43 INFO - 'NO_EM_RESTART': '1', 07:35:43 INFO - 'PAGER': '/bin/cat', 07:35:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:43 INFO - 'PWD': '/builds/slave/test', 07:35:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:43 INFO - 'SHELL': '/bin/bash', 07:35:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:35:43 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:35:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:43 INFO - 'USER': 'cltbld', 07:35:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:43 INFO - 'XPC_FLAGS': '0x0', 07:35:43 INFO - 'XPC_SERVICE_NAME': '0', 07:35:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:35:44 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 07:35:44 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 07:35:44 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 07:35:44 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 07:35:44 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 07:35:44 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 07:35:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:35:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:35:46 INFO - Installing collected packages: mozsystemmonitor 07:35:46 INFO - Running setup.py install for mozsystemmonitor 07:35:46 INFO - Successfully installed mozsystemmonitor 07:35:46 INFO - Cleaning up... 07:35:47 INFO - Return code: 0 07:35:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:35:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:35:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:35:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:35:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:47 INFO - 'HOME': '/Users/cltbld', 07:35:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:47 INFO - 'LOGNAME': 'cltbld', 07:35:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:47 INFO - 'MOZ_NO_REMOTE': '1', 07:35:47 INFO - 'NO_EM_RESTART': '1', 07:35:47 INFO - 'PAGER': '/bin/cat', 07:35:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:47 INFO - 'PWD': '/builds/slave/test', 07:35:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:47 INFO - 'SHELL': '/bin/bash', 07:35:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:35:47 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:35:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:47 INFO - 'USER': 'cltbld', 07:35:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:47 INFO - 'XPC_FLAGS': '0x0', 07:35:47 INFO - 'XPC_SERVICE_NAME': '0', 07:35:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:47 INFO - Downloading/unpacking jsonschema==2.5.1 07:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:35:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:35:49 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 07:35:49 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 07:35:49 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 07:35:49 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 07:35:49 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 07:35:49 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 07:35:50 INFO - Downloading functools32-3.2.3-2.tar.gz 07:35:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:35:50 INFO - warning: no files found matching '*.txt' 07:35:50 INFO - no previously-included directories found matching 'build' 07:35:50 INFO - no previously-included directories found matching 'dist' 07:35:50 INFO - no previously-included directories found matching '.git*' 07:35:50 INFO - Installing collected packages: jsonschema, functools32 07:35:50 INFO - Running setup.py install for functools32 07:35:50 INFO - warning: no files found matching '*.txt' 07:35:50 INFO - no previously-included directories found matching 'build' 07:35:50 INFO - no previously-included directories found matching 'dist' 07:35:50 INFO - no previously-included directories found matching '.git*' 07:35:50 INFO - Successfully installed jsonschema functools32 07:35:50 INFO - Cleaning up... 07:35:50 INFO - Return code: 0 07:35:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:35:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:35:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:50 INFO - 'HOME': '/Users/cltbld', 07:35:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:50 INFO - 'LOGNAME': 'cltbld', 07:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:50 INFO - 'MOZ_NO_REMOTE': '1', 07:35:50 INFO - 'NO_EM_RESTART': '1', 07:35:50 INFO - 'PAGER': '/bin/cat', 07:35:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:50 INFO - 'PWD': '/builds/slave/test', 07:35:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:50 INFO - 'SHELL': '/bin/bash', 07:35:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:35:50 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:35:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:50 INFO - 'USER': 'cltbld', 07:35:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:50 INFO - 'XPC_FLAGS': '0x0', 07:35:50 INFO - 'XPC_SERVICE_NAME': '0', 07:35:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:35:50 INFO - Cleaning up... 07:35:50 INFO - Return code: 0 07:35:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:35:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:35:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:50 INFO - 'HOME': '/Users/cltbld', 07:35:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:50 INFO - 'LOGNAME': 'cltbld', 07:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:50 INFO - 'MOZ_NO_REMOTE': '1', 07:35:50 INFO - 'NO_EM_RESTART': '1', 07:35:50 INFO - 'PAGER': '/bin/cat', 07:35:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:50 INFO - 'PWD': '/builds/slave/test', 07:35:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:50 INFO - 'SHELL': '/bin/bash', 07:35:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:35:50 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:35:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:50 INFO - 'USER': 'cltbld', 07:35:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:50 INFO - 'XPC_FLAGS': '0x0', 07:35:50 INFO - 'XPC_SERVICE_NAME': '0', 07:35:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:51 INFO - Downloading/unpacking blobuploader==1.2.4 07:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:53 INFO - Downloading blobuploader-1.2.4.tar.gz 07:35:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:35:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:35:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:35:54 INFO - Downloading docopt-0.6.1.tar.gz 07:35:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:35:54 INFO - Installing collected packages: blobuploader, requests, docopt 07:35:54 INFO - Running setup.py install for blobuploader 07:35:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:35:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:35:55 INFO - Running setup.py install for requests 07:35:55 INFO - Running setup.py install for docopt 07:35:55 INFO - Successfully installed blobuploader requests docopt 07:35:55 INFO - Cleaning up... 07:35:55 INFO - Return code: 0 07:35:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:35:55 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')]} 07:35:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:35:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:55 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')]} 07:35:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:35:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:35:55 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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:35:55 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 07:35:55 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 07:35:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:35:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:35:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:35:55 INFO - 'HOME': '/Users/cltbld', 07:35:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:35:55 INFO - 'LOGNAME': 'cltbld', 07:35:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:35:55 INFO - 'MOZ_NO_REMOTE': '1', 07:35:55 INFO - 'NO_EM_RESTART': '1', 07:35:55 INFO - 'PAGER': '/bin/cat', 07:35:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:35:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:35:55 INFO - 'PWD': '/builds/slave/test', 07:35:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:35:55 INFO - 'SHELL': '/bin/bash', 07:35:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:35:55 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:35:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:35:55 INFO - 'USER': 'cltbld', 07:35:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:35:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:35:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:35:55 INFO - 'XPC_FLAGS': '0x0', 07:35:55 INFO - 'XPC_SERVICE_NAME': '0', 07:35:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Mca4KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Qz9n08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-CmXDcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-y1hSdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-mLV61R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-WfYRD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-WEMGu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-SBEPfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:35:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:35:56 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-hmzVY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-a42iMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-AIUBk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-mRQ4yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-8SP5h3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-ypkiF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-rtLYnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-cQdPCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:35:57 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-j6D5kC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:35:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:35:57 INFO - Running setup.py install for manifestparser 07:35:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:35:58 INFO - Running setup.py install for mozcrash 07:35:58 INFO - Running setup.py install for mozdebug 07:35:58 INFO - Running setup.py install for mozdevice 07:35:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:35:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:35:58 INFO - Running setup.py install for mozfile 07:35:58 INFO - Running setup.py install for mozhttpd 07:35:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:35:58 INFO - Running setup.py install for mozinfo 07:35:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:35:58 INFO - Running setup.py install for mozInstall 07:35:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Running setup.py install for mozleak 07:35:59 INFO - Running setup.py install for mozlog 07:35:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Running setup.py install for moznetwork 07:35:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Running setup.py install for mozprocess 07:35:59 INFO - Running setup.py install for mozprofile 07:35:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:35:59 INFO - Running setup.py install for mozrunner 07:36:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:36:00 INFO - Running setup.py install for mozscreenshot 07:36:00 INFO - Running setup.py install for moztest 07:36:00 INFO - Running setup.py install for mozversion 07:36:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:36:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:36:00 INFO - Cleaning up... 07:36:00 INFO - Return code: 0 07:36:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36: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')]} 07:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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')]} 07:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:00 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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:36:00 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 07:36:00 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 07:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:00 INFO - 'HOME': '/Users/cltbld', 07:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:00 INFO - 'LOGNAME': 'cltbld', 07:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:00 INFO - 'MOZ_NO_REMOTE': '1', 07:36:00 INFO - 'NO_EM_RESTART': '1', 07:36:00 INFO - 'PAGER': '/bin/cat', 07:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:00 INFO - 'PWD': '/builds/slave/test', 07:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:00 INFO - 'SHELL': '/bin/bash', 07:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:36:00 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:00 INFO - 'USER': 'cltbld', 07:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:00 INFO - 'XPC_FLAGS': '0x0', 07:36:00 INFO - 'XPC_SERVICE_NAME': '0', 07:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:36:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-c3skmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:36:00 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)) 07:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:36:00 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-0Szonx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-xs4kj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-v8ez9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:36:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-O6npn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-LE7Lmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-_4xICF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Vf8tXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-QuBciH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-nKoWQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-KJZqgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:36:01 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)) 07:36:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:36:01 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-7lZYO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:02 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-uJ7BlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:02 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)) 07:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:02 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-G11Sy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:36:02 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)) 07:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:02 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-SrWv6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:02 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)) 07:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:36:02 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-4MuDVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:36:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:36:02 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-tJn73S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:36:02 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)) 07:36:02 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)) 07:36:02 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)) 07:36:02 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)) 07:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:36:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:36:02 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 07:36:02 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 07:36:02 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 07:36:02 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 07:36:02 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 07:36:02 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 07:36:05 INFO - Downloading blessings-1.6.tar.gz 07:36:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:36:05 INFO - Installing collected packages: blessings 07:36:05 INFO - Running setup.py install for blessings 07:36:05 INFO - Successfully installed blessings 07:36:05 INFO - Cleaning up... 07:36:05 INFO - Return code: 0 07:36:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:36:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:05 INFO - Reading from file tmpfile_stdout 07:36:05 INFO - Current package versions: 07:36:05 INFO - blessings == 1.6 07:36:05 INFO - blobuploader == 1.2.4 07:36:05 INFO - docopt == 0.6.1 07:36:05 INFO - functools32 == 3.2.3-2 07:36:05 INFO - jsonschema == 2.5.1 07:36:05 INFO - manifestparser == 1.1 07:36:05 INFO - mozInstall == 1.12 07:36:05 INFO - mozcrash == 1.0 07:36:05 INFO - mozdebug == 0.1 07:36:05 INFO - mozdevice == 0.48 07:36:05 INFO - mozfile == 1.2 07:36:05 INFO - mozhttpd == 0.7 07:36:05 INFO - mozinfo == 0.9 07:36:05 INFO - mozleak == 0.1 07:36:05 INFO - mozlog == 3.4 07:36:05 INFO - moznetwork == 0.27 07:36:05 INFO - mozprocess == 0.23 07:36:05 INFO - mozprofile == 0.28 07:36:05 INFO - mozrunner == 6.13 07:36:05 INFO - mozscreenshot == 0.1 07:36:05 INFO - mozsystemmonitor == 0.3 07:36:05 INFO - moztest == 0.7 07:36:05 INFO - mozversion == 1.4 07:36:05 INFO - psutil == 3.1.1 07:36:05 INFO - requests == 1.2.3 07:36:05 INFO - wsgiref == 0.1.2 07:36:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36:05 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')]} 07:36:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:05 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')]} 07:36:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:05 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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:36:05 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 07:36:05 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 07:36:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:36:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:05 INFO - 'HOME': '/Users/cltbld', 07:36:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:05 INFO - 'LOGNAME': 'cltbld', 07:36:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:05 INFO - 'MOZ_NO_REMOTE': '1', 07:36:05 INFO - 'NO_EM_RESTART': '1', 07:36:05 INFO - 'PAGER': '/bin/cat', 07:36:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:05 INFO - 'PWD': '/builds/slave/test', 07:36:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:05 INFO - 'SHELL': '/bin/bash', 07:36:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:36:05 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:36:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:05 INFO - 'USER': 'cltbld', 07:36:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:05 INFO - 'XPC_FLAGS': '0x0', 07:36:05 INFO - 'XPC_SERVICE_NAME': '0', 07:36:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 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)) 07:36:06 INFO - Cleaning up... 07:36:06 INFO - Return code: 0 07:36:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:06 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 0x100e60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dd6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f2bcb2df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, '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.WtGi3S6YqO/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.xtyrC6sovM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:36:06 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 07:36:06 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 07:36:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:36:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:06 INFO - 'HOME': '/Users/cltbld', 07:36:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:06 INFO - 'LOGNAME': 'cltbld', 07:36:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:06 INFO - 'MOZ_NO_REMOTE': '1', 07:36:06 INFO - 'NO_EM_RESTART': '1', 07:36:06 INFO - 'PAGER': '/bin/cat', 07:36:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:06 INFO - 'PWD': '/builds/slave/test', 07:36:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:06 INFO - 'SHELL': '/bin/bash', 07:36:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:36:06 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:36:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:06 INFO - 'USER': 'cltbld', 07:36:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:06 INFO - 'XPC_FLAGS': '0x0', 07:36:06 INFO - 'XPC_SERVICE_NAME': '0', 07:36:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:36:06 INFO - Cleaning up... 07:36:06 INFO - Return code: 0 07:36:06 INFO - Running post-action listener: _resource_record_post_action 07:36:06 INFO - Running post-action listener: _start_resource_monitoring 07:36:06 INFO - Starting resource monitoring. 07:36:06 INFO - [mozharness: 2016-12-11 15:36:06.417172Z] Finished create-virtualenv step (success) 07:36:06 INFO - [mozharness: 2016-12-11 15:36:06.418221Z] Running install step. 07:36:06 INFO - Running pre-action listener: _resource_record_pre_action 07:36:06 INFO - Running main action method: install 07:36:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:06 INFO - Reading from file tmpfile_stdout 07:36:06 INFO - Detecting whether we're running mozinstall >=1.0... 07:36:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:36:06 INFO - Reading from file tmpfile_stdout 07:36:06 INFO - Output received: 07:36:06 INFO - Usage: mozinstall [options] installer 07:36:06 INFO - Options: 07:36:06 INFO - -h, --help show this help message and exit 07:36:06 INFO - -d DEST, --destination=DEST 07:36:06 INFO - Directory to install application into. [default: 07:36:06 INFO - "/builds/slave/test"] 07:36:06 INFO - --app=APP Application being installed. [default: firefox] 07:36:06 INFO - mkdir: /builds/slave/test/build/application 07:36:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:36:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:36:37 INFO - Reading from file tmpfile_stdout 07:36:37 INFO - Output received: 07:36:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:36:37 INFO - Running post-action listener: _resource_record_post_action 07:36:37 INFO - [mozharness: 2016-12-11 15:36:37.576995Z] Finished install step (success) 07:36:37 INFO - [mozharness: 2016-12-11 15:36:37.577133Z] Running run-tests step. 07:36:37 INFO - Running pre-action listener: _resource_record_pre_action 07:36:37 INFO - Running main action method: run_tests 07:36:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:36:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:36:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:36:37 INFO - 2016-12-11 07:36:37.591 screenresolution[2264:26731] starting screenresolution argv=screenresolution get 07:36:37 INFO - 2016-12-11 07:36:37.594 screenresolution[2264:26731] Display 0: 1600x1200x32@60 07:36:37 INFO - 2016-12-11 07:36:37.600 screenresolution[2265:26733] starting screenresolution argv=screenresolution list 07:36:37 INFO - Available Modes on Display 0 07:36:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:36:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:36:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:36:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:36:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:36:37 INFO - Intel Iris: 07:36:37 INFO - Chipset Model: Intel Iris 07:36:37 INFO - Type: GPU 07:36:37 INFO - Bus: Built-In 07:36:37 INFO - VRAM (Dynamic, Max): 1536 MB 07:36:37 INFO - Vendor: Intel (0x8086) 07:36:37 INFO - Device ID: 0x0a2e 07:36:37 INFO - Revision ID: 0x0009 07:36:37 INFO - Displays: 07:36:37 INFO - Display: 07:36:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:36:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:36:37 INFO - Display Serial Number: 1600x1200 60 07:36:37 INFO - Main Display: Yes 07:36:37 INFO - Mirror: Off 07:36:37 INFO - Online: Yes 07:36:37 INFO - Rotation: Supported 07:36:37 INFO - Return code: 0 07:36:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:36:37 INFO - Python 2.7.3 07:36:37 INFO - Return code: 0 07:36:37 INFO - grabbing minidump binary from tooltool 07:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:37 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c74d30>, '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 0x100adadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c6cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:36:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:36:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:36:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:36:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:36:37 INFO - Return code: 0 07:36:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:36:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:36:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:36:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:36:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:36:37 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/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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 07:36:37 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/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0101 --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 07:36:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WtGi3S6YqO/Render', 07:36:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:36:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:36:37 INFO - 'HOME': '/Users/cltbld', 07:36:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:36:37 INFO - 'LOGNAME': 'cltbld', 07:36:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:36:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:37 INFO - 'MOZ_NO_REMOTE': '1', 07:36:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:36:37 INFO - 'NO_EM_RESTART': '1', 07:36:37 INFO - 'PAGER': '/bin/cat', 07:36:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:37 INFO - 'PWD': '/builds/slave/test', 07:36:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:36:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:36:37 INFO - 'SHELL': '/bin/bash', 07:36:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xtyrC6sovM/Listeners', 07:36:37 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:36:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:36:37 INFO - 'USER': 'cltbld', 07:36:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:36:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:37 INFO - 'XPC_FLAGS': '0x0', 07:36:37 INFO - 'XPC_SERVICE_NAME': '0', 07:36:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:36:37 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/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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 07:36:38 INFO - mozversion application_buildid: 20161211063750 07:36:38 INFO - mozversion application_changeset: 327307b7933666ef32b964865f2d853ece5744e1 07:36:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:36:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:36:38 INFO - mozversion application_name: Firefox 07:36:38 INFO - mozversion application_remotingname: firefox-dev 07:36:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:36:38 INFO - mozversion application_vendor: Mozilla 07:36:38 INFO - mozversion application_version: 52.0a2 07:36:38 INFO - mozversion platform_buildid: 20161211063750 07:36:38 INFO - mozversion platform_changeset: 327307b7933666ef32b964865f2d853ece5744e1 07:36:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:36:38 INFO - mozversion platform_version: 52.0a2 07:36:38 INFO - using testdate: 1481470598 07:36:38 INFO - actual date: 1481470598 07:36:38 INFO - starting webserver on 'localhost:51843' 07:36:38 INFO - SUITE-START | Running 2 tests 07:36:38 INFO - TEST-START | dromaeo_css 07:36:38 INFO - operating with platform_type : mac_ 07:36:38 INFO - Initialising browser for dromaeo_css test... 07:36:38 INFO - TEST-INFO | started process 2275 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmp08_CRd/profile http://localhost:51843/getInfo.html) 07:36:40 INFO - PROCESS | 2275 | __metrics Screen width/height:1600/1200 07:36:40 INFO - PROCESS | 2275 | colorDepth:24 07:36:40 INFO - PROCESS | 2275 | Browser inner width/height: 1024/689 07:36:40 INFO - PROCESS | 2275 | __metrics 07:36:41 INFO - PROCESS | 2275 | 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" 07:36:41 INFO - PROCESS | 2275 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:36:41 INFO - TEST-INFO | 2275: exit 0 07:36:41 INFO - Browser initialized. 07:36:41 INFO - Running cycle 1/1 for dromaeo_css test... 07:36:41 INFO - TEST-INFO | started process 2277 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmp08_CRd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:36:54 INFO - PROCESS | 2277 | RSS: Main: 222486528 07:36:54 INFO - PROCESS | 2277 | 07:37:47 INFO - PROCESS | 2277 | 1481470667966 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:39:58 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51843/tests/dromaeo/cssquery-ext.html) 07:40:00 INFO - PROCESS | 2277 | RSS: Main: 433475584 07:40:00 INFO - PROCESS | 2277 | 07:43:03 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-ext.html (next: http://localhost:51843/tests/dromaeo/cssquery-jquery.html) 07:43:04 INFO - PROCESS | 2277 | RSS: Main: 349274112 07:43:04 INFO - PROCESS | 2277 | 07:46:06 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51843/tests/dromaeo/cssquery-mootools.html) 07:46:07 INFO - PROCESS | 2277 | RSS: Main: 324259840 07:46:07 INFO - PROCESS | 2277 | 07:49:09 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51843/tests/dromaeo/cssquery-prototype.html) 07:49:10 INFO - PROCESS | 2277 | RSS: Main: 320143360 07:49:10 INFO - PROCESS | 2277 | 07:52:13 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51843/tests/dromaeo/cssquery-yui.html) 07:52:14 INFO - PROCESS | 2277 | RSS: Main: 313753600 07:52:14 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51843/tests/dromaeo/cssquery-yui.html (next: http://localhost:51843/tests/dromaeo/cssquery-dojo.html) 07:55:17 INFO - PROCESS | 2277 | RSS: Main: 414916608 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | __start_tp_report 07:55:17 INFO - PROCESS | 2277 | _x_x_mozilla_page_load 07:55:17 INFO - PROCESS | 2277 | _x_x_mozilla_page_load_details 07:55:17 INFO - PROCESS | 2277 | |i|pagename|runs| 07:55:17 INFO - PROCESS | 2277 | |0;dojo.html;2941.91174264772;3279.1474216703664;3284.4252255855217;3294.3246634439943;3364.024432914455;5547.500724934755;5594.832155035345;5618.297712785902;5650.830475085749;5664.830055098343;5382.3541175059045;5410;5413.810516631918;5422.186671999202;5464.398916119224;4941.67879087859;4959.032988567231;4968.3292755477905;5049.84850454487;5053.292539044537;4974.278729584208;5072.137736584789;5083.618728595352;5084.974575127119;5085.110105731482;5128.743562821863;5190.92213616796;5198.662086964335;5199.558037566792;5208.348956380452;5088.898222035538;5143.948560514385;5175;5198.584113270929;5259.526642602165;5069.138246498104;5121.641485096007;5130.563902068328;5133.691978481265;5145.536901678867;5555.583331250132;5585.273914391144;5629.774809007676;5636;5666.235058267122;5943.286805583314;5989.431004054652;5994;6036.6076205046975;6090.33006369299;6094.085887116924;6109.755609775603;6113.44978951892;6133.509319254449;6204.038374052029;5706.343770466408;5720.227769251181;5720.685362305113;5735.799247026417;5740.311162660424;6115.51416190291;6130.012120365664;6236.656983865931;6245.375462453719;6272.466840318624;6020.4280593343565;6046.3651316612;6046.395360463918;6049.213602231682;6056.545759068087;6148.78479253224;6152.8769424611255;6159.907601385983;6168.25980882297;6171.59884607499;5892.705364731746;5911.527077833763;5952.285725712942;6038.788642397495;6063.848403789941;6131.223750760645;6168.35232300602;6181.81454556364;6182.165407669997;6193.969030154821;4806.519348065165;4869.902601947942;4872.001239745844;4885.340479035356;4898.510148985073;4322.762248076323;4374.168907907551;4394.099209662011;4401.845935392246;4436.889077773081;5534.806281780118;5609.15862620602;5662.348829884616;5662.405447428043;5668.744906479218;6188.566800324024;6196.535259855529;6203.906941395882;6215.409536094064;6223.844403889849;5688.800891968761;5711.057675483582;5711.343195532484;5718.857028574235;5729.169270455761;3981.482407287034;5151.304573882553;5196.960607878439;5269.473052694777;5321.813736519203;6192;6231.158793562811;6241.157443745036;6272;6282.3089460158435;5148.6910785353;5979.940200597938;6073.696315184135;6088.817335479943;6109.938900610937;4853.320535125156;6254.155688982111;6272.372762723874;6272.843178920563;6275.435210831047;5975.581709280483;5998.970005149947;6067.362926892789;6075.787347442818;6115.663638499926;6037.366076562073;6110.930587147142;6139.4781443577795;6152.661603611844;6155.35368786271;5746.770129194785;5940.435658612425;5964.403559644001;5971.044632858721;5971.343152253162;5845.883082338414;5878.882422351615;5926.081457374114;5935.495482884004;5936.7922122725495;5795.652260864362;5799.5070419014855;5803.245578074823;5808.796692115756;5819.5635327351465;5899.351071382231;5913.704314784158;5918.526517878646;5920.319163296103;5939.851503712442;5715;5734.856628584319;5758.021136406905;5773.393793651774;5789.507891829242 07:55:17 INFO - PROCESS | 2277 | |1;ext.html;475.96477661087124;492.34026404617805;493.8765308672832;507.8476457062881;568.9487946084852;29455.85272073639;30433.847830760817;31131.066068017968;31173.220669483275;37776;32205.5169172462;40712;40756.796216018876;40780;40883.99999999993;7863.72476963305;9451.149396554309;9461.101195386427;9462.384944978588;9474;8448.408611397204;8670.52312122833;9422.52887355633;11081.113510919107;11299.49152288145;11273.549058037668;11318.490667919963;11335.093192544577;11375.886241137565;11380.601678941197;11251.60619378322;11339.603113891057;11345.886541134565;11351;11353.205275630751;8355.623996920092;8608.52653104079;10905.67282981504;11226.214165008452;11253.606123785628;32044;38491.807540962116;38935.22129557393;39009.609903900884;39335.40996885049;3275.2630658101975;3371.662833716633;3377.290768938526;3559.768615040014;3834.213986132836;27958;28650.856745716348;29679.25801854971;29784.851075744482;30087.849560752275;3016.351484430858;3206.9839650801596;3217.549543063973;3248.220427097503;3249.1714612773826;324190;387860;390571;390719;393356;59504.70247648734;59960;60051.798964020476;60063.399366006655;60106;33687.83156084204;34087.82956085253;34405.655943440746;35480.64519354825;61889;15742.291596878167;15882;15898.602534936488;15915.442959496362;15915.920420397824;10740.140788736882;12197.45111469986;12202.511899524097;12211.023118150704;12218.877811221952;15056.698866022618;15181.316840742193;16796.58008549796;17161.056141912064;17161.31354745796;7409.147713071732;7675.731349402744;7730.961345193238;7808.804779880548;7891.881621775678;845.8223773007737;877.0965905117739;967.9322447428754;982.7985263021063;1037.9273450858368;22931;28810.27974300659;28815.855920720263;28872.42255154885;29586.556201656993;1045.2474218562622;1089.6676513663251;1109.7392112853472;1112.093643680398;1182.8639706433698;14693;15211.771823422658;15232.162231077176;15558.299876505584;15590.298436570381;544447;556448;557487;559958.8008239813;559974.6003809946;55195.448045519835;55751;55770.44229557733;55804.720976394856;56110.43889561134;1341.208686874729;1354.6951935814363;1366.9521566745116;1371.6845125620964;1388.6597783542895;27918.86040569784;28269.434611307246;28555.857220713762;29209.415811684074;29216.7078329214;41071.38392924049;49059.264111039076;49434;49663.25505117354;49712.005759885324;477759;479475;480726;481517;482466.5876670735;25363;26409.471810563296;26409.867950660122;26477.33806654849;26481.735182647928;32831.179220519676;34628.48245458983;39002;44638.660840174845;46540;36018.63981360153;36827;36952.81523592365;36979;37786;36940.22948278836;37495.7038040631;37943.86168414952;38036.42945355766;38299.80850095732;36068.819655901556;36293.81853090718;36445;37064.44403333898;37142;31753.8412307937;32868.01395958125;33657.15857103592;33689.32621347608;33834.98495045152;24250.666213358436;24921.75078249267;25152.24543263705;25458.618120727835;25531.489370212865 07:55:17 INFO - PROCESS | 2277 | |2;jquery.html;288.070971118144;370.89058727675314;374.15814417560495;394.34538665814756;405.5295856806105;18341.7248741269;19337.226510939563;19983.900080499578;20166.49583760405;20385.388438346836;19457.92981386023;19547.654281486208;19549.315773947914;19673.31143409981;20097.397078087644;4751.216049351853;5628.26832511774;5700.600957932947;5905.970470147643;5907.113932910055;5737.110747834093;5741.397153298906;5794.188813566104;5794.536437085023;5812.854678633025;5657.462541058593;5749.310082790071;5755.568332375088;5762.625429347079;5938.228030356069;5994.610350327237;6040.879182416371;6043.576949613529;6046.213992181032;6046.367480780186;4908.852734417973;5169.224616307584;5226.608004399685;5269.235960785703;5815.302163740336;12670;12844.422001009976;12905.67735806603;21868.562628747495;23353;2100.8214301784365;2154.7306586676664;2270.773782157633;2464.445499762557;2595.831270967381;9946.35348702332;9987.400755954593;10206.285560010801;13102.86897131026;15310.540683779505;1719.1662043908693;1749.0883946293613;1768.3015208992376;1857.907104644776;1866.3840932492244;101831.98168018373;106392;108232;112073;116729;10894.509747061402;11323.603673871376;11403.087752979742;11515.424228788526;11678.124140689482;14341.56975290743;14344.713105737826;14348.426062957573;14353.712925741427;14356;6708.865822683519;8169.142240064826;8314.293285070717;8395.748127556182;8399.580020999048;6378.330275321025;8111.229433203955;8194.303484203909;8218.424710270223;8370.916290837136;4331.285337919208;4416.4258646375765;4418.2106246970025;4491.88770280739;4517.2094883395275;1851.1022154255174;1900.1544312780957;1905.4188472516016;1919.8176173263519;1938.806119388078;630.8328293002359;689.4074687471956;712.9572225666477;818.3774605619097;996.1482932092956;18505.259789608535;19528.414147575597;19769.703454447907;20134;20257;880.564120760215;905.0723008915783;924.2190349154953;930.2929773372285;954.3224310739314;9272.70182174496;9725.416475011521;10419.947900260602;10714.785704286027;10814.972577605115;458456;473915;475130;476570;513974;111722;116172.41913790545;123194.38402807928;123347;125155;874.510274246424;882.2368651116738;900.4372267332917;925.3984909808678;959.6833045095102;14287.856971442052;14507.564773056825;17785.73321400206;18083;18089.276428942696;50345.496545035545;51577;52565.474345257586;52647.21029184642;54340.641483961634;58998;73874.63062684653;74855;77433.22566774486;77585;7683.884741728767;7702.345300649284;8133.105358410452;10962.123030157727;11533.480993355151;12486.625401237978;12833.614991550268;13289.269090200132;13538.390772415069;13581.185128892299;11184.72038199019;11482.94258528702;11549.48027338755;11731.00286475625;11794.115441342105;10956.835647465135;11105.05607023412;11151.275167114436;11167.329960202414;11311.434428278388;11498.597549085742;11734.237274577124;11747.118966077407;11852.585159519376;11902.940485297519;12002.339871307162;12020.355930009007;12037.638870833538;12311.076669249664;12357.505699771908;10489.108425783588;11379.146564007899;11485.02377297939;11735.530578776754;11740.823887641522 07:55:17 INFO - PROCESS | 2277 | |3;mootools.html;1451.1583281696614;1479.2529772464904;1616.4342480131954;1785.196951079411;1808.6834803909312;8064.112947575762;8496.49021058736;8497.065322814493;8500.957495212515;8511.191436813508;7477;7686.26980436859;7717.884231736529;7967.76096717098;8301.045379781326;2299.7010388649496;2348.4598542335275;2351.9647205291935;2354.6114891042957;2355.1168311883043;2346.507233480971;2378.6075297575967;2384.7257565380028;2385.85684858909;2396.2332053742807;2316.9073237070497;2323.2797832671927;2343.988280058606;2345.8944347504316;2349.776771206741;2312.3756585721844;2338.3920180752957;2339.520398318358;2340.754220806808;2341.3678306857055;2325.360525855386;2360.5160942006846;2361.7874391304863;2362.4566349739484;2371.0752806405517;8230.176982301722;8297.294729947962;8308.210719981593;8336.333093352518;8357.540335281617;4992.051510213048;5134.229865520165;5143.228515722634;5152.536271735525;5155.072087024334;3274.590676165479;3407.335569563936;3482.5298584690995;3552.2185119273718;3554.147004718849;898.5641963647626;1073.2755390111722;1090.5910283643634;1096.5997410945026;1098.6209757633603;10470.528826202839;10714.98207670278;10843.620473283476;10924.180686448468;10947.56209751609;4270.530241673413;4377.299632058855;4406.4051353066925;4500.189965806187;4521.796519156645;8683.001454832774;8687.174718401742;8710.25962793157;8715.215630593277;8719.302455803521;5110.3356563646485;5115;5115.87210319745;5119.948800511947;5121.743912804345;4692.061587682409;4695.014047050089;4706.435227772621;4796.448408433005;4798.712077275374;3516.4725291206014;3530.5763308402643;3554.8222588870453;3591.371509985794;3630.310241054244;955.9808803823885;1119.5018216893568;1125.5610311978335;1126.5437497813398;1140.401289323112;856.4946681457969;873.4104479476329;891.777055736066;899.9950221514288;926.4348747264163;5468.425815289417;5586;5740.483356497935;5750.856228594235;5825.359210486843;845.750503601439;857.2798848966773;917.8439665256923;920.9907900920905;970.0687340153531;3759.041444431707;3765.679917207013;3793.2223894102194;3824.7896365700153;3857.1321452672923;9853.605855765689;10153.187744980383;10281.920398358065;10289.176865850714;10306;8896.466212027299;9908.603655853685;10239.692809215736;10251.128654064489;10258.794824103476;7709.961450192825;7740.690372385154;7864.882026769488;7899.289063984269;7902.288794008567;5388.407275199804;5448.427915068861;5564.527015203753;5751.827445176651;5830.883382332415;9288.907110928803;9432.95283523578;9484.336096473457;9489.762755931157;9525.61897524119;9057.275417966415;9362.438253704799;9430.811383772423;9455.574499147693;9560.760980975254;6519.282878883224;6750.493712971449;6754.155730533684;6816.079829222991;6816.965915170393;7462.253774622494;7637.121731001006;7786.649600768093;7875.291223789657;7946.682132714626;7050.682719277543;7143.749968751069;7178.928210718035;7207.495475316678;7247.855042899218;6967.65161741921;7072.045273887959;7074.68163932614;7089.751858684921;7108.07595012645;6507.186601674791;6662.367075127855;6665;6666.400023997863;6669.899951500634;4767;4858.5384388483035;4865.440474345495;4883.877903052452;4938.8765280868265;4842.491538388418;4913.238448040559;4942.456329803791;5042.4705405931845;5069.833938194162 07:55:17 INFO - PROCESS | 2277 | |4;prototype.html;297.9061595597387;304.3502122967465;308.3109250824407;314.5077953003549;316.9033444799338;3977.6078372569586;4037.0436971767685;5345.49217824307;5396.64921780084;5410.513053825155;4869.610431165507;5002.349694539714;5016.372953380827;5150.557843803741;5278.577713782898;3385.356782211384;4032.7378720383226;4060.4112403701592;4075.1034772350185;4077.3272410052305;3754.854769295364;4047.9677682191;4054.898627534306;4056.5132184137947;4074.490688663917;3708.592054873962;3953.42675312081;3990.221906728189;4006.17718937884;4035.8609936733433;3283.868645254187;3301.18434860828;3706.4625629283605;3945.842166313344;3988.623924745958;3050.4442734205713;3265.053134590966;3444.621091679913;3447.637998010198;3466.9133271668156;4231.322988321854;4778.569928706398;5127.795048129303;5135.204043373283;5140.794368225266;4138.255114079465;4448.599626033641;4502.977485112553;4568.337591049312;4588.357629931812;5031.572316353115;5293.126634105368;5608.355039170466;5612.943870561324;5645.8588535286535;3803.6386543278345;3809.676177524914;3811.1615444602144;3815.4849095372324;3823.9808800955816;4819.566239038469;5388.248819133758;5811.186433899257;5852.4440178183;5877.471027607536;3396.8301584920655;3639;3828.8659896903473;3831.1954489556947;3833.849845046475;3433.4163192257374;3452.188735647121;3557.5553055868018;3821.9044523887123;3879.2047630236298;4266.210751011073;4505.594496495331;4605.6776025678555;4611.302388102998;4616.884577885513;4623;5253.789848406021;5563.916541251884;5595.565928820976;5611.523020543218;1016.8983101689772;1074.8499105956707;1110.4614262082882;1212.7089498520468;1278.5780692371495;809.429706369641;845.1801752300258;855.5551113420986;863.3869952333788;868.2706526517755;5029.673071250357;5034.496550345009;5056.974715126475;5092.490750924878;5097.439281679012;4477.433018321294;4708.646851486153;5023;5449.5640348772;5513.448655134454;4118.4440100586635;4973.378327709036;4980.526849949249;4992.975035124874;5017.247412888023;2801.103646832994;2846.7864910131825;2850.529662605647;2866.5556838690036;2870.7846911481724;4887;5449.918251226235;5528.590209496633;5558.916616250759;5563.36021357541;4629.67592268538;5039.77321020555;5195.376554813447;5338.199270109437;5495.422980587061;4612.700174488715;5657.482420322916;5659.773609055674;5695.8860822784145;5697.888911662186;5029.974850125726;5043.949560504495;5379.838604841861;5387.407385187548;5419.292922729328;4892.877678058077;5018;5348.358197016228;5387.784488620411;5388.515033646991;4752.453467851103;4826.187312023462;5002.724305302125;5472.425395333433;5655.462731040545;3086.9780293907525;3483.780676763214;3589.653879795077;3615.9332996765493;3643.9635603644683;4824.710517368969;5326.707031113326;5375.554122874005;5429.30807051209;5456.727163641882;5239.842804715865;5241.50205730455;5255.868603284948;5264.499872512105;5270.446603106772;4515.75163366018;4662.813487460464;4753.310769938409;5180;5260.684358938476;5117.692938423706;5125.231215317583;5143.382794064736;5222.321098257202;5410.48600382963;4880.975595122144;5268.4994925482015;5491.945080549143;5595.99272131004;5631.915521267102;4859.1010663027455;5482;5495.890082198413;5514.393416724238;5519.254900588369 07:55:17 INFO - PROCESS | 2277 | |5;yui.html;695.5965539986809;709.2907092907096;732.4689600039972;777.7899967008906;779.5205948341771;13568.592942211724;14053.016288859784;14090.29548522575;14116.858831411682;14118.152910825345;12842.422091005903;12867.227966322027;12873.485060597588;12874.485020599164;12959;640.8846407646622;655.6426747422643;660.2802944790178;695.6382681005887;795.6260557537948;816.6610856494548;824.2787560884226;834.1533343656193;834.9666013359458;838.7861095420659;873.0440168016044;875.4003507597284;876.1807709791366;877.0308808766317;880.9383343165981;675.8986152077227;696.380221602774;869.447900583128;870.9346798990069;872.5462759365126;622.2315440431064;640.2956747577674;646.9708863101125;648.71132346106;649.3506493506494;13148.60554183376;13177.341132943315;13199.538016169388;13210.33948302581;13220.86779132206;12998.545050923267;13083.738325233442;13110.541131060367;13195.538156164585;13269;11754.23597466162;11773.679856952578;12007.639770806889;12022.939885300604;12041.277523348539;4245.532991370947;4271.530131685512;4308.612224899774;4347.608715215646;4464.754438505881;84011.5799421005;84714.57642711808;84764.72852907212;85008.14991850064;86577;9937.304388692723;10228.693139205836;10445.738856528496;10511.211659125596;10584.947075264574;18228.45314640563;19118;19181.520461988563;19909.203631854565;19943.60112797736;7207.019321008821;7479.626018699043;7491.812704682317;7618.390528757686;7708.2291770823595;3926.6833956537853;4143.109231515239;4144.626983571493;4146.937795933063;4236.661067114624;3327.401067807818;3445.3453843769603;3445.758796884194;3448.2930999145115;3454.1537323355437;627.8681476889813;631.0219160301514;632.6615260835431;639.5746828359089;651.840299126717;575.2722805650855;637.5250438423404;651.1957732200729;664.8670265946733;671.1476424940298;8653.764472494051;8904.985513038317;9024.548772561346;9056.954715226513;9070.591823367964;597.620510975525;628.3653509954978;647.5499527828114;649.5712829532486;654.1267408010359;3735.2716220336774;4009.0177906413114;4323;4484.372187893698;4508.549145085531;102760;105148.47425762976;108515;111217;111612.44193778979;86022.13977860266;86727.56636216778;87562;88121.11878881257;95305;49669.75165124224;66270;67944;76675;78766.66783326857;8970;9411.717648470556;9730.172935300296;9752.707418777449;9753.268504862306;40186.186966912035;40297.1940561193;40839.591604084766;41178.38232426456;49063.754681227794;56903.715481422325;72319;74045.25954740384;75111.24888751261;75204.62397687977;11071.176849903222;11614.825777613174;11767.705807354885;11793.70515737079;12169;13122.14706044104;13744.189294336045;14323.283835807959;14329.426822927384;14375.221186005308;12484.625461236177;12532.436040378387;13126.737465250451;13197.20816750998;13541.661458463617;9588.520573971413;12503.312317822607;12610.180338277982;12937.870621294043;13063.934680326538;11507.030074624241;11549;11720.648380548597;12063;12496.31270280107;9343.53282335894;9977.156510394503;10947.206902908145;11217;11302.943485282522;10080.344777589433;10863.076638485501;10918.836217456903;11172.720681983015;11183.88816111861 07:55:17 INFO - PROCESS | 2277 | __end_tp_report 07:55:17 INFO - PROCESS | 2277 | __start_cc_report 07:55:17 INFO - PROCESS | 2277 | _x_x_mozilla_cycle_collect,2856 07:55:17 INFO - PROCESS | 2277 | __end_cc_report 07:55:17 INFO - PROCESS | 2277 | __startTimestamp1481471717706__endTimestamp 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | ------- Summary: start ------- 07:55:17 INFO - PROCESS | 2277 | Number of tests: 6 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | [#0] dojo.html Cycles:165 Average:5611.70 Median:5789.51 stddev:646.32 (11.2%) stddev-sans-first:613.43 07:55:17 INFO - PROCESS | 2277 | Values: 2941.9 3279.1 3284.4 3294.3 3364.0 5547.5 5594.8 5618.3 5650.8 5664.8 5382.4 5410.0 5413.8 5422.2 5464.4 4941.7 4959.0 4968.3 5049.8 5053.3 4974.3 5072.1 5083.6 5085.0 5085.1 5128.7 5190.9 5198.7 5199.6 5208.3 5088.9 5143.9 5175.0 5198.6 5259.5 5069.1 5121.6 5130.6 5133.7 5145.5 5555.6 5585.3 5629.8 5636.0 5666.2 5943.3 5989.4 5994.0 6036.6 6090.3 6094.1 6109.8 6113.4 6133.5 6204.0 5706.3 5720.2 5720.7 5735.8 5740.3 6115.5 6130.0 6236.7 6245.4 6272.5 6020.4 6046.4 6046.4 6049.2 6056.5 6148.8 6152.9 6159.9 6168.3 6171.6 5892.7 5911.5 5952.3 6038.8 6063.8 6131.2 6168.4 6181.8 6182.2 6194.0 4806.5 4869.9 4872.0 4885.3 4898.5 4322.8 4374.2 4394.1 4401.8 4436.9 5534.8 5609.2 5662.3 5662.4 5668.7 6188.6 6196.5 6203.9 6215.4 6223.8 5688.8 5711.1 5711.3 5718.9 5729.2 3981.5 5151.3 5197.0 5269.5 5321.8 6192.0 6231.2 6241.2 6272.0 6282.3 5148.7 5979.9 6073.7 6088.8 6109.9 4853.3 6254.2 6272.4 6272.8 6275.4 5975.6 5999.0 6067.4 6075.8 6115.7 6037.4 6110.9 6139.5 6152.7 6155.4 5746.8 5940.4 5964.4 5971.0 5971.3 5845.9 5878.9 5926.1 5935.5 5936.8 5795.7 5799.5 5803.2 5808.8 5819.6 5899.4 5913.7 5918.5 5920.3 5939.9 5715.0 5734.9 5758.0 5773.4 5789.5 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | [#1] ext.html Cycles:180 Average:60432.17 Median:3374.48 stddev:127063.65 (3765.4%) stddev-sans-first:127340.36 07:55:17 INFO - PROCESS | 2277 | Values: 476.0 492.3 493.9 507.8 568.9 29455.9 30433.8 31131.1 31173.2 37776.0 32205.5 40712.0 40756.8 40780.0 40884.0 7863.7 9451.1 9461.1 9462.4 9474.0 8448.4 8670.5 9422.5 11081.1 11299.5 11273.5 11318.5 11335.1 11375.9 11380.6 11251.6 11339.6 11345.9 11351.0 11353.2 8355.6 8608.5 10905.7 11226.2 11253.6 32044.0 38491.8 38935.2 39009.6 39335.4 3275.3 3371.7 3377.3 3559.8 3834.2 27958.0 28650.9 29679.3 29784.9 30087.8 3016.4 3207.0 3217.5 3248.2 3249.2 324190.0 387860.0 390571.0 390719.0 393356.0 59504.7 59960.0 60051.8 60063.4 60106.0 33687.8 34087.8 34405.7 35480.6 61889.0 15742.3 15882.0 15898.6 15915.4 15915.9 10740.1 12197.5 12202.5 12211.0 12218.9 15056.7 15181.3 16796.6 17161.1 17161.3 7409.1 7675.7 7731.0 7808.8 7891.9 845.8 877.1 967.9 982.8 1037.9 22931.0 28810.3 28815.9 28872.4 29586.6 1045.2 1089.7 1109.7 1112.1 1182.9 14693.0 15211.8 15232.2 15558.3 15590.3 544447.0 556448.0 557487.0 559958.8 559974.6 55195.4 55751.0 55770.4 55804.7 56110.4 1341.2 1354.7 1367.0 1371.7 1388.7 27918.9 28269.4 28555.9 29209.4 29216.7 41071.4 49059.3 49434.0 49663.3 49712.0 477759.0 479475.0 480726.0 481517.0 482466.6 25363.0 26409.5 26409.9 26477.3 26481.7 32831.2 34628.5 39002.0 44638.7 46540.0 36018.6 36827.0 36952.8 36979.0 37786.0 36940.2 37495.7 37943.9 38036.4 38299.8 36068.8 36293.8 36445.0 37064.4 37142.0 31753.8 32868.0 33657.2 33689.3 33835.0 24250.7 24921.8 25152.2 25458.6 25531.5 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | [#2] jquery.html Cycles:180 Average:30868.29 Median:11243.10 stddev:80849.06 (719.1%) stddev-sans-first:81043.26 07:55:17 INFO - PROCESS | 2277 | Values: 288.1 370.9 374.2 394.3 405.5 18341.7 19337.2 19983.9 20166.5 20385.4 19457.9 19547.7 19549.3 19673.3 20097.4 4751.2 5628.3 5700.6 5906.0 5907.1 5737.1 5741.4 5794.2 5794.5 5812.9 5657.5 5749.3 5755.6 5762.6 5938.2 5994.6 6040.9 6043.6 6046.2 6046.4 4908.9 5169.2 5226.6 5269.2 5815.3 12670.0 12844.4 12905.7 21868.6 23353.0 2100.8 2154.7 2270.8 2464.4 2595.8 9946.4 9987.4 10206.3 13102.9 15310.5 1719.2 1749.1 1768.3 1857.9 1866.4 101832.0 106392.0 108232.0 112073.0 116729.0 10894.5 11323.6 11403.1 11515.4 11678.1 14341.6 14344.7 14348.4 14353.7 14356.0 6708.9 8169.1 8314.3 8395.7 8399.6 6378.3 8111.2 8194.3 8218.4 8370.9 4331.3 4416.4 4418.2 4491.9 4517.2 1851.1 1900.2 1905.4 1919.8 1938.8 630.8 689.4 713.0 818.4 996.1 18505.3 19528.4 19769.7 20134.0 20257.0 880.6 905.1 924.2 930.3 954.3 9272.7 9725.4 10419.9 10714.8 10815.0 458456.0 473915.0 475130.0 476570.0 513974.0 111722.0 116172.4 123194.4 123347.0 125155.0 874.5 882.2 900.4 925.4 959.7 14287.9 14507.6 17785.7 18083.0 18089.3 50345.5 51577.0 52565.5 52647.2 54340.6 58998.0 73874.6 74855.0 77433.2 77585.0 7683.9 7702.3 8133.1 10962.1 11533.5 12486.6 12833.6 13289.3 13538.4 13581.2 11184.7 11482.9 11549.5 11731.0 11794.1 10956.8 11105.1 11151.3 11167.3 11311.4 11498.6 11734.2 11747.1 11852.6 11902.9 12002.3 12020.4 12037.6 12311.1 12357.5 10489.1 11379.1 11485.0 11735.5 11740.8 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | [#3] mootools.html Cycles:180 Average:5408.59 Median:5127.99 stddev:2944.83 (57.4%) stddev-sans-first:2938.07 07:55:17 INFO - PROCESS | 2277 | Values: 1451.2 1479.3 1616.4 1785.2 1808.7 8064.1 8496.5 8497.1 8501.0 8511.2 7477.0 7686.3 7717.9 7967.8 8301.0 2299.7 2348.5 2352.0 2354.6 2355.1 2346.5 2378.6 2384.7 2385.9 2396.2 2316.9 2323.3 2344.0 2345.9 2349.8 2312.4 2338.4 2339.5 2340.8 2341.4 2325.4 2360.5 2361.8 2362.5 2371.1 8230.2 8297.3 8308.2 8336.3 8357.5 4992.1 5134.2 5143.2 5152.5 5155.1 3274.6 3407.3 3482.5 3552.2 3554.1 898.6 1073.3 1090.6 1096.6 1098.6 10470.5 10715.0 10843.6 10924.2 10947.6 4270.5 4377.3 4406.4 4500.2 4521.8 8683.0 8687.2 8710.3 8715.2 8719.3 5110.3 5115.0 5115.9 5119.9 5121.7 4692.1 4695.0 4706.4 4796.4 4798.7 3516.5 3530.6 3554.8 3591.4 3630.3 956.0 1119.5 1125.6 1126.5 1140.4 856.5 873.4 891.8 900.0 926.4 5468.4 5586.0 5740.5 5750.9 5825.4 845.8 857.3 917.8 921.0 970.1 3759.0 3765.7 3793.2 3824.8 3857.1 9853.6 10153.2 10281.9 10289.2 10306.0 8896.5 9908.6 10239.7 10251.1 10258.8 7710.0 7740.7 7864.9 7899.3 7902.3 5388.4 5448.4 5564.5 5751.8 5830.9 9288.9 9433.0 9484.3 9489.8 9525.6 9057.3 9362.4 9430.8 9455.6 9560.8 6519.3 6750.5 6754.2 6816.1 6817.0 7462.3 7637.1 7786.6 7875.3 7946.7 7050.7 7143.7 7178.9 7207.5 7247.9 6967.7 7072.0 7074.7 7089.8 7108.1 6507.2 6662.4 6665.0 6666.4 6669.9 4767.0 4858.5 4865.4 4883.9 4938.9 4842.5 4913.2 4942.5 5042.5 5069.8 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | [#4] prototype.html Cycles:180 Average:4349.02 Median:4889.94 stddev:1330.57 (27.2%) stddev-sans-first:1299.09 07:55:17 INFO - PROCESS | 2277 | Values: 297.9 304.4 308.3 314.5 316.9 3977.6 4037.0 5345.5 5396.6 5410.5 4869.6 5002.3 5016.4 5150.6 5278.6 3385.4 4032.7 4060.4 4075.1 4077.3 3754.9 4048.0 4054.9 4056.5 4074.5 3708.6 3953.4 3990.2 4006.2 4035.9 3283.9 3301.2 3706.5 3945.8 3988.6 3050.4 3265.1 3444.6 3447.6 3466.9 4231.3 4778.6 5127.8 5135.2 5140.8 4138.3 4448.6 4503.0 4568.3 4588.4 5031.6 5293.1 5608.4 5612.9 5645.9 3803.6 3809.7 3811.2 3815.5 3824.0 4819.6 5388.2 5811.2 5852.4 5877.5 3396.8 3639.0 3828.9 3831.2 3833.8 3433.4 3452.2 3557.6 3821.9 3879.2 4266.2 4505.6 4605.7 4611.3 4616.9 4623.0 5253.8 5563.9 5595.6 5611.5 1016.9 1074.8 1110.5 1212.7 1278.6 809.4 845.2 855.6 863.4 868.3 5029.7 5034.5 5057.0 5092.5 5097.4 4477.4 4708.6 5023.0 5449.6 5513.4 4118.4 4973.4 4980.5 4993.0 5017.2 2801.1 2846.8 2850.5 2866.6 2870.8 4887.0 5449.9 5528.6 5558.9 5563.4 4629.7 5039.8 5195.4 5338.2 5495.4 4612.7 5657.5 5659.8 5695.9 5697.9 5030.0 5043.9 5379.8 5387.4 5419.3 4892.9 5018.0 5348.4 5387.8 5388.5 4752.5 4826.2 5002.7 5472.4 5655.5 3087.0 3483.8 3589.7 3615.9 3644.0 4824.7 5326.7 5375.6 5429.3 5456.7 5239.8 5241.5 5255.9 5264.5 5270.4 4515.8 4662.8 4753.3 5180.0 5260.7 5117.7 5125.2 5143.4 5222.3 5410.5 4881.0 5268.5 5491.9 5596.0 5631.9 4859.1 5482.0 5495.9 5514.4 5519.3 07:55:17 INFO - PROCESS | 2277 | 07:55:17 INFO - PROCESS | 2277 | [#5] yui.html Cycles:180 Average:19161.81 Median:4335.30 stddev:27841.96 (642.2%) stddev-sans-first:27885.53 07:55:17 INFO - PROCESS | 2277 | Values: 695.6 709.3 732.5 777.8 779.5 13568.6 14053.0 14090.3 14116.9 14118.2 12842.4 12867.2 12873.5 12874.5 12959.0 640.9 655.6 660.3 695.6 795.6 816.7 824.3 834.2 835.0 838.8 873.0 875.4 876.2 877.0 880.9 675.9 696.4 869.4 870.9 872.5 622.2 640.3 647.0 648.7 649.4 13148.6 13177.3 13199.5 13210.3 13220.9 12998.5 13083.7 13110.5 13195.5 13269.0 11754.2 11773.7 12007.6 12022.9 12041.3 4245.5 4271.5 4308.6 4347.6 4464.8 84011.6 84714.6 84764.7 85008.1 86577.0 9937.3 10228.7 10445.7 10511.2 10584.9 18228.5 19118.0 19181.5 19909.2 19943.6 7207.0 7479.6 7491.8 7618.4 7708.2 3926.7 4143.1 4144.6 4146.9 4236.7 3327.4 3445.3 3445.8 3448.3 3454.2 627.9 631.0 632.7 639.6 651.8 575.3 637.5 651.2 664.9 671.1 8653.8 8905.0 9024.5 9057.0 9070.6 597.6 628.4 647.5 649.6 654.1 3735.3 4009.0 4323.0 4484.4 4508.5 102760.0 105148.5 108515.0 111217.0 111612.4 86022.1 86727.6 87562.0 88121.1 95305.0 49669.8 66270.0 67944.0 76675.0 78766.7 8970.0 9411.7 9730.2 9752.7 9753.3 40186.2 40297.2 40839.6 41178.4 49063.8 56903.7 72319.0 74045.3 75111.2 75204.6 11071.2 11614.8 11767.7 11793.7 12169.0 13122.1 13744.2 14323.3 14329.4 14375.2 12484.6 12532.4 13126.7 13197.2 13541.7 9588.5 12503.3 12610.2 12937.9 13063.9 11507.0 11549.0 11720.6 12063.0 12496.3 9343.5 9977.2 10947.2 11217.0 11302.9 10080.3 10863.1 10918.8 11172.7 11183.9 07:55:17 INFO - PROCESS | 2277 | -------- Summary: end -------- 07:55:17 INFO - PROCESS | 2277 | 07:55:18 INFO - TEST-INFO | 2277: exit 0 07:55:18 INFO - TEST-OK | dromaeo_css | took 1120211ms 07:55:18 INFO - TEST-START | kraken 07:55:18 INFO - operating with platform_type : mac_ 07:55:18 INFO - Initialising browser for kraken test... 07:55:18 INFO - TEST-INFO | started process 2282 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpm48iMm/profile http://localhost:51843/getInfo.html) 07:55:21 INFO - PROCESS | 2282 | __metrics Screen width/height:1600/1200 07:55:21 INFO - PROCESS | 2282 | colorDepth:24 07:55:21 INFO - PROCESS | 2282 | Browser inner width/height: 1024/689 07:55:21 INFO - PROCESS | 2282 | __metrics 07:55:21 INFO - PROCESS | 2282 | 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" 07:55:21 INFO - PROCESS | 2282 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:55:22 INFO - TEST-INFO | 2282: exit 0 07:55:22 INFO - Browser initialized. 07:55:22 INFO - Running cycle 1/1 for kraken test... 07:55:22 INFO - TEST-INFO | started process 2283 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpm48iMm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:34 INFO - PROCESS | 2283 | RSS: Main: 222588928 07:55:34 INFO - PROCESS | 2283 | 07:55:37 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-beat-detection) 07:55:38 INFO - PROCESS | 2283 | RSS: Main: 272269312 07:55:38 INFO - PROCESS | 2283 | 07:55:40 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-dft) 07:55:41 INFO - PROCESS | 2283 | RSS: Main: 282935296 07:55:41 INFO - PROCESS | 2283 | 07:55:44 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-fft) 07:55:45 INFO - PROCESS | 2283 | RSS: Main: 380809216 07:55:45 INFO - PROCESS | 2283 | 07:55:47 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51843/tests/kraken/driver.html?testName=audio-oscillator) 07:55:47 INFO - PROCESS | 2283 | RSS: Main: 282988544 07:55:47 INFO - PROCESS | 2283 | 07:55:49 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51843/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:55:50 INFO - PROCESS | 2283 | RSS: Main: 284532736 07:55:50 INFO - PROCESS | 2283 | 07:55:53 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51843/tests/kraken/driver.html?testName=imaging-darkroom) 07:55:54 INFO - PROCESS | 2283 | RSS: Main: 325832704 07:55:54 INFO - PROCESS | 2283 | 07:55:56 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51843/tests/kraken/driver.html?testName=imaging-desaturate) 07:55:57 INFO - PROCESS | 2283 | RSS: Main: 326148096 07:55:57 INFO - PROCESS | 2283 | 07:56:00 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51843/tests/kraken/driver.html?testName=json-parse-financial) 07:56:00 INFO - PROCESS | 2283 | RSS: Main: 324333568 07:56:00 INFO - PROCESS | 2283 | 07:56:02 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51843/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:56:03 INFO - PROCESS | 2283 | RSS: Main: 299790336 07:56:03 INFO - PROCESS | 2283 | 07:56:04 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:56:05 INFO - PROCESS | 2283 | RSS: Main: 298872832 07:56:05 INFO - PROCESS | 2283 | 07:56:07 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:56:08 INFO - PROCESS | 2283 | RSS: Main: 301117440 07:56:08 INFO - PROCESS | 2283 | 07:56:11 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:56:12 INFO - PROCESS | 2283 | RSS: Main: 310034432 07:56:12 INFO - PROCESS | 2283 | 07:56:15 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:56:15 INFO - PROCESS | 2283 | RSS: Main: 310153216 07:56:15 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | Cycle 1(1): loaded http://localhost:51843/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51843/tests/kraken/driver.html?testName=ai-astar) 07:56:17 INFO - PROCESS | 2283 | RSS: Main: 353808384 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | __start_tp_report 07:56:17 INFO - PROCESS | 2283 | _x_x_mozilla_page_load 07:56:17 INFO - PROCESS | 2283 | _x_x_mozilla_page_load_details 07:56:17 INFO - PROCESS | 2283 | |i|pagename|runs| 07:56:17 INFO - PROCESS | 2283 | |0;ai-astar;115;114;114;114;114;113;113;113;114;113 07:56:17 INFO - PROCESS | 2283 | |1;audio-beat-detection;152;117;112;149;117;119;112;113;150;144 07:56:17 INFO - PROCESS | 2283 | |2;audio-dft;183;182;185;184;183;185;183;185;182;183 07:56:17 INFO - PROCESS | 2283 | |3;audio-fft;81;59;59;91;60;59;60;58;84;58 07:56:17 INFO - PROCESS | 2283 | |4;audio-oscillator;92;70;96;71;90;67;89;68;69;93 07:56:17 INFO - PROCESS | 2283 | |5;imaging-gaussian-blur;125;131;115;85;96;84;87;84;88;83 07:56:17 INFO - PROCESS | 2283 | |6;imaging-darkroom;95;102;95;95;95;96;95;95;96;94 07:56:17 INFO - PROCESS | 2283 | |7;imaging-desaturate;80;84;79;79;80;81;83;86;82;80 07:56:17 INFO - PROCESS | 2283 | |8;json-parse-financial;55;56;53;53;57;56;58;57;56;56 07:56:17 INFO - PROCESS | 2283 | |9;json-stringify-tinderbox;65;71;68;75;74;71;70;71;71;73 07:56:17 INFO - PROCESS | 2283 | |10;stanford-crypto-aes;80;84;86;83;86;87;84;86;85;86 07:56:17 INFO - PROCESS | 2283 | |11;stanford-crypto-ccm;149;147;149;150;156;160;149;152;246;175 07:56:17 INFO - PROCESS | 2283 | |12;stanford-crypto-pbkdf2;168;176;172;168;170;170;226;210;168;170 07:56:17 INFO - PROCESS | 2283 | |13;stanford-crypto-sha256-iterative;69;69;69;69;70;70;68;66;67;68 07:56:17 INFO - PROCESS | 2283 | __end_tp_report 07:56:17 INFO - PROCESS | 2283 | __start_cc_report 07:56:17 INFO - PROCESS | 2283 | _x_x_mozilla_cycle_collect,2093 07:56:17 INFO - PROCESS | 2283 | __end_cc_report 07:56:17 INFO - PROCESS | 2283 | __startTimestamp1481471777840__endTimestamp 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | ------- Summary: start ------- 07:56:17 INFO - PROCESS | 2283 | Number of tests: 14 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 07:56:17 INFO - PROCESS | 2283 | Values: 115.0 114.0 114.0 114.0 114.0 113.0 113.0 113.0 114.0 113.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:131.50 stddev:17.68 (13.4%) stddev-sans-first:16.59 07:56:17 INFO - PROCESS | 2283 | Values: 152.0 117.0 112.0 149.0 117.0 119.0 112.0 113.0 150.0 144.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#2] audio-dft Cycles:10 Average:183.50 Median:183.50 stddev:1.18 (0.6%) stddev-sans-first:1.24 07:56:17 INFO - PROCESS | 2283 | Values: 183.0 182.0 185.0 184.0 183.0 185.0 183.0 185.0 182.0 183.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#3] audio-fft Cycles:10 Average:66.90 Median:60.00 stddev:12.97 (21.6%) stddev-sans-first:12.71 07:56:17 INFO - PROCESS | 2283 | Values: 81.0 59.0 59.0 91.0 60.0 59.0 60.0 58.0 84.0 58.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:89.50 stddev:12.30 (13.7%) stddev-sans-first:12.33 07:56:17 INFO - PROCESS | 2283 | Values: 92.0 70.0 96.0 71.0 90.0 67.0 89.0 68.0 69.0 93.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:84.50 stddev:18.61 (22.0%) stddev-sans-first:16.94 07:56:17 INFO - PROCESS | 2283 | Values: 125.0 131.0 115.0 85.0 96.0 84.0 87.0 84.0 88.0 83.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:95.00 stddev:2.25 (2.4%) stddev-sans-first:2.37 07:56:17 INFO - PROCESS | 2283 | Values: 95.0 102.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 94.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#7] imaging-desaturate Cycles:10 Average:81.40 Median:81.50 stddev:2.32 (2.8%) stddev-sans-first:2.40 07:56:17 INFO - PROCESS | 2283 | Values: 80.0 84.0 79.0 79.0 80.0 81.0 83.0 86.0 82.0 80.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:1.64 (2.9%) stddev-sans-first:1.72 07:56:17 INFO - PROCESS | 2283 | Values: 55.0 56.0 53.0 53.0 57.0 56.0 58.0 57.0 56.0 56.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:2.88 (4.1%) stddev-sans-first:2.13 07:56:17 INFO - PROCESS | 2283 | Values: 65.0 71.0 68.0 75.0 74.0 71.0 70.0 71.0 71.0 73.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:86.00 stddev:2.06 (2.4%) stddev-sans-first:1.30 07:56:17 INFO - PROCESS | 2283 | Values: 80.0 84.0 86.0 83.0 86.0 87.0 84.0 86.0 85.0 86.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#11] stanford-crypto-ccm Cycles:10 Average:163.30 Median:154.00 stddev:30.23 (19.6%) stddev-sans-first:31.62 07:56:17 INFO - PROCESS | 2283 | Values: 149.0 147.0 149.0 150.0 156.0 160.0 149.0 152.0 246.0 175.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:171.00 stddev:20.62 (12.1%) stddev-sans-first:21.43 07:56:17 INFO - PROCESS | 2283 | Values: 168.0 176.0 172.0 168.0 170.0 170.0 226.0 210.0 168.0 170.0 07:56:17 INFO - PROCESS | 2283 | 07:56:17 INFO - PROCESS | 2283 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.27 (1.8%) stddev-sans-first:1.33 07:56:17 INFO - PROCESS | 2283 | Values: 69.0 69.0 69.0 69.0 70.0 70.0 68.0 66.0 67.0 68.0 07:56:17 INFO - PROCESS | 2283 | -------- Summary: end -------- 07:56:17 INFO - PROCESS | 2283 | 07:56:18 INFO - TEST-INFO | 2283: exit 0 07:56:18 INFO - TEST-OK | kraken | took 60515ms 07:56:18 INFO - SUITE-END | took 1180s 07:56:19 INFO - Completed test suite (00:19:40) 07:56:19 INFO - javascript benchmark 07:56:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2941.91174264772, 3279.1474216703664, 3284.4252255855217, 3294.3246634439943, 3364.024432914455, 5547.500724934755, 5594.832155035345, 5618.297712785902, 5650.830475085749, 5664.830055098343, 5382.3541175059045, 5410.0, 5413.810516631918, 5422.186671999202, 5464.398916119224, 4941.67879087859, 4959.032988567231, 4968.3292755477905, 5049.84850454487, 5053.292539044537, 4974.278729584208, 5072.137736584789, 5083.618728595352, 5084.974575127119, 5085.110105731482, 5128.743562821863, 5190.92213616796, 5198.662086964335, 5199.558037566792, 5208.348956380452, 5088.898222035538, 5143.948560514385, 5175.0, 5198.584113270929, 5259.526642602165, 5069.138246498104, 5121.641485096007, 5130.563902068328, 5133.691978481265, 5145.536901678867, 5555.583331250132, 5585.273914391144, 5629.774809007676, 5636.0, 5666.235058267122, 5943.286805583314, 5989.431004054652, 5994.0, 6036.6076205046975, 6090.33006369299, 6094.085887116924, 6109.755609775603, 6113.44978951892, 6133.509319254449, 6204.038374052029, 5706.343770466408, 5720.227769251181, 5720.685362305113, 5735.799247026417, 5740.311162660424, 6115.51416190291, 6130.012120365664, 6236.656983865931, 6245.375462453719, 6272.466840318624, 6020.4280593343565, 6046.3651316612, 6046.395360463918, 6049.213602231682, 6056.545759068087, 6148.78479253224, 6152.8769424611255, 6159.907601385983, 6168.25980882297, 6171.59884607499, 5892.705364731746, 5911.527077833763, 5952.285725712942, 6038.788642397495, 6063.848403789941, 6131.223750760645, 6168.35232300602, 6181.81454556364, 6182.165407669997, 6193.969030154821, 4806.519348065165, 4869.902601947942, 4872.001239745844, 4885.340479035356, 4898.510148985073, 4322.762248076323, 4374.168907907551, 4394.099209662011, 4401.845935392246, 4436.889077773081, 5534.806281780118, 5609.15862620602, 5662.348829884616, 5662.405447428043, 5668.744906479218, 6188.566800324024, 6196.535259855529, 6203.906941395882, 6215.409536094064, 6223.844403889849, 5688.800891968761, 5711.057675483582, 5711.343195532484, 5718.857028574235, 5729.169270455761, 3981.482407287034, 5151.304573882553, 5196.960607878439, 5269.473052694777, 5321.813736519203, 6192.0, 6231.158793562811, 6241.157443745036, 6272.0, 6282.3089460158435, 5148.6910785353, 5979.940200597938, 6073.696315184135, 6088.817335479943, 6109.938900610937, 4853.320535125156, 6254.155688982111, 6272.372762723874, 6272.843178920563, 6275.435210831047, 5975.581709280483, 5998.970005149947, 6067.362926892789, 6075.787347442818, 6115.663638499926, 6037.366076562073, 6110.930587147142, 6139.4781443577795, 6152.661603611844, 6155.35368786271, 5746.770129194785, 5940.435658612425, 5964.403559644001, 5971.044632858721, 5971.343152253162, 5845.883082338414, 5878.882422351615, 5926.081457374114, 5935.495482884004, 5936.7922122725495, 5795.652260864362, 5799.5070419014855, 5803.245578074823, 5808.796692115756, 5819.5635327351465, 5899.351071382231, 5913.704314784158, 5918.526517878646, 5920.319163296103, 5939.851503712442, 5715.0, 5734.856628584319, 5758.021136406905, 5773.393793651774, 5789.507891829242], "value": 5569.900655032614, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.96477661087124, 492.34026404617805, 493.8765308672832, 507.8476457062881, 568.9487946084852, 29455.85272073639, 30433.847830760817, 31131.066068017968, 31173.220669483275, 37776.0, 32205.5169172462, 40712.0, 40756.796216018876, 40780.0, 40883.99999999993, 7863.72476963305, 9451.149396554309, 9461.101195386427, 9462.384944978588, 9474.0, 8448.408611397204, 8670.52312122833, 9422.52887355633, 11081.113510919107, 11299.49152288145, 11273.549058037668, 11318.490667919963, 11335.093192544577, 11375.886241137565, 11380.601678941197, 11251.60619378322, 11339.603113891057, 11345.886541134565, 11351.0, 11353.205275630751, 8355.623996920092, 8608.52653104079, 10905.67282981504, 11226.214165008452, 11253.606123785628, 32044.0, 38491.807540962116, 38935.22129557393, 39009.609903900884, 39335.40996885049, 3275.2630658101975, 3371.662833716633, 3377.290768938526, 3559.768615040014, 3834.213986132836, 27958.0, 28650.856745716348, 29679.25801854971, 29784.851075744482, 30087.849560752275, 3016.351484430858, 3206.9839650801596, 3217.549543063973, 3248.220427097503, 3249.1714612773826, 324190.0, 387860.0, 390571.0, 390719.0, 393356.0, 59504.70247648734, 59960.0, 60051.798964020476, 60063.399366006655, 60106.0, 33687.83156084204, 34087.82956085253, 34405.655943440746, 35480.64519354825, 61889.0, 15742.291596878167, 15882.0, 15898.602534936488, 15915.442959496362, 15915.920420397824, 10740.140788736882, 12197.45111469986, 12202.511899524097, 12211.023118150704, 12218.877811221952, 15056.698866022618, 15181.316840742193, 16796.58008549796, 17161.056141912064, 17161.31354745796, 7409.147713071732, 7675.731349402744, 7730.961345193238, 7808.804779880548, 7891.881621775678, 845.8223773007737, 877.0965905117739, 967.9322447428754, 982.7985263021063, 1037.9273450858368, 22931.0, 28810.27974300659, 28815.855920720263, 28872.42255154885, 29586.556201656993, 1045.2474218562622, 1089.6676513663251, 1109.7392112853472, 1112.093643680398, 1182.8639706433698, 14693.0, 15211.771823422658, 15232.162231077176, 15558.299876505584, 15590.298436570381, 544447.0, 556448.0, 557487.0, 559958.8008239813, 559974.6003809946, 55195.448045519835, 55751.0, 55770.44229557733, 55804.720976394856, 56110.43889561134, 1341.208686874729, 1354.6951935814363, 1366.9521566745116, 1371.6845125620964, 1388.6597783542895, 27918.86040569784, 28269.434611307246, 28555.857220713762, 29209.415811684074, 29216.7078329214, 41071.38392924049, 49059.264111039076, 49434.0, 49663.25505117354, 49712.005759885324, 477759.0, 479475.0, 480726.0, 481517.0, 482466.5876670735, 25363.0, 26409.471810563296, 26409.867950660122, 26477.33806654849, 26481.735182647928, 32831.179220519676, 34628.48245458983, 39002.0, 44638.660840174845, 46540.0, 36018.63981360153, 36827.0, 36952.81523592365, 36979.0, 37786.0, 36940.22948278836, 37495.7038040631, 37943.86168414952, 38036.42945355766, 38299.80850095732, 36068.819655901556, 36293.81853090718, 36445.0, 37064.44403333898, 37142.0, 31753.8412307937, 32868.01395958125, 33657.15857103592, 33689.32621347608, 33834.98495045152, 24250.666213358436, 24921.75078249267, 25152.24543263705, 25458.618120727835, 25531.489370212865], "value": 18978.138880380968, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.070971118144, 370.89058727675314, 374.15814417560495, 394.34538665814756, 405.5295856806105, 18341.7248741269, 19337.226510939563, 19983.900080499578, 20166.49583760405, 20385.388438346836, 19457.92981386023, 19547.654281486208, 19549.315773947914, 19673.31143409981, 20097.397078087644, 4751.216049351853, 5628.26832511774, 5700.600957932947, 5905.970470147643, 5907.113932910055, 5737.110747834093, 5741.397153298906, 5794.188813566104, 5794.536437085023, 5812.854678633025, 5657.462541058593, 5749.310082790071, 5755.568332375088, 5762.625429347079, 5938.228030356069, 5994.610350327237, 6040.879182416371, 6043.576949613529, 6046.213992181032, 6046.367480780186, 4908.852734417973, 5169.224616307584, 5226.608004399685, 5269.235960785703, 5815.302163740336, 12670.0, 12844.422001009976, 12905.67735806603, 21868.562628747495, 23353.0, 2100.8214301784365, 2154.7306586676664, 2270.773782157633, 2464.445499762557, 2595.831270967381, 9946.35348702332, 9987.400755954593, 10206.285560010801, 13102.86897131026, 15310.540683779505, 1719.1662043908693, 1749.0883946293613, 1768.3015208992376, 1857.907104644776, 1866.3840932492244, 101831.98168018373, 106392.0, 108232.0, 112073.0, 116729.0, 10894.509747061402, 11323.603673871376, 11403.087752979742, 11515.424228788526, 11678.124140689482, 14341.56975290743, 14344.713105737826, 14348.426062957573, 14353.712925741427, 14356.0, 6708.865822683519, 8169.142240064826, 8314.293285070717, 8395.748127556182, 8399.580020999048, 6378.330275321025, 8111.229433203955, 8194.303484203909, 8218.424710270223, 8370.916290837136, 4331.285337919208, 4416.4258646375765, 4418.2106246970025, 4491.88770280739, 4517.2094883395275, 1851.1022154255174, 1900.1544312780957, 1905.4188472516016, 1919.8176173263519, 1938.806119388078, 630.8328293002359, 689.4074687471956, 712.9572225666477, 818.3774605619097, 996.1482932092956, 18505.259789608535, 19528.414147575597, 19769.703454447907, 20134.0, 20257.0, 880.564120760215, 905.0723008915783, 924.2190349154953, 930.2929773372285, 954.3224310739314, 9272.70182174496, 9725.416475011521, 10419.947900260602, 10714.785704286027, 10814.972577605115, 458456.0, 473915.0, 475130.0, 476570.0, 513974.0, 111722.0, 116172.41913790545, 123194.38402807928, 123347.0, 125155.0, 874.510274246424, 882.2368651116738, 900.4372267332917, 925.3984909808678, 959.6833045095102, 14287.856971442052, 14507.564773056825, 17785.73321400206, 18083.0, 18089.276428942696, 50345.496545035545, 51577.0, 52565.474345257586, 52647.21029184642, 54340.641483961634, 58998.0, 73874.63062684653, 74855.0, 77433.22566774486, 77585.0, 7683.884741728767, 7702.345300649284, 8133.105358410452, 10962.123030157727, 11533.480993355151, 12486.625401237978, 12833.614991550268, 13289.269090200132, 13538.390772415069, 13581.185128892299, 11184.72038199019, 11482.94258528702, 11549.48027338755, 11731.00286475625, 11794.115441342105, 10956.835647465135, 11105.05607023412, 11151.275167114436, 11167.329960202414, 11311.434428278388, 11498.597549085742, 11734.237274577124, 11747.118966077407, 11852.585159519376, 11902.940485297519, 12002.339871307162, 12020.355930009007, 12037.638870833538, 12311.076669249664, 12357.505699771908, 10489.108425783588, 11379.146564007899, 11485.02377297939, 11735.530578776754, 11740.823887641522], "value": 9308.746572326125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.1583281696614, 1479.2529772464904, 1616.4342480131954, 1785.196951079411, 1808.6834803909312, 8064.112947575762, 8496.49021058736, 8497.065322814493, 8500.957495212515, 8511.191436813508, 7477.0, 7686.26980436859, 7717.884231736529, 7967.76096717098, 8301.045379781326, 2299.7010388649496, 2348.4598542335275, 2351.9647205291935, 2354.6114891042957, 2355.1168311883043, 2346.507233480971, 2378.6075297575967, 2384.7257565380028, 2385.85684858909, 2396.2332053742807, 2316.9073237070497, 2323.2797832671927, 2343.988280058606, 2345.8944347504316, 2349.776771206741, 2312.3756585721844, 2338.3920180752957, 2339.520398318358, 2340.754220806808, 2341.3678306857055, 2325.360525855386, 2360.5160942006846, 2361.7874391304863, 2362.4566349739484, 2371.0752806405517, 8230.176982301722, 8297.294729947962, 8308.210719981593, 8336.333093352518, 8357.540335281617, 4992.051510213048, 5134.229865520165, 5143.228515722634, 5152.536271735525, 5155.072087024334, 3274.590676165479, 3407.335569563936, 3482.5298584690995, 3552.2185119273718, 3554.147004718849, 898.5641963647626, 1073.2755390111722, 1090.5910283643634, 1096.5997410945026, 1098.6209757633603, 10470.528826202839, 10714.98207670278, 10843.620473283476, 10924.180686448468, 10947.56209751609, 4270.530241673413, 4377.299632058855, 4406.4051353066925, 4500.189965806187, 4521.796519156645, 8683.001454832774, 8687.174718401742, 8710.25962793157, 8715.215630593277, 8719.302455803521, 5110.3356563646485, 5115.0, 5115.87210319745, 5119.948800511947, 5121.743912804345, 4692.061587682409, 4695.014047050089, 4706.435227772621, 4796.448408433005, 4798.712077275374, 3516.4725291206014, 3530.5763308402643, 3554.8222588870453, 3591.371509985794, 3630.310241054244, 955.9808803823885, 1119.5018216893568, 1125.5610311978335, 1126.5437497813398, 1140.401289323112, 856.4946681457969, 873.4104479476329, 891.777055736066, 899.9950221514288, 926.4348747264163, 5468.425815289417, 5586.0, 5740.483356497935, 5750.856228594235, 5825.359210486843, 845.750503601439, 857.2798848966773, 917.8439665256923, 920.9907900920905, 970.0687340153531, 3759.041444431707, 3765.679917207013, 3793.2223894102194, 3824.7896365700153, 3857.1321452672923, 9853.605855765689, 10153.187744980383, 10281.920398358065, 10289.176865850714, 10306.0, 8896.466212027299, 9908.603655853685, 10239.692809215736, 10251.128654064489, 10258.794824103476, 7709.961450192825, 7740.690372385154, 7864.882026769488, 7899.289063984269, 7902.288794008567, 5388.407275199804, 5448.427915068861, 5564.527015203753, 5751.827445176651, 5830.883382332415, 9288.907110928803, 9432.95283523578, 9484.336096473457, 9489.762755931157, 9525.61897524119, 9057.275417966415, 9362.438253704799, 9430.811383772423, 9455.574499147693, 9560.760980975254, 6519.282878883224, 6750.493712971449, 6754.155730533684, 6816.079829222991, 6816.965915170393, 7462.253774622494, 7637.121731001006, 7786.649600768093, 7875.291223789657, 7946.682132714626, 7050.682719277543, 7143.749968751069, 7178.928210718035, 7207.495475316678, 7247.855042899218, 6967.65161741921, 7072.045273887959, 7074.68163932614, 7089.751858684921, 7108.07595012645, 6507.186601674791, 6662.367075127855, 6665.0, 6666.400023997863, 6669.899951500634, 4767.0, 4858.5384388483035, 4865.440474345495, 4883.877903052452, 4938.8765280868265, 4842.491538388418, 4913.238448040559, 4942.456329803791, 5042.4705405931845, 5069.833938194162], "value": 4388.523354082511, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.9061595597387, 304.3502122967465, 308.3109250824407, 314.5077953003549, 316.9033444799338, 3977.6078372569586, 4037.0436971767685, 5345.49217824307, 5396.64921780084, 5410.513053825155, 4869.610431165507, 5002.349694539714, 5016.372953380827, 5150.557843803741, 5278.577713782898, 3385.356782211384, 4032.7378720383226, 4060.4112403701592, 4075.1034772350185, 4077.3272410052305, 3754.854769295364, 4047.9677682191, 4054.898627534306, 4056.5132184137947, 4074.490688663917, 3708.592054873962, 3953.42675312081, 3990.221906728189, 4006.17718937884, 4035.8609936733433, 3283.868645254187, 3301.18434860828, 3706.4625629283605, 3945.842166313344, 3988.623924745958, 3050.4442734205713, 3265.053134590966, 3444.621091679913, 3447.637998010198, 3466.9133271668156, 4231.322988321854, 4778.569928706398, 5127.795048129303, 5135.204043373283, 5140.794368225266, 4138.255114079465, 4448.599626033641, 4502.977485112553, 4568.337591049312, 4588.357629931812, 5031.572316353115, 5293.126634105368, 5608.355039170466, 5612.943870561324, 5645.8588535286535, 3803.6386543278345, 3809.676177524914, 3811.1615444602144, 3815.4849095372324, 3823.9808800955816, 4819.566239038469, 5388.248819133758, 5811.186433899257, 5852.4440178183, 5877.471027607536, 3396.8301584920655, 3639.0, 3828.8659896903473, 3831.1954489556947, 3833.849845046475, 3433.4163192257374, 3452.188735647121, 3557.5553055868018, 3821.9044523887123, 3879.2047630236298, 4266.210751011073, 4505.594496495331, 4605.6776025678555, 4611.302388102998, 4616.884577885513, 4623.0, 5253.789848406021, 5563.916541251884, 5595.565928820976, 5611.523020543218, 1016.8983101689772, 1074.8499105956707, 1110.4614262082882, 1212.7089498520468, 1278.5780692371495, 809.429706369641, 845.1801752300258, 855.5551113420986, 863.3869952333788, 868.2706526517755, 5029.673071250357, 5034.496550345009, 5056.974715126475, 5092.490750924878, 5097.439281679012, 4477.433018321294, 4708.646851486153, 5023.0, 5449.5640348772, 5513.448655134454, 4118.4440100586635, 4973.378327709036, 4980.526849949249, 4992.975035124874, 5017.247412888023, 2801.103646832994, 2846.7864910131825, 2850.529662605647, 2866.5556838690036, 2870.7846911481724, 4887.0, 5449.918251226235, 5528.590209496633, 5558.916616250759, 5563.36021357541, 4629.67592268538, 5039.77321020555, 5195.376554813447, 5338.199270109437, 5495.422980587061, 4612.700174488715, 5657.482420322916, 5659.773609055674, 5695.8860822784145, 5697.888911662186, 5029.974850125726, 5043.949560504495, 5379.838604841861, 5387.407385187548, 5419.292922729328, 4892.877678058077, 5018.0, 5348.358197016228, 5387.784488620411, 5388.515033646991, 4752.453467851103, 4826.187312023462, 5002.724305302125, 5472.425395333433, 5655.462731040545, 3086.9780293907525, 3483.780676763214, 3589.653879795077, 3615.9332996765493, 3643.9635603644683, 4824.710517368969, 5326.707031113326, 5375.554122874005, 5429.30807051209, 5456.727163641882, 5239.842804715865, 5241.50205730455, 5255.868603284948, 5264.499872512105, 5270.446603106772, 4515.75163366018, 4662.813487460464, 4753.310769938409, 5180.0, 5260.684358938476, 5117.692938423706, 5125.231215317583, 5143.382794064736, 5222.321098257202, 5410.48600382963, 4880.975595122144, 5268.4994925482015, 5491.945080549143, 5595.99272131004, 5631.915521267102, 4859.1010663027455, 5482.0, 5495.890082198413, 5514.393416724238, 5519.254900588369], "value": 3923.259179876002, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.5965539986809, 709.2907092907096, 732.4689600039972, 777.7899967008906, 779.5205948341771, 13568.592942211724, 14053.016288859784, 14090.29548522575, 14116.858831411682, 14118.152910825345, 12842.422091005903, 12867.227966322027, 12873.485060597588, 12874.485020599164, 12959.0, 640.8846407646622, 655.6426747422643, 660.2802944790178, 695.6382681005887, 795.6260557537948, 816.6610856494548, 824.2787560884226, 834.1533343656193, 834.9666013359458, 838.7861095420659, 873.0440168016044, 875.4003507597284, 876.1807709791366, 877.0308808766317, 880.9383343165981, 675.8986152077227, 696.380221602774, 869.447900583128, 870.9346798990069, 872.5462759365126, 622.2315440431064, 640.2956747577674, 646.9708863101125, 648.71132346106, 649.3506493506494, 13148.60554183376, 13177.341132943315, 13199.538016169388, 13210.33948302581, 13220.86779132206, 12998.545050923267, 13083.738325233442, 13110.541131060367, 13195.538156164585, 13269.0, 11754.23597466162, 11773.679856952578, 12007.639770806889, 12022.939885300604, 12041.277523348539, 4245.532991370947, 4271.530131685512, 4308.612224899774, 4347.608715215646, 4464.754438505881, 84011.5799421005, 84714.57642711808, 84764.72852907212, 85008.14991850064, 86577.0, 9937.304388692723, 10228.693139205836, 10445.738856528496, 10511.211659125596, 10584.947075264574, 18228.45314640563, 19118.0, 19181.520461988563, 19909.203631854565, 19943.60112797736, 7207.019321008821, 7479.626018699043, 7491.812704682317, 7618.390528757686, 7708.2291770823595, 3926.6833956537853, 4143.109231515239, 4144.626983571493, 4146.937795933063, 4236.661067114624, 3327.401067807818, 3445.3453843769603, 3445.758796884194, 3448.2930999145115, 3454.1537323355437, 627.8681476889813, 631.0219160301514, 632.6615260835431, 639.5746828359089, 651.840299126717, 575.2722805650855, 637.5250438423404, 651.1957732200729, 664.8670265946733, 671.1476424940298, 8653.764472494051, 8904.985513038317, 9024.548772561346, 9056.954715226513, 9070.591823367964, 597.620510975525, 628.3653509954978, 647.5499527828114, 649.5712829532486, 654.1267408010359, 3735.2716220336774, 4009.0177906413114, 4323.0, 4484.372187893698, 4508.549145085531, 102760.0, 105148.47425762976, 108515.0, 111217.0, 111612.44193778979, 86022.13977860266, 86727.56636216778, 87562.0, 88121.11878881257, 95305.0, 49669.75165124224, 66270.0, 67944.0, 76675.0, 78766.66783326857, 8970.0, 9411.717648470556, 9730.172935300296, 9752.707418777449, 9753.268504862306, 40186.186966912035, 40297.1940561193, 40839.591604084766, 41178.38232426456, 49063.754681227794, 56903.715481422325, 72319.0, 74045.25954740384, 75111.24888751261, 75204.62397687977, 11071.176849903222, 11614.825777613174, 11767.705807354885, 11793.70515737079, 12169.0, 13122.14706044104, 13744.189294336045, 14323.283835807959, 14329.426822927384, 14375.221186005308, 12484.625461236177, 12532.436040378387, 13126.737465250451, 13197.20816750998, 13541.661458463617, 9588.520573971413, 12503.312317822607, 12610.180338277982, 12937.870621294043, 13063.934680326538, 11507.030074624241, 11549.0, 11720.648380548597, 12063.0, 12496.31270280107, 9343.53282335894, 9977.156510394503, 10947.206902908145, 11217.0, 11302.943485282522, 10080.344777589433, 10863.076638485501, 10918.836217456903, 11172.720681983015, 11183.88816111861], "value": 7053.519508442508, "unit": "score"}], "name": "dromaeo_css", "value": 7018.352357718582, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 112.0, 149.0, 117.0, 119.0, 112.0, 113.0, 150.0, 144.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 185.0, 184.0, 183.0, 185.0, 183.0, 185.0, 182.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 91.0, 60.0, 59.0, 60.0, 58.0, 84.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 96.0, 71.0, 90.0, 67.0, 89.0, 68.0, 69.0, 93.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 131.0, 115.0, 85.0, 96.0, 84.0, 87.0, 84.0, 88.0, 83.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 94.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 79.0, 79.0, 80.0, 81.0, 83.0, 86.0, 82.0, 80.0], "value": 81.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 53.0, 53.0, 57.0, 56.0, 58.0, 57.0, 56.0, 56.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 68.0, 75.0, 74.0, 71.0, 70.0, 71.0, 71.0, 73.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 86.0, 83.0, 86.0, 87.0, 84.0, 86.0, 85.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 149.0, 150.0, 156.0, 160.0, 149.0, 152.0, 246.0, 175.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 176.0, 172.0, 168.0, 170.0, 170.0, 226.0, 210.0, 168.0, 170.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 69.0, 70.0, 70.0, 68.0, 66.0, 67.0, 68.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1470.9999999999998, "alertThreshold": 2.0}]} 07:56:19 INFO - Return code: 0 07:56:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:56:19 INFO - # TBPL SUCCESS # 07:56:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:56:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:56:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:56:19 INFO - 2016-12-11 07:56:19.166 screenresolution[2287:29026] starting screenresolution argv=screenresolution get 07:56:19 INFO - 2016-12-11 07:56:19.171 screenresolution[2287:29026] Display 0: 1600x1200x32@60 07:56:19 INFO - 2016-12-11 07:56:19.177 screenresolution[2288:29028] starting screenresolution argv=screenresolution list 07:56:19 INFO - Available Modes on Display 0 07:56:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:56:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:56:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:56:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:56:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:56:19 INFO - Intel Iris: 07:56:19 INFO - Chipset Model: Intel Iris 07:56:19 INFO - Type: GPU 07:56:19 INFO - Bus: Built-In 07:56:19 INFO - VRAM (Dynamic, Max): 1536 MB 07:56:19 INFO - Vendor: Intel (0x8086) 07:56:19 INFO - Device ID: 0x0a2e 07:56:19 INFO - Revision ID: 0x0009 07:56:19 INFO - Displays: 07:56:19 INFO - Display: 07:56:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:56:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:56:19 INFO - Display Serial Number: 1600x1200 60 07:56:19 INFO - Main Display: Yes 07:56:19 INFO - Mirror: Off 07:56:19 INFO - Online: Yes 07:56:19 INFO - Rotation: Supported 07:56:19 INFO - Return code: 0 07:56:19 INFO - Running post-action listener: _resource_record_post_action 07:56:19 INFO - [mozharness: 2016-12-11 15:56:19.420121Z] Finished run-tests step (success) 07:56:19 INFO - Running post-run listener: _resource_record_post_run 07:56:19 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 643072; Write bytes: 658545664; Read time: 7; Write time: 1989 07:56:19 INFO - TinderboxPrint: CPU usage
53.5% 07:56:19 INFO - TinderboxPrint: I/O read bytes / time
643,072 / 7 07:56:19 INFO - TinderboxPrint: I/O write bytes / time
658,545,664 / 1,989 07:56:19 INFO - TinderboxPrint: CPU idle
2,255.1 (46.5%) 07:56:19 INFO - TinderboxPrint: CPU system
966.6 (19.9%) 07:56:19 INFO - TinderboxPrint: CPU user
1,627.9 (33.6%) 07:56:19 INFO - TinderboxPrint: Swap in / out
348,741,632 / 0 07:56:19 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349383168; Write bytes: 351754240; Read time: 26681; Write time: 662 07:56:19 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 479232; Write bytes: 305378304; Read time: 5; Write time: 1320 07:56:19 INFO - Running post-run listener: _upload_blobber_files 07:56:19 INFO - Blob upload gear active. 07:56:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:56:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:56:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:56:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:56:20 INFO - (blobuploader) - INFO - Open directory for files ... 07:56:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:56:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:56:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:21 INFO - (blobuploader) - INFO - Done attempting. 07:56:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:56:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:56:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:22 INFO - (blobuploader) - INFO - Done attempting. 07:56:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:56:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:56:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:24 INFO - (blobuploader) - INFO - Done attempting. 07:56:24 INFO - (blobuploader) - INFO - Iteration through files over. 07:56:24 INFO - Return code: 0 07:56:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:56:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:56:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5eb863fd5daf7789feb5c721b2fc6f04b94226f9baf5bb1ebd6993ac620be41328b77e14ba76d8c7de2e5b4d545486f8c818b59a3457d6f686c6ac4954116ef6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad5849186a51b5da1f01feafb1c03473e7f137a0665fc9febb17cf15a67585a2f5da5b0d78d8f699d4c8ddced4a528a27875b0505766a45114a5e3e042b3d902", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5995812227a2483f644c7b738f6593322ccb5b7e4540157cf027d181422128811489796171d6abfdae5f3a0be1f096c223810c1a69c9e8713e81bc605eef59fc"} 07:56:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:56:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:56:24 INFO - Contents: 07:56:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5eb863fd5daf7789feb5c721b2fc6f04b94226f9baf5bb1ebd6993ac620be41328b77e14ba76d8c7de2e5b4d545486f8c818b59a3457d6f686c6ac4954116ef6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad5849186a51b5da1f01feafb1c03473e7f137a0665fc9febb17cf15a67585a2f5da5b0d78d8f699d4c8ddced4a528a27875b0505766a45114a5e3e042b3d902", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5995812227a2483f644c7b738f6593322ccb5b7e4540157cf027d181422128811489796171d6abfdae5f3a0be1f096c223810c1a69c9e8713e81bc605eef59fc"} 07:56:24 INFO - Running post-run listener: copy_logs_to_upload_dir 07:56:24 INFO - Copying logs to upload dir... 07:56:24 INFO - mkdir: /builds/slave/test/build/upload/logs 07:56:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.536594 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-11 07:56:24.310035) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-11 07:56:24.313185) ========= 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.WtGi3S6YqO/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.xtyrC6sovM/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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/5eb863fd5daf7789feb5c721b2fc6f04b94226f9baf5bb1ebd6993ac620be41328b77e14ba76d8c7de2e5b4d545486f8c818b59a3457d6f686c6ac4954116ef6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad5849186a51b5da1f01feafb1c03473e7f137a0665fc9febb17cf15a67585a2f5da5b0d78d8f699d4c8ddced4a528a27875b0505766a45114a5e3e042b3d902", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5995812227a2483f644c7b738f6593322ccb5b7e4540157cf027d181422128811489796171d6abfdae5f3a0be1f096c223810c1a69c9e8713e81bc605eef59fc"} build_url:https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/bUglAxXDRlGyZMJBQNyRDA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5eb863fd5daf7789feb5c721b2fc6f04b94226f9baf5bb1ebd6993ac620be41328b77e14ba76d8c7de2e5b4d545486f8c818b59a3457d6f686c6ac4954116ef6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad5849186a51b5da1f01feafb1c03473e7f137a0665fc9febb17cf15a67585a2f5da5b0d78d8f699d4c8ddced4a528a27875b0505766a45114a5e3e042b3d902", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5995812227a2483f644c7b738f6593322ccb5b7e4540157cf027d181422128811489796171d6abfdae5f3a0be1f096c223810c1a69c9e8713e81bc605eef59fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-11 07:56:24.347564) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 07:56:24.347875) ========= 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.WtGi3S6YqO/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.xtyrC6sovM/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.005257 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 07:56:24.398792) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-11 07:56:24.399585) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-11 07:56:24.400244) ========= ========= Total master_lag: 0.22 =========