builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0102 starttime: 1480150025.73 results: success (0) buildid: 20161125215618 builduid: 3d25df939310471291a61c6836acb6bd revision: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:05.731635) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:05.732103) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:05.732377) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:05.802334) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-26 00:47:05.802731) ========= 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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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-11-26 00:47:05-- 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% 17.2M=0.001s 2016-11-26 00:47:06 (17.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.654117 ========= master_lag: 0.60 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-26 00:47:07.061181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:07.061511) ========= 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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.027034 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:07.110448) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:07.110796) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d87b17a47f600b30cad7e7a48dfaa42d3faa7678 --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 d87b17a47f600b30cad7e7a48dfaa42d3faa7678 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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-11-26 00:47:07,201 truncating revision to first 12 chars 2016-11-26 00:47:07,201 Setting DEBUG logging. 2016-11-26 00:47:07,201 attempt 1/10 2016-11-26 00:47:07,201 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d87b17a47f60?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-26 00:47:07,846 unpacking tar archive at: mozilla-aurora-d87b17a47f60/testing/mozharness/ program finished with exit code 0 elapsedTime=0.922548 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:08.057133) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:08.057444) ========= script_repo_revision: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:08.057853) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:08.058139) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-26 00:47:08.078176) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-26 00:47:08.078613) ========= /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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:08 INFO - MultiFileLogger online at 20161126 00:47:08 in /builds/slave/test 00:47:08 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 00:47:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:47:08 INFO - {'append_to_log': False, 00:47:08 INFO - 'base_work_dir': '/builds/slave/test', 00:47:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 00:47:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:47:08 INFO - 'branch': 'Mozilla-Aurora', 00:47:08 INFO - 'buildbot_json_path': 'buildprops.json', 00:47:08 INFO - 'config_files': ('talos/mac_config.py',), 00:47:08 INFO - 'default_actions': ('clobber', 00:47:08 INFO - 'read-buildbot-config', 00:47:08 INFO - 'download-and-extract', 00:47:08 INFO - 'populate-webroot', 00:47:08 INFO - 'create-virtualenv', 00:47:08 INFO - 'install', 00:47:08 INFO - 'run-tests'), 00:47:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:47:08 INFO - 'download_minidump_stackwalk': True, 00:47:08 INFO - 'download_symbols': 'ondemand', 00:47:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:47:08 INFO - 'tooltool.py': '/tools/tooltool.py', 00:47:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:47:08 INFO - '/tools/misc-python/virtualenv.py')}, 00:47:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:47:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:47:08 INFO - 'installer_path': 'installer.exe', 00:47:08 INFO - 'log_level': 'info', 00:47:08 INFO - 'log_name': 'talos', 00:47:08 INFO - 'log_to_console': True, 00:47:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:47:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:47:08 INFO - 'opt_config_files': (), 00:47:08 INFO - 'pip_index': False, 00:47:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:47:08 INFO - 'cmd': ('bash', 00:47:08 INFO - '-c', 00:47:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:47:08 INFO - 'enabled': True, 00:47:08 INFO - 'halt_on_failure': False, 00:47:08 INFO - 'name': 'check_screen_resolution'},), 00:47:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:47:08 INFO - 'cmd': ('bash', 00:47:08 INFO - '-c', 00:47:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:47:08 INFO - 'enabled': True, 00:47:08 INFO - 'halt_on_failure': False, 00:47:08 INFO - 'name': 'check_screen_resolution'},), 00:47:08 INFO - 'run_cmd_checks_enabled': True, 00:47:08 INFO - 'sps_profile': False, 00:47:08 INFO - 'sps_profile_interval': 0, 00:47:08 INFO - 'suite': 'dromaeojs', 00:47:08 INFO - 'system_bits': '32', 00:47:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:47:08 INFO - 'title': 't-yosemite-r7-0102', 00:47:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:47:08 INFO - 'use_talos_json': True, 00:47:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:47:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:47:08 INFO - 'work_dir': 'build'} 00:47:08 INFO - [mozharness: 2016-11-26 08:47:08.281683Z] Running clobber step. 00:47:08 INFO - Running pre-action listener: _resource_record_pre_action 00:47:08 INFO - Running main action method: clobber 00:47:08 INFO - rmtree: /builds/slave/test/build 00:47:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:47:10 INFO - Running post-action listener: _resource_record_post_action 00:47:10 INFO - [mozharness: 2016-11-26 08:47:10.694819Z] Finished clobber step (success) 00:47:10 INFO - [mozharness: 2016-11-26 08:47:10.694944Z] Running read-buildbot-config step. 00:47:10 INFO - Running pre-action listener: _resource_record_pre_action 00:47:10 INFO - Running main action method: read_buildbot_config 00:47:10 INFO - Using buildbot properties: 00:47:10 INFO - { 00:47:10 INFO - "project": "", 00:47:10 INFO - "product": "firefox", 00:47:10 INFO - "script_repo_revision": "production", 00:47:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 00:47:10 INFO - "repository": "", 00:47:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 00:47:10 INFO - "buildid": "20161125215618", 00:47:10 INFO - "pgo_build": "False", 00:47:10 INFO - "basedir": "/builds/slave/test", 00:47:10 INFO - "buildnumber": 48, 00:47:10 INFO - "slavename": "t-yosemite-r7-0102", 00:47:10 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 00:47:10 INFO - "platform": "yosemite_r7", 00:47:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 00:47:10 INFO - "branch": "mozilla-aurora", 00:47:10 INFO - "repo_path": "releases/mozilla-aurora", 00:47:10 INFO - "revision": "d87b17a47f600b30cad7e7a48dfaa42d3faa7678", 00:47:10 INFO - "stage_platform": "macosx64", 00:47:10 INFO - "builduid": "3d25df939310471291a61c6836acb6bd", 00:47:10 INFO - "slavebuilddir": "test" 00:47:10 INFO - } 00:47:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 00:47:10 INFO - Running post-action listener: _resource_record_post_action 00:47:10 INFO - [mozharness: 2016-11-26 08:47:10.698270Z] Finished read-buildbot-config step (success) 00:47:10 INFO - [mozharness: 2016-11-26 08:47:10.698394Z] Running download-and-extract step. 00:47:10 INFO - Running pre-action listener: _resource_record_pre_action 00:47:10 INFO - Running main action method: download_and_extract 00:47:10 INFO - mkdir: /builds/slave/test/build/tests 00:47:10 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')]} 00:47:10 INFO - trying https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 00:47:10 INFO - Downloading https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/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 00:47:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/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 00:47:11 INFO - Downloaded 1199 bytes. 00:47:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 00:47:11 INFO - Using the following test package requirements: 00:47:11 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 00:47:11 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 00:47:11 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 00:47:11 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'jsshell-mac.zip'], 00:47:11 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 00:47:11 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 00:47:11 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 00:47:11 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 00:47:11 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 00:47:11 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 00:47:11 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 00:47:11 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 00:47:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 00:47:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 00:47:11 INFO - Fetch https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 00:47:12 INFO - Http code: 200 00:47:12 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 00:47:12 INFO - x-amz-cf-id: QQrUmjO-ErbZTNTmLB90Iv1xlNpkNkxpx_RMELR1XOiJPXUWsOSThA== 00:47:12 INFO - x-amz-version-id: Sx5UqTJ_RKTEsHqKjJoYuca_P8PapyWb 00:47:12 INFO - Expected file size: 16279006 00:47:12 INFO - Obtained file size: 16279006 00:47:18 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 00:47:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 00:47:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 00:47:18 INFO - Fetch https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 00:47:19 INFO - Http code: 200 00:47:19 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 00:47:19 INFO - x-amz-cf-id: 9h5umgEak7UNId9XabZscZ6pNfebqz5I3zrrxENPzU_wjsHHYt3IqA== 00:47:19 INFO - x-amz-version-id: Sx5UqTJ_RKTEsHqKjJoYuca_P8PapyWb 00:47:20 INFO - Expected file size: 16279006 00:47:20 INFO - Obtained file size: 16279006 00:47:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 00:47:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 00:47:26 INFO - Fetch https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 00:47:27 INFO - Http code: 200 00:47:27 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 00:47:27 INFO - x-amz-cf-id: g6ds2gTioQs6HC3S0Wba00cVObDO9zO3yrXrMWET4XmiSN95KwyaXQ== 00:47:27 INFO - x-amz-version-id: dQ_vxeaO82aWMRPlxlfWbgluPrdCJBEq 00:47:27 INFO - Expected file size: 14409038 00:47:27 INFO - Obtained file size: 14409038 00:47:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:28 INFO - trying https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 00:47:28 INFO - Downloading https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 00:47:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/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 00:47:30 INFO - Downloaded 109744366 bytes. 00:47:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 00:47:30 INFO - mkdir: /builds/slave/test/properties 00:47:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:47:30 INFO - Writing to file /builds/slave/test/properties/build_url 00:47:30 INFO - Contents: 00:47:30 INFO - build_url:https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 00:47:31 INFO - Running post-action listener: _resource_record_post_action 00:47:31 INFO - Running post-action listener: set_extra_try_arguments 00:47:31 INFO - [mozharness: 2016-11-26 08:47:31.558810Z] Finished download-and-extract step (success) 00:47:31 INFO - [mozharness: 2016-11-26 08:47:31.558928Z] Running populate-webroot step. 00:47:31 INFO - Running pre-action listener: _resource_record_pre_action 00:47:31 INFO - Running main action method: populate_webroot 00:47:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:47:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:47:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:47:31 INFO - '--tppagecycles', 00:47:31 INFO - '7'], 00:47:31 INFO - 'tests': ['tsvgm']}}, 00:47:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:47:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:47:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:47:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:47:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:47:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:47:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['damp', 'tps']}, 00:47:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'tests': ['damp', 'tps']}, 00:47:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['dromaeo_dom']}, 00:47:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:47:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['basic_compositor_video']}, 00:47:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:47:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['a11yr', 00:47:31 INFO - 'ts_paint', 00:47:31 INFO - 'tpaint', 00:47:31 INFO - 'sessionrestore', 00:47:31 INFO - 'sessionrestore_no_auto_restore', 00:47:31 INFO - 'tabpaint']}, 00:47:31 INFO - 'other-e10s': {'tests': ['a11yr', 00:47:31 INFO - 'ts_paint', 00:47:31 INFO - 'tpaint', 00:47:31 INFO - 'sessionrestore', 00:47:31 INFO - 'sessionrestore_no_auto_restore', 00:47:31 INFO - 'tabpaint']}, 00:47:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:47:31 INFO - 'ts_paint', 00:47:31 INFO - 'tpaint', 00:47:31 INFO - 'sessionrestore', 00:47:31 INFO - 'sessionrestore_no_auto_restore', 00:47:31 INFO - 'tabpaint']}, 00:47:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:47:31 INFO - 'ts_paint', 00:47:31 INFO - 'tpaint', 00:47:31 INFO - 'sessionrestore', 00:47:31 INFO - 'sessionrestore_no_auto_restore', 00:47:31 INFO - 'tabpaint']}, 00:47:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['a11yr', 00:47:31 INFO - 'ts_paint', 00:47:31 INFO - 'tpaint', 00:47:31 INFO - 'sessionrestore', 00:47:31 INFO - 'sessionrestore_no_auto_restore', 00:47:31 INFO - 'tabpaint']}, 00:47:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['a11yr', 00:47:31 INFO - 'ts_paint', 00:47:31 INFO - 'tpaint', 00:47:31 INFO - 'sessionrestore', 00:47:31 INFO - 'sessionrestore_no_auto_restore', 00:47:31 INFO - 'tabpaint']}, 00:47:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['tsvgx', 00:47:31 INFO - 'tsvgr_opacity', 00:47:31 INFO - 'tart', 00:47:31 INFO - 'tscrollx', 00:47:31 INFO - 'cart']}, 00:47:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:47:31 INFO - 'tsvgr_opacity', 00:47:31 INFO - 'tart', 00:47:31 INFO - 'tscrollx', 00:47:31 INFO - 'cart']}, 00:47:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'talos_options': ['--disable-e10s'], 00:47:31 INFO - 'tests': ['tp5o']}, 00:47:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'tests': ['tp5o']}, 00:47:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'talos_options': ['--disable-e10s', 00:47:31 INFO - '--xperf_path', 00:47:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:47:31 INFO - 'tests': ['tp5n']}, 00:47:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:47:31 INFO - 'talos_options': ['--xperf_path', 00:47:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:47:31 INFO - 'tests': ['tp5n']}}, 00:47:31 INFO - 'talos.zip': {'path': '', 00:47:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:47:31 INFO - Running post-action listener: _resource_record_post_action 00:47:31 INFO - [mozharness: 2016-11-26 08:47:31.577329Z] Finished populate-webroot step (success) 00:47:31 INFO - [mozharness: 2016-11-26 08:47:31.577457Z] Running create-virtualenv step. 00:47:31 INFO - Running pre-action listener: _resource_record_pre_action 00:47:31 INFO - Running main action method: create_virtualenv 00:47:31 INFO - Creating virtualenv /builds/slave/test/build/venv 00:47:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 00:47:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:47:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:47:31 INFO - Using real prefix '/tools/python27' 00:47:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:47:32 INFO - Installing distribute.............................................................................................................................................................................................done. 00:47:35 INFO - Installing pip.................done. 00:47:35 INFO - Return code: 0 00:47:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:47:35 INFO - Reading from file tmpfile_stdout 00:47:35 INFO - Output received: 00:47:35 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) 00:47:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:35 INFO - 'HOME': '/Users/cltbld', 00:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:35 INFO - 'LOGNAME': 'cltbld', 00:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:35 INFO - 'MOZ_NO_REMOTE': '1', 00:47:35 INFO - 'NO_EM_RESTART': '1', 00:47:35 INFO - 'PAGER': '/bin/cat', 00:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:35 INFO - 'PWD': '/builds/slave/test', 00:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:35 INFO - 'SHELL': '/bin/bash', 00:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:35 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:35 INFO - 'USER': 'cltbld', 00:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:35 INFO - 'XPC_FLAGS': '0x0', 00:47:35 INFO - 'XPC_SERVICE_NAME': '0', 00:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 00:47:35 INFO - Cleaning up... 00:47:35 INFO - Return code: 0 00:47:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:35 INFO - 'HOME': '/Users/cltbld', 00:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:35 INFO - 'LOGNAME': 'cltbld', 00:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:35 INFO - 'MOZ_NO_REMOTE': '1', 00:47:35 INFO - 'NO_EM_RESTART': '1', 00:47:35 INFO - 'PAGER': '/bin/cat', 00:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:35 INFO - 'PWD': '/builds/slave/test', 00:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:35 INFO - 'SHELL': '/bin/bash', 00:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:35 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:35 INFO - 'USER': 'cltbld', 00:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:35 INFO - 'XPC_FLAGS': '0x0', 00:47:35 INFO - 'XPC_SERVICE_NAME': '0', 00:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:36 INFO - Downloading/unpacking psutil>=3.1.1 00:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:47:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:47:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:47:40 INFO - Installing collected packages: psutil 00:47:40 INFO - Running setup.py install for psutil 00:47:40 INFO - building 'psutil._psutil_osx' extension 00:47:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 00:47:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 00:47:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 00:47:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 00:47:41 INFO - building 'psutil._psutil_posix' extension 00:47:41 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 00:47:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:47:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:47:41 INFO - ^ 00:47:41 INFO - 1 warning generated. 00:47:41 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 00:47:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:47:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:47:41 INFO - Successfully installed psutil 00:47:41 INFO - Cleaning up... 00:47:41 INFO - Return code: 0 00:47:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:41 INFO - 'HOME': '/Users/cltbld', 00:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:41 INFO - 'LOGNAME': 'cltbld', 00:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:41 INFO - 'MOZ_NO_REMOTE': '1', 00:47:41 INFO - 'NO_EM_RESTART': '1', 00:47:41 INFO - 'PAGER': '/bin/cat', 00:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:41 INFO - 'PWD': '/builds/slave/test', 00:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:41 INFO - 'SHELL': '/bin/bash', 00:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:41 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:41 INFO - 'USER': 'cltbld', 00:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:41 INFO - 'XPC_FLAGS': '0x0', 00:47:41 INFO - 'XPC_SERVICE_NAME': '0', 00:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:47:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:47:44 INFO - Installing collected packages: mozsystemmonitor 00:47:44 INFO - Running setup.py install for mozsystemmonitor 00:47:44 INFO - Successfully installed mozsystemmonitor 00:47:44 INFO - Cleaning up... 00:47:44 INFO - Return code: 0 00:47:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:47:44 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')]} 00:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:44 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')]} 00:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:44 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 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:44 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 00:47:44 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 00:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:44 INFO - 'HOME': '/Users/cltbld', 00:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:44 INFO - 'LOGNAME': 'cltbld', 00:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:44 INFO - 'MOZ_NO_REMOTE': '1', 00:47:44 INFO - 'NO_EM_RESTART': '1', 00:47:44 INFO - 'PAGER': '/bin/cat', 00:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:44 INFO - 'PWD': '/builds/slave/test', 00:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:44 INFO - 'SHELL': '/bin/bash', 00:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:44 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:44 INFO - 'USER': 'cltbld', 00:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:44 INFO - 'XPC_FLAGS': '0x0', 00:47:44 INFO - 'XPC_SERVICE_NAME': '0', 00:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:44 INFO - Downloading/unpacking jsonschema==2.5.1 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:47:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47:47 INFO - Downloading functools32-3.2.3-2.tar.gz 00:47:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:47:47 INFO - warning: no files found matching '*.txt' 00:47:47 INFO - no previously-included directories found matching 'build' 00:47:47 INFO - no previously-included directories found matching 'dist' 00:47:47 INFO - no previously-included directories found matching '.git*' 00:47:47 INFO - Installing collected packages: jsonschema, functools32 00:47:47 INFO - Running setup.py install for functools32 00:47:47 INFO - warning: no files found matching '*.txt' 00:47:47 INFO - no previously-included directories found matching 'build' 00:47:47 INFO - no previously-included directories found matching 'dist' 00:47:47 INFO - no previously-included directories found matching '.git*' 00:47:47 INFO - Successfully installed jsonschema functools32 00:47:47 INFO - Cleaning up... 00:47:48 INFO - Return code: 0 00:47:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 00:47:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 00:47:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:48 INFO - 'HOME': '/Users/cltbld', 00:47:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:48 INFO - 'LOGNAME': 'cltbld', 00:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:48 INFO - 'MOZ_NO_REMOTE': '1', 00:47:48 INFO - 'NO_EM_RESTART': '1', 00:47:48 INFO - 'PAGER': '/bin/cat', 00:47:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:48 INFO - 'PWD': '/builds/slave/test', 00:47:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:48 INFO - 'SHELL': '/bin/bash', 00:47:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:48 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:48 INFO - 'USER': 'cltbld', 00:47:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:48 INFO - 'XPC_FLAGS': '0x0', 00:47:48 INFO - 'XPC_SERVICE_NAME': '0', 00:47:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:47:48 INFO - Cleaning up... 00:47:48 INFO - Return code: 0 00:47:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:47:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:47:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:48 INFO - 'HOME': '/Users/cltbld', 00:47:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:48 INFO - 'LOGNAME': 'cltbld', 00:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:48 INFO - 'MOZ_NO_REMOTE': '1', 00:47:48 INFO - 'NO_EM_RESTART': '1', 00:47:48 INFO - 'PAGER': '/bin/cat', 00:47:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:48 INFO - 'PWD': '/builds/slave/test', 00:47:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:48 INFO - 'SHELL': '/bin/bash', 00:47:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:48 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:48 INFO - 'USER': 'cltbld', 00:47:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:48 INFO - 'XPC_FLAGS': '0x0', 00:47:48 INFO - 'XPC_SERVICE_NAME': '0', 00:47:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:48 INFO - Downloading/unpacking blobuploader==1.2.4 00:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:47:51 INFO - Downloading blobuploader-1.2.4.tar.gz 00:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:47:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:47:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47: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 00:47:52 INFO - Downloading docopt-0.6.1.tar.gz 00:47:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:47:52 INFO - Installing collected packages: blobuploader, requests, docopt 00:47:52 INFO - Running setup.py install for blobuploader 00:47:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:47:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:47:52 INFO - Running setup.py install for requests 00:47:53 INFO - Running setup.py install for docopt 00:47:53 INFO - Successfully installed blobuploader requests docopt 00:47:53 INFO - Cleaning up... 00:47:53 INFO - Return code: 0 00:47:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:47:53 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')]} 00:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:53 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')]} 00:47:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:53 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 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:53 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 00:47:53 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 00:47:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:53 INFO - 'HOME': '/Users/cltbld', 00:47:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:53 INFO - 'LOGNAME': 'cltbld', 00:47:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:53 INFO - 'MOZ_NO_REMOTE': '1', 00:47:53 INFO - 'NO_EM_RESTART': '1', 00:47:53 INFO - 'PAGER': '/bin/cat', 00:47:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:53 INFO - 'PWD': '/builds/slave/test', 00:47:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:53 INFO - 'SHELL': '/bin/bash', 00:47:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:53 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:53 INFO - 'USER': 'cltbld', 00:47:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:53 INFO - 'XPC_FLAGS': '0x0', 00:47:53 INFO - 'XPC_SERVICE_NAME': '0', 00:47:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:47:53 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Mt3z1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:47:53 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-fSqpnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:47:53 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-g0AynX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:47:53 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-yljM6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:47:53 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-hpV3Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-9lYm7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-snSdjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-gckNqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-gXfS11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-LCWGgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-oQp8Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-8lL4Xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-rFZkyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-nGYjgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:47:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:54 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-bB4Krm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:47:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-re_YSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:47:55 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-v1lmyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:47:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:47:55 INFO - Running setup.py install for manifestparser 00:47:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Running setup.py install for mozcrash 00:47:55 INFO - Running setup.py install for mozdebug 00:47:55 INFO - Running setup.py install for mozdevice 00:47:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:47:55 INFO - Running setup.py install for mozfile 00:47:56 INFO - Running setup.py install for mozhttpd 00:47:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Running setup.py install for mozinfo 00:47:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Running setup.py install for mozInstall 00:47:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Running setup.py install for mozleak 00:47:56 INFO - Running setup.py install for mozlog 00:47:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Running setup.py install for moznetwork 00:47:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:47:56 INFO - Running setup.py install for mozprocess 00:47:57 INFO - Running setup.py install for mozprofile 00:47:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:47:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:47:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:47:57 INFO - Running setup.py install for mozrunner 00:47:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:47:57 INFO - Running setup.py install for mozscreenshot 00:47:57 INFO - Running setup.py install for moztest 00:47:57 INFO - Running setup.py install for mozversion 00:47:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:47:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:47:57 INFO - Cleaning up... 00:47:57 INFO - Return code: 0 00:47:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:47:57 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 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:47:57 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 00:47:57 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 00:47:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:47:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:47:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:47:57 INFO - 'HOME': '/Users/cltbld', 00:47:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:47:57 INFO - 'LOGNAME': 'cltbld', 00:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:47:57 INFO - 'MOZ_NO_REMOTE': '1', 00:47:57 INFO - 'NO_EM_RESTART': '1', 00:47:57 INFO - 'PAGER': '/bin/cat', 00:47:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:47:57 INFO - 'PWD': '/builds/slave/test', 00:47:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:47:57 INFO - 'SHELL': '/bin/bash', 00:47:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:47:57 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:47:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:47:57 INFO - 'USER': 'cltbld', 00:47:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:47:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:47:57 INFO - 'XPC_FLAGS': '0x0', 00:47:57 INFO - 'XPC_SERVICE_NAME': '0', 00:47:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Aucqfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:47:58 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)) 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-oiYwNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:47:58 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)) 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-EOyOEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:47:58 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)) 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-h_Z6mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:47:58 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)) 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-LPHdPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:47:58 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)) 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-6BzkGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:47:58 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)) 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-iBsxIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:47:58 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)) 00:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:47:58 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-GVi5p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-GxBWgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-svWGHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-9PAPXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-N40fE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-FXX4wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-dUJklF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-7U_iFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-wAZ1MQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:47:59 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)) 00:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:47:59 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-w8h0Ip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:47:59 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)) 00:47:59 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)) 00:47:59 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)) 00:47:59 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)) 00:47:59 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)) 00:47:59 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)) 00:47:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:47:59 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 00:47:59 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 00:47:59 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 00:47:59 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 00:47:59 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 00:47:59 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 00:48:02 INFO - Downloading blessings-1.6.tar.gz 00:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:48:02 INFO - Installing collected packages: blessings 00:48:02 INFO - Running setup.py install for blessings 00:48:02 INFO - Successfully installed blessings 00:48:02 INFO - Cleaning up... 00:48:02 INFO - Return code: 0 00:48:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:48:03 INFO - Reading from file tmpfile_stdout 00:48:03 INFO - Current package versions: 00:48:03 INFO - blessings == 1.6 00:48:03 INFO - blobuploader == 1.2.4 00:48:03 INFO - docopt == 0.6.1 00:48:03 INFO - functools32 == 3.2.3-2 00:48:03 INFO - jsonschema == 2.5.1 00:48:03 INFO - manifestparser == 1.1 00:48:03 INFO - mozInstall == 1.12 00:48:03 INFO - mozcrash == 1.0 00:48:03 INFO - mozdebug == 0.1 00:48:03 INFO - mozdevice == 0.48 00:48:03 INFO - mozfile == 1.2 00:48:03 INFO - mozhttpd == 0.7 00:48:03 INFO - mozinfo == 0.9 00:48:03 INFO - mozleak == 0.1 00:48:03 INFO - mozlog == 3.4 00:48:03 INFO - moznetwork == 0.27 00:48:03 INFO - mozprocess == 0.23 00:48:03 INFO - mozprofile == 0.28 00:48:03 INFO - mozrunner == 6.13 00:48:03 INFO - mozscreenshot == 0.1 00:48:03 INFO - mozsystemmonitor == 0.3 00:48:03 INFO - moztest == 0.7 00:48:03 INFO - mozversion == 1.4 00:48:03 INFO - psutil == 3.1.1 00:48:03 INFO - requests == 1.2.3 00:48:03 INFO - wsgiref == 0.1.2 00:48:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:48:03 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')]} 00:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:03 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')]} 00:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:03 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 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:48:03 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 00:48:03 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 00:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:48:03 INFO - 'HOME': '/Users/cltbld', 00:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:48:03 INFO - 'LOGNAME': 'cltbld', 00:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:48:03 INFO - 'MOZ_NO_REMOTE': '1', 00:48:03 INFO - 'NO_EM_RESTART': '1', 00:48:03 INFO - 'PAGER': '/bin/cat', 00:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:48:03 INFO - 'PWD': '/builds/slave/test', 00:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:48:03 INFO - 'SHELL': '/bin/bash', 00:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:48:03 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:48:03 INFO - 'USER': 'cltbld', 00:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:48:03 INFO - 'XPC_FLAGS': '0x0', 00:48:03 INFO - 'XPC_SERVICE_NAME': '0', 00:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 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)) 00:48:03 INFO - Cleaning up... 00:48:03 INFO - Return code: 0 00:48:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:48:03 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')]} 00:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:03 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')]} 00:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:48:03 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 0x10c82c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90f247c980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, '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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 00:48:03 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 00:48:03 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 00:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:48:03 INFO - 'HOME': '/Users/cltbld', 00:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:48:03 INFO - 'LOGNAME': 'cltbld', 00:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:48:03 INFO - 'MOZ_NO_REMOTE': '1', 00:48:03 INFO - 'NO_EM_RESTART': '1', 00:48:03 INFO - 'PAGER': '/bin/cat', 00:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:48:03 INFO - 'PWD': '/builds/slave/test', 00:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:48:03 INFO - 'SHELL': '/bin/bash', 00:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:48:03 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:48:03 INFO - 'USER': 'cltbld', 00:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:48:03 INFO - 'XPC_FLAGS': '0x0', 00:48:03 INFO - 'XPC_SERVICE_NAME': '0', 00:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:48:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:48:03 INFO - Cleaning up... 00:48:03 INFO - Return code: 0 00:48:03 INFO - Running post-action listener: _resource_record_post_action 00:48:03 INFO - Running post-action listener: _start_resource_monitoring 00:48:03 INFO - Starting resource monitoring. 00:48:03 INFO - [mozharness: 2016-11-26 08:48:03.746573Z] Finished create-virtualenv step (success) 00:48:03 INFO - [mozharness: 2016-11-26 08:48:03.747714Z] Running install step. 00:48:03 INFO - Running pre-action listener: _resource_record_pre_action 00:48:03 INFO - Running main action method: install 00:48:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:48:04 INFO - Reading from file tmpfile_stdout 00:48:04 INFO - Detecting whether we're running mozinstall >=1.0... 00:48:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:48:04 INFO - Reading from file tmpfile_stdout 00:48:04 INFO - Output received: 00:48:04 INFO - Usage: mozinstall [options] installer 00:48:04 INFO - Options: 00:48:04 INFO - -h, --help show this help message and exit 00:48:04 INFO - -d DEST, --destination=DEST 00:48:04 INFO - Directory to install application into. [default: 00:48:04 INFO - "/builds/slave/test"] 00:48:04 INFO - --app=APP Application being installed. [default: firefox] 00:48:04 INFO - mkdir: /builds/slave/test/build/application 00:48:04 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'] 00:48:04 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 00:48:35 INFO - Reading from file tmpfile_stdout 00:48:35 INFO - Output received: 00:48:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 00:48:35 INFO - Running post-action listener: _resource_record_post_action 00:48:35 INFO - [mozharness: 2016-11-26 08:48:35.270563Z] Finished install step (success) 00:48:35 INFO - [mozharness: 2016-11-26 08:48:35.270726Z] Running run-tests step. 00:48:35 INFO - Running pre-action listener: _resource_record_pre_action 00:48:35 INFO - Running main action method: run_tests 00:48:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:48:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:48:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:48:35 INFO - 2016-11-26 00:48:35.285 screenresolution[1632:8247] starting screenresolution argv=screenresolution get 00:48:35 INFO - 2016-11-26 00:48:35.287 screenresolution[1632:8247] Display 0: 1600x1200x32@60 00:48:35 INFO - 2016-11-26 00:48:35.293 screenresolution[1633:8249] starting screenresolution argv=screenresolution list 00:48:35 INFO - Available Modes on Display 0 00:48:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:48:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:48:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:48:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:48:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:48:35 INFO - Intel Iris: 00:48:35 INFO - Chipset Model: Intel Iris 00:48:35 INFO - Type: GPU 00:48:35 INFO - Bus: Built-In 00:48:35 INFO - VRAM (Dynamic, Max): 1536 MB 00:48:35 INFO - Vendor: Intel (0x8086) 00:48:35 INFO - Device ID: 0x0a2e 00:48:35 INFO - Revision ID: 0x0009 00:48:35 INFO - Displays: 00:48:35 INFO - Display: 00:48:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:48:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:48:35 INFO - Display Serial Number: 1600x1200 60 00:48:35 INFO - Main Display: Yes 00:48:35 INFO - Mirror: Off 00:48:35 INFO - Online: Yes 00:48:35 INFO - Rotation: Supported 00:48:35 INFO - Return code: 0 00:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:48:35 INFO - Python 2.7.3 00:48:35 INFO - Return code: 0 00:48:35 INFO - grabbing minidump binary from tooltool 00:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:48:35 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 0x10c640d30>, '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 0x10c4a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c638b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:48:35 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 00:48:35 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 00:48:35 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 00:48:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:48:35 INFO - Return code: 0 00:48:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:48:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:48:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:48:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:48:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:48:35 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/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--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 00:48:35 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/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0102 --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 00:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y3IJuKIF6K/Render', 00:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:48:35 INFO - 'HOME': '/Users/cltbld', 00:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:48:35 INFO - 'LOGNAME': 'cltbld', 00:48:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:48:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:48:35 INFO - 'MOZ_NO_REMOTE': '1', 00:48:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:48:35 INFO - 'NO_EM_RESTART': '1', 00:48:35 INFO - 'PAGER': '/bin/cat', 00:48:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:48:35 INFO - 'PWD': '/builds/slave/test', 00:48:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:48:35 INFO - 'SHELL': '/bin/bash', 00:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.D9WBfpC6Go/Listeners', 00:48:35 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 00:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:48:35 INFO - 'USER': 'cltbld', 00:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:48:35 INFO - 'XPC_FLAGS': '0x0', 00:48:35 INFO - 'XPC_SERVICE_NAME': '0', 00:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:48:35 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/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--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 00:48:35 INFO - mozversion application_buildid: 20161125215618 00:48:35 INFO - mozversion application_changeset: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 00:48:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 00:48:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:48:35 INFO - mozversion application_name: Firefox 00:48:35 INFO - mozversion application_remotingname: firefox-dev 00:48:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:48:35 INFO - mozversion application_vendor: Mozilla 00:48:35 INFO - mozversion application_version: 52.0a2 00:48:35 INFO - mozversion platform_buildid: 20161125215618 00:48:35 INFO - mozversion platform_changeset: d87b17a47f600b30cad7e7a48dfaa42d3faa7678 00:48:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:48:35 INFO - mozversion platform_version: 52.0a2 00:48:35 INFO - using testdate: 1480150115 00:48:35 INFO - actual date: 1480150115 00:48:35 INFO - starting webserver on 'localhost:49248' 00:48:35 INFO - SUITE-START | Running 2 tests 00:48:35 INFO - TEST-START | dromaeo_css 00:48:35 INFO - operating with platform_type : mac_ 00:48:35 INFO - Initialising browser for dromaeo_css test... 00:48:35 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpns_DhG/profile http://localhost:49248/getInfo.html) 00:48:38 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 00:48:38 INFO - PROCESS | 1643 | colorDepth:24 00:48:38 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 00:48:38 INFO - PROCESS | 1643 | __metrics 00:48:38 INFO - PROCESS | 1643 | 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" 00:48:38 INFO - PROCESS | 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:48:39 INFO - TEST-INFO | 1643: exit 0 00:48:39 INFO - Browser initialized. 00:48:39 INFO - Running cycle 1/1 for dromaeo_css test... 00:48:39 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpns_DhG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:48:51 INFO - PROCESS | 1646 | RSS: Main: 223064064 00:48:51 INFO - PROCESS | 1646 | 00:49:43 INFO - PROCESS | 1646 | 1480150183430 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] 00:51:55 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 00:51:58 INFO - PROCESS | 1646 | RSS: Main: 449089536 00:51:58 INFO - PROCESS | 1646 | 00:55:01 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 00:55:02 INFO - PROCESS | 1646 | RSS: Main: 348344320 00:55:02 INFO - PROCESS | 1646 | 00:58:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 00:58:04 INFO - PROCESS | 1646 | RSS: Main: 318267392 00:58:04 INFO - PROCESS | 1646 | 01:01:07 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 01:01:07 INFO - PROCESS | 1646 | RSS: Main: 330825728 01:01:07 INFO - PROCESS | 1646 | 01:04:11 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 01:04:11 INFO - PROCESS | 1646 | RSS: Main: 311255040 01:04:11 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 01:07:15 INFO - PROCESS | 1646 | RSS: Main: 437297152 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | __start_tp_report 01:07:15 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 01:07:15 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 01:07:15 INFO - PROCESS | 1646 | |i|pagename|runs| 01:07:15 INFO - PROCESS | 1646 | |0;dojo.html;3319.7344212463004;3351.0617027232374;3382.543356646852;3395.1681837949704;3432.1934345428836;4229.900225941258;5028.170351891933;5723.084306510959;5742.54059675225;5785.681787501691;5615.578831587637;5703.77184912603;5717.285339332583;5724.570657200706;5756.625819321752;5182.766775495103;5208.958208358325;5222.503862133091;5260.488154204222;5264.105102132647;5145.253938178962;5190.195519694454;5202.06362854686;5311.654742441749;5311.707856067915;5213.895722085556;5226.738663066851;5242.449542798008;5274.815381461631;5293.311869456965;5224.373075230997;5259.579233661298;5268.341457317835;5292.629515933886;5321.148616221386;5168.302279192336;5286.9471305287225;5289.973550132263;5292.153255479144;5292.391374991887;5581.386047534768;5629.043062679354;5646.463585959368;5652.378238393773;5740.54075673945;6187.721552530147;6260.15487909131;6267.4045965633195;6278.215223097113;6296.811095667137;6317.210348706411;6326.557141000132;6332.240131184288;6351.777687780952;6376.904346434807;5689.2319536862;5756.798512052058;5763.682997435181;5804.245448091763;5829.8251052468495;6235.625862448268;6261.029540421242;6268;6272.247330320391;6293.653849038347;5532.28080349552;6183.072539119168;6190.164327815777;6203.875922481525;6209.720562574695;6122.947104152868;6148.1699970503405;6271.432141964508;6303.936960630335;6317.684115794192;6129.693515324216;6151.015837465984;6160.1375807387;6248.187735594343;6249.531285153632;6262.5929314594405;6272.905906411407;6278.46633036197;6285.528585356116;6287.717052732638;4865.829695960624;4937.975310123426;4961.454240033593;4964.106460837084;4967.453580106186;4364.039911219526;4390.319500477431;4420.248557745191;4457.576530229623;4470;5766;5815.534757219412;5846.766129354864;5895.557833162444;5909.674967876808;6252.093446450239;6288.8427789304715;6298.307186209513;6315.621062736251;6320.462760665303;5640.238567793295;5760.596758226968;5858.355580886099;5869.295684517885;5896.911546326808;4577.473590537131;5326.98787230425;5330.067238233293;5348.064088784525;5372.57019438444;6274.1843560336865;6275.497960163268;6297.275813281348;6299.086632438362;6307.653079080603;6178.042403427476;6295.055741638607;6304.873902522016;6314.589551679126;6318.715657795502;6138.602983405075;6195.597286176384;6204.565680402328;6216.129741836237;6240.968795155995;6176.382361763787;6213.9067913980425;6214.844628884314;6221.751129954751;6228.4705800007505;6099.268087829489;6222.16000839899;6282.371762823864;6305.085762564494;6329.019002054689;5938.6436813791315;6003;6004.459598636144;6114.755409783559;6150.600210986271;6020.277566692025;6042.1540984261355;6043.3050199227655;6043.456088952015;6045.637261764308;5850.619709718854;5858.033424485083;5858.355580886014;5863.677497737494;5878.500327472042;5958.076498142795;6008.789692360747;6021.729022193924;6030.155778191144;6036.6679832609625;5829.183914252093;5844.6785426801935;5845.444682755132;5845.736941837543;5846.327672317738 01:07:15 INFO - PROCESS | 1646 | |1;ext.html;465.6996237426859;501.14054396709633;526.1003683700872;529.1559961860833;559.9356074051481;28652.570211446844;38410.42384364233;39367.60632393675;39539.60460395395;41402;40400.39399409011;40819;40874;40884.182316353654;40903.38644920329;8600;9299.070092990698;9479.905200947971;9485.525723713821;9491.66779162726;8568.87146692797;8700.869486957701;8734.650613975433;8739.431936924111;11087.83368249477;11131.165162612797;11177.441127943612;11235.438228088562;11253.381064041474;11265.380404077772;11136.721581960475;11236.381998990053;11244.943775281072;11246.49390777417;11251.437428128642;8490.150984901522;8755.168259015383;10894.019538241597;11107.944460277648;11184.552617895275;37167;38905.83282501529;38910.832675019796;38976.830695079196;38999.610003899885;3119.859606317721;3174.4920812670093;3189.5853539039776;3195.1053704962646;3248.1717162123746;29254.26864328409;29876;30023;30200.09399718012;30228.697713022808;3052.4047810676925;3115.688431156889;3131.780775345727;3227.596550431196;3268.509723541464;488037;489327.5533622345;490648;491325;491381;58384;58751;58777.41222587805;58807.11789323163;58875;60529.39470605326;60686;60720.696396517735;60772;60778.39221607727;15807.13060781668;16320.775941804412;16325.83674163243;16335.26491307894;16342.591435213977;12600.369981501071;12647.620571382873;12687.365631718378;12687.619371418872;12739;14424.06243594163;14804.407823686932;14810.555683329518;16049;16275.674486510203;6443.581167224115;6525.086487891699;6570.441512471398;6576.342365763386;6594.241662208908;883.9558022098869;953.5518306395983;974.2790335151936;974.3910056214866;1050.1283934334483;28468.288292792848;29088.709112909022;29253.414931701245;29916.70083299139;30108.397832043236;848.6266042941086;970.8252514547308;1073.3774410841693;1127.143371038017;1199.3103965220034;14698.706025879423;15171.924140379228;15192.392304307705;15450.381984720485;15502.379904803907;197680;914219;916023;920900;922006;56164.87670246572;56659;56663;56682.716586416806;56700.58248543819;1211.3277131192096;1251.1679732977832;1318.630783380655;1346.5421756602802;1375.642332993429;25118;26221.868890655423;29424.705752942198;30226.395472089996;30668.539971899994;51144;53078.93842123213;53122;53146.4685353157;53345.6663583398;602246;609789;610215;610726.9463652653;610938;25004.874975625007;25082;25393.238202853943;25769.613455797804;25833.87083064648;37907.81046094752;41352.75941721753;43252.351214732436;45269.868253293935;46152.07695845997;32408;36701.81649091737;36913.89258322147;37121.44317835181;37860.24279514339;34166;35682.64317356793;37676.43485347777;38687.41968870525;38946.6105338943;36310.91067267986;37045.259094818495;37442.063948401505;37451;37698;32061;32446.675533244364;34057.829710851285;34199.829000854836;34436.82781586076;24958;25045.74954250434;25106.74893251117;25565.744342557082;25626.743732562434 01:07:15 INFO - PROCESS | 1646 | |2;jquery.html;364.5843738138522;371.78622292182;374.91002159481735;389.06041908790274;399.5784447407983;18982;19892.40322790315;20252.39242822716;20657.27699530517;20878.68681969768;19488;19784.802151978474;20320.39038828834;20380.59238815223;20627;5479.342478902538;5654.434556544334;5666.9433305666935;5864.853378665546;5976.491998180139;5775.884482310351;5920.911186332209;5921.378255283198;5925.170476133345;5933.050711886078;5274.129768588178;5563.471470210324;5752.367239603661;5810;5879.441453061973;5560.888782224353;5772.797952071657;5817.941820581804;5818.429024163453;5819.7963071292725;4917.36074310341;5173.715445650488;5199.220116982445;5200.349956255468;5216.973915130438;12925.806112908314;13175.209487430784;14022.85977140236;23987.640185397235;24571.508569828504;2099.2442720620566;2198.351486311542;2378.1319818266206;2514.660520829701;2515.5220508103403;7638.236176382373;8517.82964340716;9677.854832177452;9787.070228328297;10318.89681103187;1761.445144779403;1785.3751187084547;1836.173721825184;1849.833514983658;1894.8484121270264;93222;116955.83044169535;117961;119257;119435;11302;11415.942920285512;11514.712132196762;11546.884531154581;11739.530418783153;14597.708045839025;14618.780718289234;14620;14647.267636618126;14651.487197948234;8371.95814020926;8462.87305690415;8485.618147183392;8489.787755306166;8684.39209255346;8450.239478446847;8480.53357065355;8562.972443306842;8614.569271536524;8627.352948528885;4440.3561483585345;4514.052049069666;4516.751578663205;4538.523455037173;4626.565764612981;1868.4768264885854;1923.8841471946237;1936.1577713694587;1970.438430040941;1983.4148926066846;690.3027941778839;702.4520873718508;812.6668066092874;828.6975254032235;966.468442356701;19328.71006934897;19888.403347899588;20610.73998453818;20696.79303206979;21045;823.6952327638812;916.2578311567652;945.1068740040596;945.7068308824287;1007.6120693532896;10500.00249976251;10940.288881222696;10966.890331096587;10974.231803773659;11003.009729124416;604939;609606;621936;629924;644577;90373.54813226023;106485.46757266164;107279;112893;125908;885.6191837509804;940.9952950235205;968.5205823117602;978.188103873783;982.6167794560128;18547.90726046415;18763.437096887115;19166.33505769974;19173.32893348726;19183;42782.57217427786;45537;52839.471605283456;53648;53942.73028634832;60278;64256;71556;73898.78303650899;76843.84734229103;6837.965810171117;7902.84194316099;7956.363490920829;8073.273405393543;8240.917590824016;10369.74075648115;12669;12931;13041.478340866259;13118.016148788689;10786.191035672198;10815.783684326427;10861.945690271497;10974.670759877217;11014.50434730423;9180.668803023658;9662.806743864943;10855.185861060294;10882.61910833117;11328.32030078198;9396.577154028222;9694.951525242328;10519.89480105189;10790.89209107899;10846.566137354734;8051.074126475296;8093;8110.878336824834;8356.247937685637;8390.70632527883;9137.5888085035;10799.892001079888;10829.133669306279;10983.670489885317;11119.888801111885 01:07:15 INFO - PROCESS | 1646 | |3;mootools.html;1445.7903603977425;1489.7244009858173;1620.9594760130994;1807.1235450806348;1818.4544636609014;8111.553864537448;8532.23209911108;8533.829323413527;8551.87172192416;8569.614367353468;7484.887726684091;7714.997050383442;7732.845343093135;8078.071021832491;8350.331973442126;2288.9084436622516;2339.497008143249;2341.356127065054;2342.1919437793936;2342.882855857209;2160.7731188225166;2242.416971587391;2357.5520651076326;2362.11420717231;2374.4063984004;2287.6797248385237;2287.794098531132;2312.387217387394;2312.9768702313013;2313.363824948144;2294.254367330616;2317.5364927014666;2319.0839618350824;2320.3619004773655;2326.9418264543524;2299.5975704251855;2339.0760649543504;2341.953160936772;2345.4957184205477;2351.318117745852;8318.292945099674;8515.659373625047;8529.232369086812;8529.57352132391;8559;5045.117104506697;5212.322398088262;5222.686638801684;5223.921641175385;5226.189940559181;3259.315543735818;3320.8007519548664;3573.213892943574;3582.7492075554724;3588.102974256436;1045.4302405189114;1059.7774467361862;1078.1859695929602;1090.2749671468464;1095.7534554725203;10857.022867941843;10998.230123891331;11133.387663678495;11148.554057837677;11151.498182581803;3544.4151714967256;4552.567506086916;4649.488556258809;4656.5110663379855;4693.835715749932;9470.763230919281;9479.526023698787;9482;9483.099105584959;9487.335886488017;5160.225966105039;5164.612654050961;5164.690118592896;5175.06848767218;5178.275041494194;3660.4692319613505;3859.6912247020173;4756.643251756063;4761.880952976203;4770.856874293777;3767.3595488766427;3773.6792372648633;3830.6552410283207;3874.1089549403796;3879.1078052048188;1085.4355735017905;1254.347739175642;1255.7614053330021;1266.8416447944007;1270.6632742323293;773.7833406646148;862.4738909265343;871.6600525794945;888.1784349476708;899.2221728205055;5458.8908221835345;5604.271444712162;5700.800471983461;5713.714314284352;5934.673592952343;797.7809538512076;943.9008904065112;961.8124465729115;967.3615413826841;993.8807343118727;3609.7473176877365;3755.8122093895195;3769.8869033929027;3857.614238576176;3864.497615310048;9862.309638325392;10309.484525773682;10368.326058806153;10372.429516376671;10378.896211037943;9561.187299079656;9982.151517121127;10225.897741022643;10230.693079207636;10282.28024038325;7600.695972161052;7918.247766462062;7922.128565857751;7923.524588524707;7929.207079292025;5394.615365421626;5416.485433883775;5515.4760297771645;5579.414161512983;5655.943440565542;7706.7687969361;9398.718038458857;9458.054194580487;9465.384749991228;9563;8845.734627961172;9456.574454149442;9589;9674.70975870725;9703.514824258618;6478.967605162133;6722.295501902078;7693.384529237759;7830.33442157423;7996.92003079985;8024.719134830253;8119.350451963947;8119.431639785158;8131.308838748772;8138.83722325562;6977.686004129725;7031.648417579203;7050.647467626495;7207.531510451804;7286.7813965579;6982.581045137308;7004.404625606677;7052.576845389088;7158.3557479824985;7163.7492687755675;6613.933860661525;6646.2356828965285;6709.194896612437;6716.496262780409;6726.293739157318;4800.591949684316;4817.566419022305;4834.661573689808;4836.250381190921;4843.176659967743;3980.203959208228;4733.242681170997;4770.356001939694;4939.97530012362;5009.749512524287 01:07:15 INFO - PROCESS | 1646 | |4;prototype.html;299.5357196345664;302.20218622835705;308.56492345792424;314.317930091701;316.2552189593509;5116.974415127919;5320.7871685132595;5342.171963345678;5349.7592608332625;5350.545203657689;4656.511066338028;4818.771213340593;5019.974900125503;5106.744662766865;5226.863881670995;4030.662215914777;4031.2945234583976;4037.313656678372;4039.3738970459626;4044.676425885922;3331.9833400832963;3782.905427364324;3877.418387241908;4005.399190121476;4051.6021972419617;3979.8408063677416;4028.858989935353;4030.717849750519;4044;4052.7770972596495;3313.950290745641;3322.435186018383;4009.6792256619547;4017.216642754664;4020.46710572339;3303.487959366302;3405.4381027130494;3455.533502977091;3523.453864650983;3538.327717733622;5058.772355244023;5107.284980102789;5108.514691104377;5117.923231151536;5122.590192784568;4544.977275113603;4547.794834368895;4558.293464513005;4575.977120114411;4622.399088118556;4615.884602884921;4821.662483626147;5337.439568845293;5446.2919820423485;5543.36251331094;3191.361727654455;3803.2963901678277;3803.562590302123;3814.370628846237;3821.363259351039;5058.887044850164;5444.537214336786;5794.246747922743;5802.767889284423;5859.736311865976;3627.91394947906;3829.6744776694295;3831.616838316146;3834.175652234783;3844.4809950656863;3863.5943225961432;3890.8832735017995;3898.2983063048923;3934.8392224293902;3942.3889297158985;3823.770573765583;4008.939865902014;4167.770772607536;4531.728096314232;4562.794674239667;4647.233206520954;4968.503149685075;5296.735163241822;5513.889722205534;5574.414686457944;1186.887245711656;1188.5780547905542;1258.3708145927037;1261.9810702839466;1270.2568997136777;752.2439947852482;835.4360806455618;845.492704377381;865.4936861935712;867.2524862402264;4920.581750551171;4930.630202734809;4962.3624204012585;4964.404271487445;4970.8318545141865;4759.2396410082065;5185.770118848931;5214.270002199695;5380.650257733235;5403.918941215885;4356.934645980313;4361.760103194291;4403.405540252089;4913.926291105637;4959.925601115914;2334.976650233476;2590.313566904772;2742.5337692592307;2781.652293463317;2802.4815409149373;4800.4719480857075;4889.850885042012;5016.2224855147515;5498.422665620053;5557.527610153102;4228.429162063082;4500.819967201341;5363.410024897258;5372.468846378842;5495.972520137454;4858.58702010333;4997;5392.784288628411;5462.289902312674;5508.476694713996;4162.231051803032;4683.508231635629;4908.680935739309;5100.795968161232;5253.816116435906;4362.411074504901;5270.49930256625;5274.3891773570795;5278.865044015555;5690.951257547363;5443.700596467233;5929.703514824155;5959.255093113361;5962.97018514922;5989.813717992988;3412.9249286474683;3623.8912832615065;3712.294664013829;3811.2377524495764;3890.4747859038666;5227.398849132246;5430.588047107702;5581.5255703265675;5584.682356821873;5745.885082298414;4792.640551958687;5539.7784088636;5557.221389305348;5603.187537807076;5635.549156067423;4700.8119675213975;4762.690425122356;5259.60552958522;5449.264349312792;5607.691576963306;4541.409616749801;5393.465071416428;5472.288602481652;5486.6982315973;5505.614606977473;4647.16351056813;5094.923576146287;5612.831615051555;5612.85967850807;5663.205652212945;4482.393789023583;5240.318758561515;5423.725424525154;5511.476409741068;5515.393306736336 01:07:15 INFO - PROCESS | 1646 | |5;yui.html;656.8817612829695;684.8664510420467;689.1626673591585;706.8762966480865;769.3844924060753;14262.21557814321;14931.402743890258;14939.551813445587;14947.775783363259;14955.327010284536;13109.410076546554;13236.404361803718;13247.403866825993;13251.403686834092;13282.468701251939;652.6051738698067;672.445232683036;675.2100042949736;796.8605494038549;805.3476683886041;795.6340083561539;814.6008455856618;821.1296026212204;822.8683410654296;826.8387664405443;829.4649950781742;853.7950891785989;861.2291998661194;862.2756884217256;864.6066039951806;685.7839780469186;708.1855865754424;856.8286342731478;856.8457677618027;858.5835869603237;619.1023016625866;632.6899819088613;635.1076737184218;636.1825054804553;639.4596565901805;14016.22910739909;14100.153990760587;14122.36449359771;14131.081479703786;14190.716185676332;12843.807342889864;12985.610431687064;13092.803607945889;13118.212907225598;13214;10903.945480272549;11395.715107122305;11816.586419475363;11827.694575735983;12091.879081209163;4326.6754993375625;4371.934420983688;4390.824367025315;4529.388532548097;4551.795169217393;92700;93378;93413.06586934111;94297.52851235769;96347;9040.276777857756;9640.469774162348;9702.223822094216;10434.895651043544;10679.572817087384;17646.735298970525;19366.515837103903;19385.612287754167;19676.90161549183;19698.113584888713;6581.835454113685;7565.243475652391;7725.806854828675;7788.4548081634875;7948.364130869517;4141.79291035447;4147.730397524151;4304.612584867315;4376.059147283349;4501;3382.4080785862375;3439.570053743282;3485.215826439031;3488.232588830453;3493.3886569850683;554.6394843351853;570.8401647538695;639.1850390751736;642.2902692524693;645.2676212498848;588.5350573047244;643.0836058616453;643.1381948189448;653.761377097356;655.4363247607054;7500.737474188377;8043.718469853527;8084.514929104273;8531.402801803813;8971.416857904216;621.8258887511504;627.5450298533526;647.675503595149;664.1697877652933;664.8105289992329;3934.9803250984132;4239.845031137444;4436.933445998313;4466.531014243523;4472.44094488189;80439.19560804317;81312.59343703362;88354.55822720974;95540.17839286351;107607.38588921173;76890.84663730049;105066.47466762617;107689.46155269173;109864.4506777461;114624;64841;65619.70331038487;81704.59147704224;82896.58551707203;85148.57425712956;9390.624375024923;9632;9636.46999415011;9811.313208075366;9846.310758246855;50108.74945625249;50680.453204225465;52063.36202374936;53670.73164634152;54439.72780136074;54827.451725482235;56075.31774046785;77484;78208.60895695485;80866;10012.249081319076;11824.527018919147;11867.228630139014;11909.702257443632;12309.753804923672;13384.665383365103;13480.595582132551;13512.391942362814;13544.051916366154;13813.447462101403;11129.777404452028;11418.429078546205;12012.879871201176;12458.377081146087;12673.556425525063;11779.99480176744;11995.220310679779;13016.804747928978;13046.217226966412;13304.933475332562;11760.294382337088;12345.691357716129;12440.626781196579;12545;12546.435410406371;10580.841287380541;10668.146548276274;11034.613788517685;11262.436878156223;11637.418129093681;10020.348677336242;10038.849417258893;10622.84065738999;10667.839982400425;10692.021975934165 01:07:15 INFO - PROCESS | 1646 | __end_tp_report 01:07:15 INFO - PROCESS | 1646 | __start_cc_report 01:07:15 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2938 01:07:15 INFO - PROCESS | 1646 | __end_cc_report 01:07:15 INFO - PROCESS | 1646 | __startTimestamp1480151235283__endTimestamp 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | ------- Summary: start ------- 01:07:15 INFO - PROCESS | 1646 | Number of tests: 6 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5733.38 Median:5878.50 stddev:651.08 (11.1%) stddev-sans-first:624.94 01:07:15 INFO - PROCESS | 1646 | Values: 3319.7 3351.1 3382.5 3395.2 3432.2 4229.9 5028.2 5723.1 5742.5 5785.7 5615.6 5703.8 5717.3 5724.6 5756.6 5182.8 5209.0 5222.5 5260.5 5264.1 5145.3 5190.2 5202.1 5311.7 5311.7 5213.9 5226.7 5242.4 5274.8 5293.3 5224.4 5259.6 5268.3 5292.6 5321.1 5168.3 5286.9 5290.0 5292.2 5292.4 5581.4 5629.0 5646.5 5652.4 5740.5 6187.7 6260.2 6267.4 6278.2 6296.8 6317.2 6326.6 6332.2 6351.8 6376.9 5689.2 5756.8 5763.7 5804.2 5829.8 6235.6 6261.0 6268.0 6272.2 6293.7 5532.3 6183.1 6190.2 6203.9 6209.7 6122.9 6148.2 6271.4 6303.9 6317.7 6129.7 6151.0 6160.1 6248.2 6249.5 6262.6 6272.9 6278.5 6285.5 6287.7 4865.8 4938.0 4961.5 4964.1 4967.5 4364.0 4390.3 4420.2 4457.6 4470.0 5766.0 5815.5 5846.8 5895.6 5909.7 6252.1 6288.8 6298.3 6315.6 6320.5 5640.2 5760.6 5858.4 5869.3 5896.9 4577.5 5327.0 5330.1 5348.1 5372.6 6274.2 6275.5 6297.3 6299.1 6307.7 6178.0 6295.1 6304.9 6314.6 6318.7 6138.6 6195.6 6204.6 6216.1 6241.0 6176.4 6213.9 6214.8 6221.8 6228.5 6099.3 6222.2 6282.4 6305.1 6329.0 5938.6 6003.0 6004.5 6114.8 6150.6 6020.3 6042.2 6043.3 6043.5 6045.6 5850.6 5858.0 5858.4 5863.7 5878.5 5958.1 6008.8 6021.7 6030.2 6036.7 5829.2 5844.7 5845.4 5845.7 5846.3 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:74185.67 Median:36979.58 stddev:177287.61 (479.4%) stddev-sans-first:177698.55 01:07:15 INFO - PROCESS | 1646 | Values: 465.7 501.1 526.1 529.2 559.9 28652.6 38410.4 39367.6 39539.6 41402.0 40400.4 40819.0 40874.0 40884.2 40903.4 8600.0 9299.1 9479.9 9485.5 9491.7 8568.9 8700.9 8734.7 8739.4 11087.8 11131.2 11177.4 11235.4 11253.4 11265.4 11136.7 11236.4 11244.9 11246.5 11251.4 8490.2 8755.2 10894.0 11107.9 11184.6 37167.0 38905.8 38910.8 38976.8 38999.6 3119.9 3174.5 3189.6 3195.1 3248.2 29254.3 29876.0 30023.0 30200.1 30228.7 3052.4 3115.7 3131.8 3227.6 3268.5 488037.0 489327.6 490648.0 491325.0 491381.0 58384.0 58751.0 58777.4 58807.1 58875.0 60529.4 60686.0 60720.7 60772.0 60778.4 15807.1 16320.8 16325.8 16335.3 16342.6 12600.4 12647.6 12687.4 12687.6 12739.0 14424.1 14804.4 14810.6 16049.0 16275.7 6443.6 6525.1 6570.4 6576.3 6594.2 884.0 953.6 974.3 974.4 1050.1 28468.3 29088.7 29253.4 29916.7 30108.4 848.6 970.8 1073.4 1127.1 1199.3 14698.7 15171.9 15192.4 15450.4 15502.4 197680.0 914219.0 916023.0 920900.0 922006.0 56164.9 56659.0 56663.0 56682.7 56700.6 1211.3 1251.2 1318.6 1346.5 1375.6 25118.0 26221.9 29424.7 30226.4 30668.5 51144.0 53078.9 53122.0 53146.5 53345.7 602246.0 609789.0 610215.0 610726.9 610938.0 25004.9 25082.0 25393.2 25769.6 25833.9 37907.8 41352.8 43252.4 45269.9 46152.1 32408.0 36701.8 36913.9 37121.4 37860.2 34166.0 35682.6 37676.4 38687.4 38946.6 36310.9 37045.3 37442.1 37451.0 37698.0 32061.0 32446.7 34057.8 34199.8 34436.8 24958.0 25045.7 25106.7 25565.7 25626.7 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:34338.11 Median:23611.46 stddev:103115.30 (436.7%) stddev-sans-first:103373.01 01:07:15 INFO - PROCESS | 1646 | Values: 364.6 371.8 374.9 389.1 399.6 18982.0 19892.4 20252.4 20657.3 20878.7 19488.0 19784.8 20320.4 20380.6 20627.0 5479.3 5654.4 5666.9 5864.9 5976.5 5775.9 5920.9 5921.4 5925.2 5933.1 5274.1 5563.5 5752.4 5810.0 5879.4 5560.9 5772.8 5817.9 5818.4 5819.8 4917.4 5173.7 5199.2 5200.3 5217.0 12925.8 13175.2 14022.9 23987.6 24571.5 2099.2 2198.4 2378.1 2514.7 2515.5 7638.2 8517.8 9677.9 9787.1 10318.9 1761.4 1785.4 1836.2 1849.8 1894.8 93222.0 116955.8 117961.0 119257.0 119435.0 11302.0 11415.9 11514.7 11546.9 11739.5 14597.7 14618.8 14620.0 14647.3 14651.5 8372.0 8462.9 8485.6 8489.8 8684.4 8450.2 8480.5 8563.0 8614.6 8627.4 4440.4 4514.1 4516.8 4538.5 4626.6 1868.5 1923.9 1936.2 1970.4 1983.4 690.3 702.5 812.7 828.7 966.5 19328.7 19888.4 20610.7 20696.8 21045.0 823.7 916.3 945.1 945.7 1007.6 10500.0 10940.3 10966.9 10974.2 11003.0 604939.0 609606.0 621936.0 629924.0 644577.0 90373.5 106485.5 107279.0 112893.0 125908.0 885.6 941.0 968.5 978.2 982.6 18547.9 18763.4 19166.3 19173.3 19183.0 42782.6 45537.0 52839.5 53648.0 53942.7 60278.0 64256.0 71556.0 73898.8 76843.8 6838.0 7902.8 7956.4 8073.3 8240.9 10369.7 12669.0 12931.0 13041.5 13118.0 10786.2 10815.8 10861.9 10974.7 11014.5 9180.7 9662.8 10855.2 10882.6 11328.3 9396.6 9695.0 10519.9 10790.9 10846.6 8051.1 8093.0 8110.9 8356.2 8390.7 9137.6 10799.9 10829.1 10983.7 11119.9 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5462.27 Median:5169.88 stddev:3017.32 (58.4%) stddev-sans-first:3010.68 01:07:15 INFO - PROCESS | 1646 | Values: 1445.8 1489.7 1621.0 1807.1 1818.5 8111.6 8532.2 8533.8 8551.9 8569.6 7484.9 7715.0 7732.8 8078.1 8350.3 2288.9 2339.5 2341.4 2342.2 2342.9 2160.8 2242.4 2357.6 2362.1 2374.4 2287.7 2287.8 2312.4 2313.0 2313.4 2294.3 2317.5 2319.1 2320.4 2326.9 2299.6 2339.1 2342.0 2345.5 2351.3 8318.3 8515.7 8529.2 8529.6 8559.0 5045.1 5212.3 5222.7 5223.9 5226.2 3259.3 3320.8 3573.2 3582.7 3588.1 1045.4 1059.8 1078.2 1090.3 1095.8 10857.0 10998.2 11133.4 11148.6 11151.5 3544.4 4552.6 4649.5 4656.5 4693.8 9470.8 9479.5 9482.0 9483.1 9487.3 5160.2 5164.6 5164.7 5175.1 5178.3 3660.5 3859.7 4756.6 4761.9 4770.9 3767.4 3773.7 3830.7 3874.1 3879.1 1085.4 1254.3 1255.8 1266.8 1270.7 773.8 862.5 871.7 888.2 899.2 5458.9 5604.3 5700.8 5713.7 5934.7 797.8 943.9 961.8 967.4 993.9 3609.7 3755.8 3769.9 3857.6 3864.5 9862.3 10309.5 10368.3 10372.4 10378.9 9561.2 9982.2 10225.9 10230.7 10282.3 7600.7 7918.2 7922.1 7923.5 7929.2 5394.6 5416.5 5515.5 5579.4 5655.9 7706.8 9398.7 9458.1 9465.4 9563.0 8845.7 9456.6 9589.0 9674.7 9703.5 6479.0 6722.3 7693.4 7830.3 7996.9 8024.7 8119.4 8119.4 8131.3 8138.8 6977.7 7031.6 7050.6 7207.5 7286.8 6982.6 7004.4 7052.6 7158.4 7163.7 6613.9 6646.2 6709.2 6716.5 6726.3 4800.6 4817.6 4834.7 4836.3 4843.2 3980.2 4733.2 4770.4 4940.0 5009.7 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4365.92 Median:4840.12 stddev:1332.56 (27.5%) stddev-sans-first:1300.88 01:07:15 INFO - PROCESS | 1646 | Values: 299.5 302.2 308.6 314.3 316.3 5117.0 5320.8 5342.2 5349.8 5350.5 4656.5 4818.8 5020.0 5106.7 5226.9 4030.7 4031.3 4037.3 4039.4 4044.7 3332.0 3782.9 3877.4 4005.4 4051.6 3979.8 4028.9 4030.7 4044.0 4052.8 3314.0 3322.4 4009.7 4017.2 4020.5 3303.5 3405.4 3455.5 3523.5 3538.3 5058.8 5107.3 5108.5 5117.9 5122.6 4545.0 4547.8 4558.3 4576.0 4622.4 4615.9 4821.7 5337.4 5446.3 5543.4 3191.4 3803.3 3803.6 3814.4 3821.4 5058.9 5444.5 5794.2 5802.8 5859.7 3627.9 3829.7 3831.6 3834.2 3844.5 3863.6 3890.9 3898.3 3934.8 3942.4 3823.8 4008.9 4167.8 4531.7 4562.8 4647.2 4968.5 5296.7 5513.9 5574.4 1186.9 1188.6 1258.4 1262.0 1270.3 752.2 835.4 845.5 865.5 867.3 4920.6 4930.6 4962.4 4964.4 4970.8 4759.2 5185.8 5214.3 5380.7 5403.9 4356.9 4361.8 4403.4 4913.9 4959.9 2335.0 2590.3 2742.5 2781.7 2802.5 4800.5 4889.9 5016.2 5498.4 5557.5 4228.4 4500.8 5363.4 5372.5 5496.0 4858.6 4997.0 5392.8 5462.3 5508.5 4162.2 4683.5 4908.7 5100.8 5253.8 4362.4 5270.5 5274.4 5278.9 5691.0 5443.7 5929.7 5959.3 5963.0 5989.8 3412.9 3623.9 3712.3 3811.2 3890.5 5227.4 5430.6 5581.5 5584.7 5745.9 4792.6 5539.8 5557.2 5603.2 5635.5 4700.8 4762.7 5259.6 5449.3 5607.7 4541.4 5393.5 5472.3 5486.7 5505.6 4647.2 5094.9 5612.8 5612.9 5663.2 4482.4 5240.3 5423.7 5511.5 5515.4 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:19802.20 Median:4540.59 stddev:28951.31 (637.6%) stddev-sans-first:28996.83 01:07:15 INFO - PROCESS | 1646 | Values: 656.9 684.9 689.2 706.9 769.4 14262.2 14931.4 14939.6 14947.8 14955.3 13109.4 13236.4 13247.4 13251.4 13282.5 652.6 672.4 675.2 796.9 805.3 795.6 814.6 821.1 822.9 826.8 829.5 853.8 861.2 862.3 864.6 685.8 708.2 856.8 856.8 858.6 619.1 632.7 635.1 636.2 639.5 14016.2 14100.2 14122.4 14131.1 14190.7 12843.8 12985.6 13092.8 13118.2 13214.0 10903.9 11395.7 11816.6 11827.7 12091.9 4326.7 4371.9 4390.8 4529.4 4551.8 92700.0 93378.0 93413.1 94297.5 96347.0 9040.3 9640.5 9702.2 10434.9 10679.6 17646.7 19366.5 19385.6 19676.9 19698.1 6581.8 7565.2 7725.8 7788.5 7948.4 4141.8 4147.7 4304.6 4376.1 4501.0 3382.4 3439.6 3485.2 3488.2 3493.4 554.6 570.8 639.2 642.3 645.3 588.5 643.1 643.1 653.8 655.4 7500.7 8043.7 8084.5 8531.4 8971.4 621.8 627.5 647.7 664.2 664.8 3935.0 4239.8 4436.9 4466.5 4472.4 80439.2 81312.6 88354.6 95540.2 107607.4 76890.8 105066.5 107689.5 109864.5 114624.0 64841.0 65619.7 81704.6 82896.6 85148.6 9390.6 9632.0 9636.5 9811.3 9846.3 50108.7 50680.5 52063.4 53670.7 54439.7 54827.5 56075.3 77484.0 78208.6 80866.0 10012.2 11824.5 11867.2 11909.7 12309.8 13384.7 13480.6 13512.4 13544.1 13813.4 11129.8 11418.4 12012.9 12458.4 12673.6 11780.0 11995.2 13016.8 13046.2 13304.9 11760.3 12345.7 12440.6 12545.0 12546.4 10580.8 10668.1 11034.6 11262.4 11637.4 10020.3 10038.8 10622.8 10667.8 10692.0 01:07:15 INFO - PROCESS | 1646 | -------- Summary: end -------- 01:07:15 INFO - PROCESS | 1646 | 01:07:15 INFO - TEST-INFO | 1646: exit 0 01:07:15 INFO - TEST-OK | dromaeo_css | took 1120045ms 01:07:15 INFO - TEST-START | kraken 01:07:15 INFO - operating with platform_type : mac_ 01:07:15 INFO - Initialising browser for kraken test... 01:07:15 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpUZzgDB/profile http://localhost:49248/getInfo.html) 01:07:18 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 01:07:18 INFO - PROCESS | 1652 | colorDepth:24 01:07:18 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 01:07:18 INFO - PROCESS | 1652 | __metrics 01:07:18 INFO - PROCESS | 1652 | 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" 01:07:18 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:07:19 INFO - TEST-INFO | 1652: exit 0 01:07:19 INFO - Browser initialized. 01:07:19 INFO - Running cycle 1/1 for kraken test... 01:07:19 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpUZzgDB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:07:31 INFO - PROCESS | 1653 | RSS: Main: 223375360 01:07:31 INFO - PROCESS | 1653 | 01:07:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 01:07:35 INFO - PROCESS | 1653 | RSS: Main: 272756736 01:07:35 INFO - PROCESS | 1653 | 01:07:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 01:07:38 INFO - PROCESS | 1653 | RSS: Main: 282750976 01:07:38 INFO - PROCESS | 1653 | 01:07:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 01:07:42 INFO - PROCESS | 1653 | RSS: Main: 380125184 01:07:42 INFO - PROCESS | 1653 | 01:07:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 01:07:45 INFO - PROCESS | 1653 | RSS: Main: 283488256 01:07:45 INFO - PROCESS | 1653 | 01:07:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:07:47 INFO - PROCESS | 1653 | RSS: Main: 284766208 01:07:47 INFO - PROCESS | 1653 | 01:07:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 01:07:51 INFO - PROCESS | 1653 | RSS: Main: 325046272 01:07:51 INFO - PROCESS | 1653 | 01:07:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 01:07:54 INFO - PROCESS | 1653 | RSS: Main: 325820416 01:07:54 INFO - PROCESS | 1653 | 01:07:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 01:07:58 INFO - PROCESS | 1653 | RSS: Main: 323878912 01:07:58 INFO - PROCESS | 1653 | 01:07:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:08:00 INFO - PROCESS | 1653 | RSS: Main: 301748224 01:08:00 INFO - PROCESS | 1653 | 01:08:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:08:02 INFO - PROCESS | 1653 | RSS: Main: 301309952 01:08:02 INFO - PROCESS | 1653 | 01:08:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:08:05 INFO - PROCESS | 1653 | RSS: Main: 303624192 01:08:05 INFO - PROCESS | 1653 | 01:08:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:08:09 INFO - PROCESS | 1653 | RSS: Main: 314548224 01:08:09 INFO - PROCESS | 1653 | 01:08:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:08:12 INFO - PROCESS | 1653 | RSS: Main: 314298368 01:08:12 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 01:08:14 INFO - PROCESS | 1653 | RSS: Main: 357945344 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | __start_tp_report 01:08:14 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 01:08:14 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 01:08:14 INFO - PROCESS | 1653 | |i|pagename|runs| 01:08:14 INFO - PROCESS | 1653 | |0;ai-astar;115;113;113;114;113;115;114;114;113;114 01:08:14 INFO - PROCESS | 1653 | |1;audio-beat-detection;170;138;112;172;99;133;103;136;174;138 01:08:14 INFO - PROCESS | 1653 | |2;audio-dft;184;184;184;182;183;182;184;186;184;185 01:08:14 INFO - PROCESS | 1653 | |3;audio-fft;80;58;60;90;60;59;59;58;83;74 01:08:14 INFO - PROCESS | 1653 | |4;audio-oscillator;93;96;127;92;68;69;89;66;68;92 01:08:14 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;86;84;88;85;87;84;88;84;86;83 01:08:14 INFO - PROCESS | 1653 | |6;imaging-darkroom;101;93;120;93;93;93;93;93;97;140 01:08:14 INFO - PROCESS | 1653 | |7;imaging-desaturate;82;83;79;80;82;99;142;102;81;92 01:08:14 INFO - PROCESS | 1653 | |8;json-parse-financial;54;53;53;55;57;56;55;56;57;56 01:08:14 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;63;72;78;68;73;73;70;69;70;70 01:08:14 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;85;85;84;85;85;84;84;87;84;88 01:08:14 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;146;146;148;146;151;159;158;144;191;158 01:08:14 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;167;165;168;165;167;166;165;169;167;166 01:08:14 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;67;66;68;66;67;65;65;65;65;65 01:08:14 INFO - PROCESS | 1653 | __end_tp_report 01:08:14 INFO - PROCESS | 1653 | __start_cc_report 01:08:14 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1973 01:08:14 INFO - PROCESS | 1653 | __end_cc_report 01:08:14 INFO - PROCESS | 1653 | __startTimestamp1480151294826__endTimestamp 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | ------- Summary: start ------- 01:08:14 INFO - PROCESS | 1653 | Number of tests: 14 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 01:08:14 INFO - PROCESS | 1653 | Values: 115.0 113.0 113.0 114.0 113.0 115.0 114.0 114.0 113.0 114.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:154.00 stddev:27.74 (18.0%) stddev-sans-first:26.81 01:08:14 INFO - PROCESS | 1653 | Values: 170.0 138.0 112.0 172.0 99.0 133.0 103.0 136.0 174.0 138.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.23 (0.7%) stddev-sans-first:1.30 01:08:14 INFO - PROCESS | 1653 | Values: 184.0 184.0 184.0 182.0 183.0 182.0 184.0 186.0 184.0 185.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:68.10 Median:67.00 stddev:12.38 (18.5%) stddev-sans-first:12.36 01:08:14 INFO - PROCESS | 1653 | Values: 80.0 58.0 60.0 90.0 60.0 59.0 59.0 58.0 83.0 74.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:90.50 stddev:19.00 (21.0%) stddev-sans-first:19.98 01:08:14 INFO - PROCESS | 1653 | Values: 93.0 96.0 127.0 92.0 68.0 69.0 89.0 66.0 68.0 92.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.00 stddev:1.78 (2.1%) stddev-sans-first:1.88 01:08:14 INFO - PROCESS | 1653 | Values: 86.0 84.0 88.0 85.0 87.0 84.0 88.0 84.0 86.0 83.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:93.00 stddev:15.91 (17.1%) stddev-sans-first:16.87 01:08:14 INFO - PROCESS | 1653 | Values: 101.0 93.0 120.0 93.0 93.0 93.0 93.0 93.0 97.0 140.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:82.00 stddev:19.33 (23.6%) stddev-sans-first:20.15 01:08:14 INFO - PROCESS | 1653 | Values: 82.0 83.0 79.0 80.0 82.0 99.0 142.0 102.0 81.0 92.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.48 (2.6%) stddev-sans-first:1.50 01:08:14 INFO - PROCESS | 1653 | Values: 54.0 53.0 53.0 55.0 57.0 56.0 55.0 56.0 57.0 56.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:3.89 (5.5%) stddev-sans-first:3.00 01:08:14 INFO - PROCESS | 1653 | Values: 63.0 72.0 78.0 68.0 73.0 73.0 70.0 69.0 70.0 70.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.00 stddev:1.37 (1.6%) stddev-sans-first:1.45 01:08:14 INFO - PROCESS | 1653 | Values: 85.0 85.0 84.0 85.0 85.0 84.0 84.0 87.0 84.0 88.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:154.70 Median:154.50 stddev:13.98 (9.0%) stddev-sans-first:14.47 01:08:14 INFO - PROCESS | 1653 | Values: 146.0 146.0 148.0 146.0 151.0 159.0 158.0 144.0 191.0 158.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:167.00 stddev:1.35 (0.8%) stddev-sans-first:1.42 01:08:14 INFO - PROCESS | 1653 | Values: 167.0 165.0 168.0 165.0 167.0 166.0 165.0 169.0 167.0 166.0 01:08:14 INFO - PROCESS | 1653 | 01:08:14 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:1.09 01:08:14 INFO - PROCESS | 1653 | Values: 67.0 66.0 68.0 66.0 67.0 65.0 65.0 65.0 65.0 65.0 01:08:14 INFO - PROCESS | 1653 | -------- Summary: end -------- 01:08:14 INFO - PROCESS | 1653 | 01:08:15 INFO - TEST-INFO | 1653: exit 0 01:08:16 INFO - TEST-OK | kraken | took 60115ms 01:08:16 INFO - SUITE-END | took 1180s 01:08:16 INFO - Completed test suite (00:19:40) 01:08:16 INFO - javascript benchmark 01:08:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.7344212463004, 3351.0617027232374, 3382.543356646852, 3395.1681837949704, 3432.1934345428836, 4229.900225941258, 5028.170351891933, 5723.084306510959, 5742.54059675225, 5785.681787501691, 5615.578831587637, 5703.77184912603, 5717.285339332583, 5724.570657200706, 5756.625819321752, 5182.766775495103, 5208.958208358325, 5222.503862133091, 5260.488154204222, 5264.105102132647, 5145.253938178962, 5190.195519694454, 5202.06362854686, 5311.654742441749, 5311.707856067915, 5213.895722085556, 5226.738663066851, 5242.449542798008, 5274.815381461631, 5293.311869456965, 5224.373075230997, 5259.579233661298, 5268.341457317835, 5292.629515933886, 5321.148616221386, 5168.302279192336, 5286.9471305287225, 5289.973550132263, 5292.153255479144, 5292.391374991887, 5581.386047534768, 5629.043062679354, 5646.463585959368, 5652.378238393773, 5740.54075673945, 6187.721552530147, 6260.15487909131, 6267.4045965633195, 6278.215223097113, 6296.811095667137, 6317.210348706411, 6326.557141000132, 6332.240131184288, 6351.777687780952, 6376.904346434807, 5689.2319536862, 5756.798512052058, 5763.682997435181, 5804.245448091763, 5829.8251052468495, 6235.625862448268, 6261.029540421242, 6268.0, 6272.247330320391, 6293.653849038347, 5532.28080349552, 6183.072539119168, 6190.164327815777, 6203.875922481525, 6209.720562574695, 6122.947104152868, 6148.1699970503405, 6271.432141964508, 6303.936960630335, 6317.684115794192, 6129.693515324216, 6151.015837465984, 6160.1375807387, 6248.187735594343, 6249.531285153632, 6262.5929314594405, 6272.905906411407, 6278.46633036197, 6285.528585356116, 6287.717052732638, 4865.829695960624, 4937.975310123426, 4961.454240033593, 4964.106460837084, 4967.453580106186, 4364.039911219526, 4390.319500477431, 4420.248557745191, 4457.576530229623, 4470.0, 5766.0, 5815.534757219412, 5846.766129354864, 5895.557833162444, 5909.674967876808, 6252.093446450239, 6288.8427789304715, 6298.307186209513, 6315.621062736251, 6320.462760665303, 5640.238567793295, 5760.596758226968, 5858.355580886099, 5869.295684517885, 5896.911546326808, 4577.473590537131, 5326.98787230425, 5330.067238233293, 5348.064088784525, 5372.57019438444, 6274.1843560336865, 6275.497960163268, 6297.275813281348, 6299.086632438362, 6307.653079080603, 6178.042403427476, 6295.055741638607, 6304.873902522016, 6314.589551679126, 6318.715657795502, 6138.602983405075, 6195.597286176384, 6204.565680402328, 6216.129741836237, 6240.968795155995, 6176.382361763787, 6213.9067913980425, 6214.844628884314, 6221.751129954751, 6228.4705800007505, 6099.268087829489, 6222.16000839899, 6282.371762823864, 6305.085762564494, 6329.019002054689, 5938.6436813791315, 6003.0, 6004.459598636144, 6114.755409783559, 6150.600210986271, 6020.277566692025, 6042.1540984261355, 6043.3050199227655, 6043.456088952015, 6045.637261764308, 5850.619709718854, 5858.033424485083, 5858.355580886014, 5863.677497737494, 5878.500327472042, 5958.076498142795, 6008.789692360747, 6021.729022193924, 6030.155778191144, 6036.6679832609625, 5829.183914252093, 5844.6785426801935, 5845.444682755132, 5845.736941837543, 5846.327672317738], "value": 5691.739329068609, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.6996237426859, 501.14054396709633, 526.1003683700872, 529.1559961860833, 559.9356074051481, 28652.570211446844, 38410.42384364233, 39367.60632393675, 39539.60460395395, 41402.0, 40400.39399409011, 40819.0, 40874.0, 40884.182316353654, 40903.38644920329, 8600.0, 9299.070092990698, 9479.905200947971, 9485.525723713821, 9491.66779162726, 8568.87146692797, 8700.869486957701, 8734.650613975433, 8739.431936924111, 11087.83368249477, 11131.165162612797, 11177.441127943612, 11235.438228088562, 11253.381064041474, 11265.380404077772, 11136.721581960475, 11236.381998990053, 11244.943775281072, 11246.49390777417, 11251.437428128642, 8490.150984901522, 8755.168259015383, 10894.019538241597, 11107.944460277648, 11184.552617895275, 37167.0, 38905.83282501529, 38910.832675019796, 38976.830695079196, 38999.610003899885, 3119.859606317721, 3174.4920812670093, 3189.5853539039776, 3195.1053704962646, 3248.1717162123746, 29254.26864328409, 29876.0, 30023.0, 30200.09399718012, 30228.697713022808, 3052.4047810676925, 3115.688431156889, 3131.780775345727, 3227.596550431196, 3268.509723541464, 488037.0, 489327.5533622345, 490648.0, 491325.0, 491381.0, 58384.0, 58751.0, 58777.41222587805, 58807.11789323163, 58875.0, 60529.39470605326, 60686.0, 60720.696396517735, 60772.0, 60778.39221607727, 15807.13060781668, 16320.775941804412, 16325.83674163243, 16335.26491307894, 16342.591435213977, 12600.369981501071, 12647.620571382873, 12687.365631718378, 12687.619371418872, 12739.0, 14424.06243594163, 14804.407823686932, 14810.555683329518, 16049.0, 16275.674486510203, 6443.581167224115, 6525.086487891699, 6570.441512471398, 6576.342365763386, 6594.241662208908, 883.9558022098869, 953.5518306395983, 974.2790335151936, 974.3910056214866, 1050.1283934334483, 28468.288292792848, 29088.709112909022, 29253.414931701245, 29916.70083299139, 30108.397832043236, 848.6266042941086, 970.8252514547308, 1073.3774410841693, 1127.143371038017, 1199.3103965220034, 14698.706025879423, 15171.924140379228, 15192.392304307705, 15450.381984720485, 15502.379904803907, 197680.0, 914219.0, 916023.0, 920900.0, 922006.0, 56164.87670246572, 56659.0, 56663.0, 56682.716586416806, 56700.58248543819, 1211.3277131192096, 1251.1679732977832, 1318.630783380655, 1346.5421756602802, 1375.642332993429, 25118.0, 26221.868890655423, 29424.705752942198, 30226.395472089996, 30668.539971899994, 51144.0, 53078.93842123213, 53122.0, 53146.4685353157, 53345.6663583398, 602246.0, 609789.0, 610215.0, 610726.9463652653, 610938.0, 25004.874975625007, 25082.0, 25393.238202853943, 25769.613455797804, 25833.87083064648, 37907.81046094752, 41352.75941721753, 43252.351214732436, 45269.868253293935, 46152.07695845997, 32408.0, 36701.81649091737, 36913.89258322147, 37121.44317835181, 37860.24279514339, 34166.0, 35682.64317356793, 37676.43485347777, 38687.41968870525, 38946.6105338943, 36310.91067267986, 37045.259094818495, 37442.063948401505, 37451.0, 37698.0, 32061.0, 32446.675533244364, 34057.829710851285, 34199.829000854836, 34436.82781586076, 24958.0, 25045.74954250434, 25106.74893251117, 25565.744342557082, 25626.743732562434], "value": 19676.13355035311, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.5843738138522, 371.78622292182, 374.91002159481735, 389.06041908790274, 399.5784447407983, 18982.0, 19892.40322790315, 20252.39242822716, 20657.27699530517, 20878.68681969768, 19488.0, 19784.802151978474, 20320.39038828834, 20380.59238815223, 20627.0, 5479.342478902538, 5654.434556544334, 5666.9433305666935, 5864.853378665546, 5976.491998180139, 5775.884482310351, 5920.911186332209, 5921.378255283198, 5925.170476133345, 5933.050711886078, 5274.129768588178, 5563.471470210324, 5752.367239603661, 5810.0, 5879.441453061973, 5560.888782224353, 5772.797952071657, 5817.941820581804, 5818.429024163453, 5819.7963071292725, 4917.36074310341, 5173.715445650488, 5199.220116982445, 5200.349956255468, 5216.973915130438, 12925.806112908314, 13175.209487430784, 14022.85977140236, 23987.640185397235, 24571.508569828504, 2099.2442720620566, 2198.351486311542, 2378.1319818266206, 2514.660520829701, 2515.5220508103403, 7638.236176382373, 8517.82964340716, 9677.854832177452, 9787.070228328297, 10318.89681103187, 1761.445144779403, 1785.3751187084547, 1836.173721825184, 1849.833514983658, 1894.8484121270264, 93222.0, 116955.83044169535, 117961.0, 119257.0, 119435.0, 11302.0, 11415.942920285512, 11514.712132196762, 11546.884531154581, 11739.530418783153, 14597.708045839025, 14618.780718289234, 14620.0, 14647.267636618126, 14651.487197948234, 8371.95814020926, 8462.87305690415, 8485.618147183392, 8489.787755306166, 8684.39209255346, 8450.239478446847, 8480.53357065355, 8562.972443306842, 8614.569271536524, 8627.352948528885, 4440.3561483585345, 4514.052049069666, 4516.751578663205, 4538.523455037173, 4626.565764612981, 1868.4768264885854, 1923.8841471946237, 1936.1577713694587, 1970.438430040941, 1983.4148926066846, 690.3027941778839, 702.4520873718508, 812.6668066092874, 828.6975254032235, 966.468442356701, 19328.71006934897, 19888.403347899588, 20610.73998453818, 20696.79303206979, 21045.0, 823.6952327638812, 916.2578311567652, 945.1068740040596, 945.7068308824287, 1007.6120693532896, 10500.00249976251, 10940.288881222696, 10966.890331096587, 10974.231803773659, 11003.009729124416, 604939.0, 609606.0, 621936.0, 629924.0, 644577.0, 90373.54813226023, 106485.46757266164, 107279.0, 112893.0, 125908.0, 885.6191837509804, 940.9952950235205, 968.5205823117602, 978.188103873783, 982.6167794560128, 18547.90726046415, 18763.437096887115, 19166.33505769974, 19173.32893348726, 19183.0, 42782.57217427786, 45537.0, 52839.471605283456, 53648.0, 53942.73028634832, 60278.0, 64256.0, 71556.0, 73898.78303650899, 76843.84734229103, 6837.965810171117, 7902.84194316099, 7956.363490920829, 8073.273405393543, 8240.917590824016, 10369.74075648115, 12669.0, 12931.0, 13041.478340866259, 13118.016148788689, 10786.191035672198, 10815.783684326427, 10861.945690271497, 10974.670759877217, 11014.50434730423, 9180.668803023658, 9662.806743864943, 10855.185861060294, 10882.61910833117, 11328.32030078198, 9396.577154028222, 9694.951525242328, 10519.89480105189, 10790.89209107899, 10846.566137354734, 8051.074126475296, 8093.0, 8110.878336824834, 8356.247937685637, 8390.70632527883, 9137.5888085035, 10799.892001079888, 10829.133669306279, 10983.670489885317, 11119.888801111885], "value": 9200.546272766762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.7903603977425, 1489.7244009858173, 1620.9594760130994, 1807.1235450806348, 1818.4544636609014, 8111.553864537448, 8532.23209911108, 8533.829323413527, 8551.87172192416, 8569.614367353468, 7484.887726684091, 7714.997050383442, 7732.845343093135, 8078.071021832491, 8350.331973442126, 2288.9084436622516, 2339.497008143249, 2341.356127065054, 2342.1919437793936, 2342.882855857209, 2160.7731188225166, 2242.416971587391, 2357.5520651076326, 2362.11420717231, 2374.4063984004, 2287.6797248385237, 2287.794098531132, 2312.387217387394, 2312.9768702313013, 2313.363824948144, 2294.254367330616, 2317.5364927014666, 2319.0839618350824, 2320.3619004773655, 2326.9418264543524, 2299.5975704251855, 2339.0760649543504, 2341.953160936772, 2345.4957184205477, 2351.318117745852, 8318.292945099674, 8515.659373625047, 8529.232369086812, 8529.57352132391, 8559.0, 5045.117104506697, 5212.322398088262, 5222.686638801684, 5223.921641175385, 5226.189940559181, 3259.315543735818, 3320.8007519548664, 3573.213892943574, 3582.7492075554724, 3588.102974256436, 1045.4302405189114, 1059.7774467361862, 1078.1859695929602, 1090.2749671468464, 1095.7534554725203, 10857.022867941843, 10998.230123891331, 11133.387663678495, 11148.554057837677, 11151.498182581803, 3544.4151714967256, 4552.567506086916, 4649.488556258809, 4656.5110663379855, 4693.835715749932, 9470.763230919281, 9479.526023698787, 9482.0, 9483.099105584959, 9487.335886488017, 5160.225966105039, 5164.612654050961, 5164.690118592896, 5175.06848767218, 5178.275041494194, 3660.4692319613505, 3859.6912247020173, 4756.643251756063, 4761.880952976203, 4770.856874293777, 3767.3595488766427, 3773.6792372648633, 3830.6552410283207, 3874.1089549403796, 3879.1078052048188, 1085.4355735017905, 1254.347739175642, 1255.7614053330021, 1266.8416447944007, 1270.6632742323293, 773.7833406646148, 862.4738909265343, 871.6600525794945, 888.1784349476708, 899.2221728205055, 5458.8908221835345, 5604.271444712162, 5700.800471983461, 5713.714314284352, 5934.673592952343, 797.7809538512076, 943.9008904065112, 961.8124465729115, 967.3615413826841, 993.8807343118727, 3609.7473176877365, 3755.8122093895195, 3769.8869033929027, 3857.614238576176, 3864.497615310048, 9862.309638325392, 10309.484525773682, 10368.326058806153, 10372.429516376671, 10378.896211037943, 9561.187299079656, 9982.151517121127, 10225.897741022643, 10230.693079207636, 10282.28024038325, 7600.695972161052, 7918.247766462062, 7922.128565857751, 7923.524588524707, 7929.207079292025, 5394.615365421626, 5416.485433883775, 5515.4760297771645, 5579.414161512983, 5655.943440565542, 7706.7687969361, 9398.718038458857, 9458.054194580487, 9465.384749991228, 9563.0, 8845.734627961172, 9456.574454149442, 9589.0, 9674.70975870725, 9703.514824258618, 6478.967605162133, 6722.295501902078, 7693.384529237759, 7830.33442157423, 7996.92003079985, 8024.719134830253, 8119.350451963947, 8119.431639785158, 8131.308838748772, 8138.83722325562, 6977.686004129725, 7031.648417579203, 7050.647467626495, 7207.531510451804, 7286.7813965579, 6982.581045137308, 7004.404625606677, 7052.576845389088, 7158.3557479824985, 7163.7492687755675, 6613.933860661525, 6646.2356828965285, 6709.194896612437, 6716.496262780409, 6726.293739157318, 4800.591949684316, 4817.566419022305, 4834.661573689808, 4836.250381190921, 4843.176659967743, 3980.203959208228, 4733.242681170997, 4770.356001939694, 4939.97530012362, 5009.749512524287], "value": 4422.514064877858, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.5357196345664, 302.20218622835705, 308.56492345792424, 314.317930091701, 316.2552189593509, 5116.974415127919, 5320.7871685132595, 5342.171963345678, 5349.7592608332625, 5350.545203657689, 4656.511066338028, 4818.771213340593, 5019.974900125503, 5106.744662766865, 5226.863881670995, 4030.662215914777, 4031.2945234583976, 4037.313656678372, 4039.3738970459626, 4044.676425885922, 3331.9833400832963, 3782.905427364324, 3877.418387241908, 4005.399190121476, 4051.6021972419617, 3979.8408063677416, 4028.858989935353, 4030.717849750519, 4044.0, 4052.7770972596495, 3313.950290745641, 3322.435186018383, 4009.6792256619547, 4017.216642754664, 4020.46710572339, 3303.487959366302, 3405.4381027130494, 3455.533502977091, 3523.453864650983, 3538.327717733622, 5058.772355244023, 5107.284980102789, 5108.514691104377, 5117.923231151536, 5122.590192784568, 4544.977275113603, 4547.794834368895, 4558.293464513005, 4575.977120114411, 4622.399088118556, 4615.884602884921, 4821.662483626147, 5337.439568845293, 5446.2919820423485, 5543.36251331094, 3191.361727654455, 3803.2963901678277, 3803.562590302123, 3814.370628846237, 3821.363259351039, 5058.887044850164, 5444.537214336786, 5794.246747922743, 5802.767889284423, 5859.736311865976, 3627.91394947906, 3829.6744776694295, 3831.616838316146, 3834.175652234783, 3844.4809950656863, 3863.5943225961432, 3890.8832735017995, 3898.2983063048923, 3934.8392224293902, 3942.3889297158985, 3823.770573765583, 4008.939865902014, 4167.770772607536, 4531.728096314232, 4562.794674239667, 4647.233206520954, 4968.503149685075, 5296.735163241822, 5513.889722205534, 5574.414686457944, 1186.887245711656, 1188.5780547905542, 1258.3708145927037, 1261.9810702839466, 1270.2568997136777, 752.2439947852482, 835.4360806455618, 845.492704377381, 865.4936861935712, 867.2524862402264, 4920.581750551171, 4930.630202734809, 4962.3624204012585, 4964.404271487445, 4970.8318545141865, 4759.2396410082065, 5185.770118848931, 5214.270002199695, 5380.650257733235, 5403.918941215885, 4356.934645980313, 4361.760103194291, 4403.405540252089, 4913.926291105637, 4959.925601115914, 2334.976650233476, 2590.313566904772, 2742.5337692592307, 2781.652293463317, 2802.4815409149373, 4800.4719480857075, 4889.850885042012, 5016.2224855147515, 5498.422665620053, 5557.527610153102, 4228.429162063082, 4500.819967201341, 5363.410024897258, 5372.468846378842, 5495.972520137454, 4858.58702010333, 4997.0, 5392.784288628411, 5462.289902312674, 5508.476694713996, 4162.231051803032, 4683.508231635629, 4908.680935739309, 5100.795968161232, 5253.816116435906, 4362.411074504901, 5270.49930256625, 5274.3891773570795, 5278.865044015555, 5690.951257547363, 5443.700596467233, 5929.703514824155, 5959.255093113361, 5962.97018514922, 5989.813717992988, 3412.9249286474683, 3623.8912832615065, 3712.294664013829, 3811.2377524495764, 3890.4747859038666, 5227.398849132246, 5430.588047107702, 5581.5255703265675, 5584.682356821873, 5745.885082298414, 4792.640551958687, 5539.7784088636, 5557.221389305348, 5603.187537807076, 5635.549156067423, 4700.8119675213975, 4762.690425122356, 5259.60552958522, 5449.264349312792, 5607.691576963306, 4541.409616749801, 5393.465071416428, 5472.288602481652, 5486.6982315973, 5505.614606977473, 4647.16351056813, 5094.923576146287, 5612.831615051555, 5612.85967850807, 5663.205652212945, 4482.393789023583, 5240.318758561515, 5423.725424525154, 5511.476409741068, 5515.393306736336], "value": 3941.369552773732, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.8817612829695, 684.8664510420467, 689.1626673591585, 706.8762966480865, 769.3844924060753, 14262.21557814321, 14931.402743890258, 14939.551813445587, 14947.775783363259, 14955.327010284536, 13109.410076546554, 13236.404361803718, 13247.403866825993, 13251.403686834092, 13282.468701251939, 652.6051738698067, 672.445232683036, 675.2100042949736, 796.8605494038549, 805.3476683886041, 795.6340083561539, 814.6008455856618, 821.1296026212204, 822.8683410654296, 826.8387664405443, 829.4649950781742, 853.7950891785989, 861.2291998661194, 862.2756884217256, 864.6066039951806, 685.7839780469186, 708.1855865754424, 856.8286342731478, 856.8457677618027, 858.5835869603237, 619.1023016625866, 632.6899819088613, 635.1076737184218, 636.1825054804553, 639.4596565901805, 14016.22910739909, 14100.153990760587, 14122.36449359771, 14131.081479703786, 14190.716185676332, 12843.807342889864, 12985.610431687064, 13092.803607945889, 13118.212907225598, 13214.0, 10903.945480272549, 11395.715107122305, 11816.586419475363, 11827.694575735983, 12091.879081209163, 4326.6754993375625, 4371.934420983688, 4390.824367025315, 4529.388532548097, 4551.795169217393, 92700.0, 93378.0, 93413.06586934111, 94297.52851235769, 96347.0, 9040.276777857756, 9640.469774162348, 9702.223822094216, 10434.895651043544, 10679.572817087384, 17646.735298970525, 19366.515837103903, 19385.612287754167, 19676.90161549183, 19698.113584888713, 6581.835454113685, 7565.243475652391, 7725.806854828675, 7788.4548081634875, 7948.364130869517, 4141.79291035447, 4147.730397524151, 4304.612584867315, 4376.059147283349, 4501.0, 3382.4080785862375, 3439.570053743282, 3485.215826439031, 3488.232588830453, 3493.3886569850683, 554.6394843351853, 570.8401647538695, 639.1850390751736, 642.2902692524693, 645.2676212498848, 588.5350573047244, 643.0836058616453, 643.1381948189448, 653.761377097356, 655.4363247607054, 7500.737474188377, 8043.718469853527, 8084.514929104273, 8531.402801803813, 8971.416857904216, 621.8258887511504, 627.5450298533526, 647.675503595149, 664.1697877652933, 664.8105289992329, 3934.9803250984132, 4239.845031137444, 4436.933445998313, 4466.531014243523, 4472.44094488189, 80439.19560804317, 81312.59343703362, 88354.55822720974, 95540.17839286351, 107607.38588921173, 76890.84663730049, 105066.47466762617, 107689.46155269173, 109864.4506777461, 114624.0, 64841.0, 65619.70331038487, 81704.59147704224, 82896.58551707203, 85148.57425712956, 9390.624375024923, 9632.0, 9636.46999415011, 9811.313208075366, 9846.310758246855, 50108.74945625249, 50680.453204225465, 52063.36202374936, 53670.73164634152, 54439.72780136074, 54827.451725482235, 56075.31774046785, 77484.0, 78208.60895695485, 80866.0, 10012.249081319076, 11824.527018919147, 11867.228630139014, 11909.702257443632, 12309.753804923672, 13384.665383365103, 13480.595582132551, 13512.391942362814, 13544.051916366154, 13813.447462101403, 11129.777404452028, 11418.429078546205, 12012.879871201176, 12458.377081146087, 12673.556425525063, 11779.99480176744, 11995.220310679779, 13016.804747928978, 13046.217226966412, 13304.933475332562, 11760.294382337088, 12345.691357716129, 12440.626781196579, 12545.0, 12546.435410406371, 10580.841287380541, 10668.146548276274, 11034.613788517685, 11262.436878156223, 11637.418129093681, 10020.348677336242, 10038.849417258893, 10622.84065738999, 10667.839982400425, 10692.021975934165], "value": 7119.939828961859, "unit": "score"}], "name": "dromaeo_css", "value": 7098.0625215218515, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 113.0, 114.0, 113.0, 115.0, 114.0, 114.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 138.0, 112.0, 172.0, 99.0, 133.0, 103.0, 136.0, 174.0, 138.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 182.0, 183.0, 182.0, 184.0, 186.0, 184.0, 185.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 60.0, 90.0, 60.0, 59.0, 59.0, 58.0, 83.0, 74.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 127.0, 92.0, 68.0, 69.0, 89.0, 66.0, 68.0, 92.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 88.0, 85.0, 87.0, 84.0, 88.0, 84.0, 86.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 93.0, 120.0, 93.0, 93.0, 93.0, 93.0, 93.0, 97.0, 140.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 79.0, 80.0, 82.0, 99.0, 142.0, 102.0, 81.0, 92.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 55.0, 57.0, 56.0, 55.0, 56.0, 57.0, 56.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 78.0, 68.0, 73.0, 73.0, 70.0, 69.0, 70.0, 70.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 85.0, 84.0, 84.0, 87.0, 84.0, 88.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 148.0, 146.0, 151.0, 159.0, 158.0, 144.0, 191.0, 158.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 168.0, 165.0, 167.0, 166.0, 165.0, 169.0, 167.0, 166.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 66.0, 67.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1466.5000000000002, "alertThreshold": 2.0}]} 01:08:16 INFO - Return code: 0 01:08:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:08:16 INFO - # TBPL SUCCESS # 01:08:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:08:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:08:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:08:16 INFO - 2016-11-26 01:08:16.656 screenresolution[1657:10434] starting screenresolution argv=screenresolution get 01:08:16 INFO - 2016-11-26 01:08:16.659 screenresolution[1657:10434] Display 0: 1600x1200x32@60 01:08:16 INFO - 2016-11-26 01:08:16.665 screenresolution[1658:10437] starting screenresolution argv=screenresolution list 01:08:16 INFO - Available Modes on Display 0 01:08:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:08:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:08:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:08:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:08:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:08:16 INFO - Intel Iris: 01:08:16 INFO - Chipset Model: Intel Iris 01:08:16 INFO - Type: GPU 01:08:16 INFO - Bus: Built-In 01:08:16 INFO - VRAM (Dynamic, Max): 1536 MB 01:08:16 INFO - Vendor: Intel (0x8086) 01:08:16 INFO - Device ID: 0x0a2e 01:08:16 INFO - Revision ID: 0x0009 01:08:16 INFO - Displays: 01:08:16 INFO - Display: 01:08:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:08:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:08:16 INFO - Display Serial Number: 1600x1200 60 01:08:16 INFO - Main Display: Yes 01:08:16 INFO - Mirror: Off 01:08:16 INFO - Online: Yes 01:08:16 INFO - Rotation: Supported 01:08:16 INFO - Return code: 0 01:08:16 INFO - Running post-action listener: _resource_record_post_action 01:08:16 INFO - [mozharness: 2016-11-26 09:08:16.904748Z] Finished run-tests step (success) 01:08:16 INFO - Running post-run listener: _resource_record_post_run 01:08:16 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33377280; Write bytes: 658331648; Read time: 312; Write time: 1923 01:08:16 INFO - TinderboxPrint: CPU usage
53.4% 01:08:16 INFO - TinderboxPrint: I/O read bytes / time
33,377,280 / 312 01:08:16 INFO - TinderboxPrint: I/O write bytes / time
658,331,648 / 1,923 01:08:16 INFO - TinderboxPrint: CPU idle
2,258.3 (46.6%) 01:08:16 INFO - TinderboxPrint: CPU system
969.5 (20.0%) 01:08:16 INFO - TinderboxPrint: CPU user
1,622.0 (33.4%) 01:08:16 INFO - TinderboxPrint: Swap in / out
378,163,200 / 0 01:08:16 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344539648; Write bytes: 346318848; Read time: 27012; Write time: 676 01:08:16 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 31407104; Write bytes: 305098752; Read time: 291; Write time: 1224 01:08:17 INFO - Running post-run listener: _upload_blobber_files 01:08:17 INFO - Blob upload gear active. 01:08:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:08:17 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 01:08:17 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'] 01:08:17 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 01:08:17 INFO - (blobuploader) - INFO - Open directory for files ... 01:08:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:08:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:08:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:08:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:08:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:08:18 INFO - (blobuploader) - INFO - Done attempting. 01:08:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:08:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:08:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:08:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:08:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:08:19 INFO - (blobuploader) - INFO - Done attempting. 01:08:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:08:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:08:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:08:21 INFO - (blobuploader) - INFO - Done attempting. 01:08:21 INFO - (blobuploader) - INFO - Iteration through files over. 01:08:21 INFO - Return code: 0 01:08:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:08:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:08:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab1dbc6e9f5f582fac1dfea8f630376a10954aebdadad186b98fdc2c54ddf6490444812f41e074151beca4e61e65ac81d80568150cbb545eafc49bab0cc235a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c898491c0b16bc66c17e35fb3517abf1e889b8140ab5e6dae368d8951ae3833005ad128ef0ce337ab18f0013b8856184d6d60f9132e8edcfa2cf21111ff902d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8321f74b937dfa931511c2f502f6e2221b58b3ac4eb013278565d7de275a806667b17e70aed39a5f175c3a9e42c6c2bbf4cc50d1da4f4581957968c029de7e4"} 01:08:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:08:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:08:21 INFO - Contents: 01:08:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab1dbc6e9f5f582fac1dfea8f630376a10954aebdadad186b98fdc2c54ddf6490444812f41e074151beca4e61e65ac81d80568150cbb545eafc49bab0cc235a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c898491c0b16bc66c17e35fb3517abf1e889b8140ab5e6dae368d8951ae3833005ad128ef0ce337ab18f0013b8856184d6d60f9132e8edcfa2cf21111ff902d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8321f74b937dfa931511c2f502f6e2221b58b3ac4eb013278565d7de275a806667b17e70aed39a5f175c3a9e42c6c2bbf4cc50d1da4f4581957968c029de7e4"} 01:08:21 INFO - Running post-run listener: copy_logs_to_upload_dir 01:08:21 INFO - Copying logs to upload dir... 01:08:21 INFO - mkdir: /builds/slave/test/build/upload/logs 01:08:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.916104 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-26 01:08:22.313755) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 01:08:22.322458) ========= 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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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/ab1dbc6e9f5f582fac1dfea8f630376a10954aebdadad186b98fdc2c54ddf6490444812f41e074151beca4e61e65ac81d80568150cbb545eafc49bab0cc235a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c898491c0b16bc66c17e35fb3517abf1e889b8140ab5e6dae368d8951ae3833005ad128ef0ce337ab18f0013b8856184d6d60f9132e8edcfa2cf21111ff902d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8321f74b937dfa931511c2f502f6e2221b58b3ac4eb013278565d7de275a806667b17e70aed39a5f175c3a9e42c6c2bbf4cc50d1da4f4581957968c029de7e4"} build_url:https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/TBBpESdxQs26inecbKwfIA/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/ab1dbc6e9f5f582fac1dfea8f630376a10954aebdadad186b98fdc2c54ddf6490444812f41e074151beca4e61e65ac81d80568150cbb545eafc49bab0cc235a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c898491c0b16bc66c17e35fb3517abf1e889b8140ab5e6dae368d8951ae3833005ad128ef0ce337ab18f0013b8856184d6d60f9132e8edcfa2cf21111ff902d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b8321f74b937dfa931511c2f502f6e2221b58b3ac4eb013278565d7de275a806667b17e70aed39a5f175c3a9e42c6c2bbf4cc50d1da4f4581957968c029de7e4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-26 01:08:22.358872) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 01:08:22.359204) ========= 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.Y3IJuKIF6K/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.D9WBfpC6Go/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.005174 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-26 01:08:22.411796) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 01:08:22.412118) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-26 01:08:22.412436) ========= ========= Total master_lag: 1.04 =========