builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0011 starttime: 1484760078.57 results: success (0) buildid: 20170118064726 builduid: ecbd60b1d4da4adfb537473d2d87792e revision: 8a7399316d18b199a4eb8006fd339fceb535fd8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.570422) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.570905) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.571209) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.597261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.597590) ========= 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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-18 09:21:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2017-01-18 09:21:18 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137577 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.759965) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.760329) ========= 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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.027525 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:18.809173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-18 09:21:18.809528) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8a7399316d18b199a4eb8006fd339fceb535fd8f --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 8a7399316d18b199a4eb8006fd339fceb535fd8f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0m0BGkdg9g/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.qH9fd4dc2x/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-18 09:21:18,904 truncating revision to first 12 chars 2017-01-18 09:21:18,904 Setting DEBUG logging. 2017-01-18 09:21:18,904 attempt 1/10 2017-01-18 09:21:18,904 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8a7399316d18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-18 09:21:20,663 unpacking tar archive at: mozilla-aurora-8a7399316d18/testing/mozharness/ program finished with exit code 0 elapsedTime=2.037317 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-18 09:21:20.871257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:20.871585) ========= script_repo_revision: 8a7399316d18b199a4eb8006fd339fceb535fd8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:20.872006) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:20.872279) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 09:21:20.892061) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-18 09:21:20.892365) ========= /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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:21:21 INFO - MultiFileLogger online at 20170118 09:21:21 in /builds/slave/test 09:21:21 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 09:21:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:21:21 INFO - {'append_to_log': False, 09:21:21 INFO - 'base_work_dir': '/builds/slave/test', 09:21:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:21:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:21:21 INFO - 'branch': 'Mozilla-Aurora', 09:21:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:21:21 INFO - 'config_files': ('talos/mac_config.py',), 09:21:21 INFO - 'default_actions': ('clobber', 09:21:21 INFO - 'read-buildbot-config', 09:21:21 INFO - 'download-and-extract', 09:21:21 INFO - 'populate-webroot', 09:21:21 INFO - 'create-virtualenv', 09:21:21 INFO - 'install', 09:21:21 INFO - 'run-tests'), 09:21:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:21:21 INFO - 'download_minidump_stackwalk': True, 09:21:21 INFO - 'download_symbols': 'ondemand', 09:21:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:21:21 INFO - 'tooltool.py': '/tools/tooltool.py', 09:21:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:21:21 INFO - '/tools/misc-python/virtualenv.py')}, 09:21:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:21:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:21:21 INFO - 'installer_path': 'installer.exe', 09:21:21 INFO - 'log_level': 'info', 09:21:21 INFO - 'log_name': 'talos', 09:21:21 INFO - 'log_to_console': True, 09:21:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:21:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:21:21 INFO - 'opt_config_files': (), 09:21:21 INFO - 'pip_index': False, 09:21:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:21 INFO - 'cmd': ('bash', 09:21:21 INFO - '-c', 09:21:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:21 INFO - 'enabled': True, 09:21:21 INFO - 'halt_on_failure': False, 09:21:21 INFO - 'name': 'check_screen_resolution'},), 09:21:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:21 INFO - 'cmd': ('bash', 09:21:21 INFO - '-c', 09:21:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:21 INFO - 'enabled': True, 09:21:21 INFO - 'halt_on_failure': False, 09:21:21 INFO - 'name': 'check_screen_resolution'},), 09:21:21 INFO - 'run_cmd_checks_enabled': True, 09:21:21 INFO - 'sps_profile': False, 09:21:21 INFO - 'sps_profile_interval': 0, 09:21:21 INFO - 'suite': 'dromaeojs', 09:21:21 INFO - 'system_bits': '32', 09:21:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:21:21 INFO - 'title': 't-yosemite-r7-0011', 09:21:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:21:21 INFO - 'use_talos_json': True, 09:21:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:21:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:21:21 INFO - 'work_dir': 'build'} 09:21:21 INFO - [mozharness: 2017-01-18 17:21:21.099394Z] Running clobber step. 09:21:21 INFO - Running pre-action listener: _resource_record_pre_action 09:21:21 INFO - Running main action method: clobber 09:21:21 INFO - rmtree: /builds/slave/test/build 09:21:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:21:23 INFO - Running post-action listener: _resource_record_post_action 09:21:23 INFO - [mozharness: 2017-01-18 17:21:23.603220Z] Finished clobber step (success) 09:21:23 INFO - [mozharness: 2017-01-18 17:21:23.603433Z] Running read-buildbot-config step. 09:21:23 INFO - Running pre-action listener: _resource_record_pre_action 09:21:23 INFO - Running main action method: read_buildbot_config 09:21:23 INFO - Using buildbot properties: 09:21:23 INFO - { 09:21:23 INFO - "project": "", 09:21:23 INFO - "product": "firefox", 09:21:23 INFO - "script_repo_revision": "production", 09:21:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:21:23 INFO - "repository": "", 09:21:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:21:23 INFO - "buildid": "20170118064726", 09:21:23 INFO - "pgo_build": "False", 09:21:23 INFO - "basedir": "/builds/slave/test", 09:21:23 INFO - "buildnumber": 27, 09:21:23 INFO - "slavename": "t-yosemite-r7-0011", 09:21:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:21:23 INFO - "platform": "yosemite_r7", 09:21:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:21:23 INFO - "branch": "mozilla-aurora", 09:21:23 INFO - "repo_path": "releases/mozilla-aurora", 09:21:23 INFO - "revision": "8a7399316d18b199a4eb8006fd339fceb535fd8f", 09:21:23 INFO - "stage_platform": "macosx64", 09:21:23 INFO - "builduid": "ecbd60b1d4da4adfb537473d2d87792e", 09:21:23 INFO - "slavebuilddir": "test" 09:21:23 INFO - } 09:21:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:21:23 INFO - Running post-action listener: _resource_record_post_action 09:21:23 INFO - [mozharness: 2017-01-18 17:21:23.607412Z] Finished read-buildbot-config step (success) 09:21:23 INFO - [mozharness: 2017-01-18 17:21:23.607567Z] Running download-and-extract step. 09:21:23 INFO - Running pre-action listener: _resource_record_pre_action 09:21:23 INFO - Running main action method: download_and_extract 09:21:23 INFO - mkdir: /builds/slave/test/build/tests 09:21:23 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')]} 09:21:23 INFO - trying https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:21:23 INFO - Downloading https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/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 09:21:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/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 09:21:24 INFO - Downloaded 1199 bytes. 09:21:24 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:21:24 INFO - Using the following test package requirements: 09:21:24 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:21:24 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:21:24 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:21:24 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'jsshell-mac.zip'], 09:21:24 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:21:24 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:21:24 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:21:24 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:21:24 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:21:24 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:24 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:21:24 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:21:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:21:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:21:24 INFO - Fetch https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:21:25 INFO - Http code: 200 09:21:25 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 09:21:25 INFO - x-amz-cf-id: SFbQ6TIP2O9cW8Yit78Xe3FzuRDVTEmN5R_kgC1j-DrcnMX1Cd06hg== 09:21:25 INFO - x-amz-version-id: AF8v0qoJ5MoZIQCra6LLXPMVx7Cu9SIf 09:21:25 INFO - Expected file size: 16276147 09:21:25 INFO - Obtained file size: 16276147 09:21:32 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 09:21:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:21:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:21:32 INFO - Fetch https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:21:33 INFO - Http code: 200 09:21:33 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 09:21:33 INFO - x-amz-cf-id: C86KN39UMpR67DmB9kCjfs45EhUR5cypxkBduGlKKty8i4aVGaS5QA== 09:21:33 INFO - x-amz-version-id: AF8v0qoJ5MoZIQCra6LLXPMVx7Cu9SIf 09:21:33 INFO - Expected file size: 16276147 09:21:33 INFO - Obtained file size: 16276147 09:21:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:21:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:21:39 INFO - Fetch https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:21:40 INFO - Http code: 200 09:21:40 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 09:21:40 INFO - x-amz-cf-id: 7CJIhiQgVuw8j6n8zF7qQMLffT_fX-ZWStuP34DQAziMvhZc602xrg== 09:21:40 INFO - x-amz-version-id: knP.owuwTC_YPLIgOLlyaw.kVf11FzxJ 09:21:40 INFO - Expected file size: 14409038 09:21:40 INFO - Obtained file size: 14409038 09:21: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')]} 09:21:41 INFO - trying https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:41 INFO - Downloading https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/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 09:21:43 INFO - Downloaded 110014277 bytes. 09:21:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:43 INFO - mkdir: /builds/slave/test/properties 09:21:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:21:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:21:43 INFO - Contents: 09:21:43 INFO - build_url:https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:44 INFO - Running post-action listener: _resource_record_post_action 09:21:44 INFO - Running post-action listener: set_extra_try_arguments 09:21:44 INFO - [mozharness: 2017-01-18 17:21:44.613401Z] Finished download-and-extract step (success) 09:21:44 INFO - [mozharness: 2017-01-18 17:21:44.613549Z] Running populate-webroot step. 09:21:44 INFO - Running pre-action listener: _resource_record_pre_action 09:21:44 INFO - Running main action method: populate_webroot 09:21:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:21:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:21:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:21:44 INFO - '--tppagecycles', 09:21:44 INFO - '7'], 09:21:44 INFO - 'tests': ['tsvgm']}}, 09:21:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:21:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:21:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:21:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:21:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['damp', 'tps']}, 09:21:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'tests': ['damp', 'tps']}, 09:21:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['dromaeo_dom']}, 09:21:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:21:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['basic_compositor_video']}, 09:21:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:21:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['a11yr', 09:21:44 INFO - 'ts_paint', 09:21:44 INFO - 'tpaint', 09:21:44 INFO - 'sessionrestore', 09:21:44 INFO - 'sessionrestore_no_auto_restore', 09:21:44 INFO - 'tabpaint']}, 09:21:44 INFO - 'other-e10s': {'tests': ['a11yr', 09:21:44 INFO - 'ts_paint', 09:21:44 INFO - 'tpaint', 09:21:44 INFO - 'sessionrestore', 09:21:44 INFO - 'sessionrestore_no_auto_restore', 09:21:44 INFO - 'tabpaint']}, 09:21:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:21:44 INFO - 'ts_paint', 09:21:44 INFO - 'tpaint', 09:21:44 INFO - 'sessionrestore', 09:21:44 INFO - 'sessionrestore_no_auto_restore', 09:21:44 INFO - 'tabpaint']}, 09:21:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:21:44 INFO - 'ts_paint', 09:21:44 INFO - 'tpaint', 09:21:44 INFO - 'sessionrestore', 09:21:44 INFO - 'sessionrestore_no_auto_restore', 09:21:44 INFO - 'tabpaint']}, 09:21:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['a11yr', 09:21:44 INFO - 'ts_paint', 09:21:44 INFO - 'tpaint', 09:21:44 INFO - 'sessionrestore', 09:21:44 INFO - 'sessionrestore_no_auto_restore', 09:21:44 INFO - 'tabpaint']}, 09:21:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['a11yr', 09:21:44 INFO - 'ts_paint', 09:21:44 INFO - 'tpaint', 09:21:44 INFO - 'sessionrestore', 09:21:44 INFO - 'sessionrestore_no_auto_restore', 09:21:44 INFO - 'tabpaint']}, 09:21:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['tsvgx', 09:21:44 INFO - 'tsvgr_opacity', 09:21:44 INFO - 'tart', 09:21:44 INFO - 'tscrollx', 09:21:44 INFO - 'cart']}, 09:21:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:21:44 INFO - 'tsvgr_opacity', 09:21:44 INFO - 'tart', 09:21:44 INFO - 'tscrollx', 09:21:44 INFO - 'cart']}, 09:21:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'talos_options': ['--disable-e10s'], 09:21:44 INFO - 'tests': ['tp5o']}, 09:21:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'tests': ['tp5o']}, 09:21:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'talos_options': ['--disable-e10s', 09:21:44 INFO - '--xperf_path', 09:21:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:44 INFO - 'tests': ['tp5n']}, 09:21:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:44 INFO - 'talos_options': ['--xperf_path', 09:21:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:44 INFO - 'tests': ['tp5n']}}, 09:21:44 INFO - 'talos.zip': {'path': '', 09:21:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:21:44 INFO - Running post-action listener: _resource_record_post_action 09:21:44 INFO - [mozharness: 2017-01-18 17:21:44.632110Z] Finished populate-webroot step (success) 09:21:44 INFO - [mozharness: 2017-01-18 17:21:44.632221Z] Running create-virtualenv step. 09:21:44 INFO - Running pre-action listener: _resource_record_pre_action 09:21:44 INFO - Running main action method: create_virtualenv 09:21:44 INFO - Creating virtualenv /builds/slave/test/build/venv 09:21:44 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 09:21:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:21:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:21:44 INFO - Using real prefix '/tools/python27' 09:21:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:21:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:21:48 INFO - Installing pip.................done. 09:21:48 INFO - Return code: 0 09:21:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:21:48 INFO - Reading from file tmpfile_stdout 09:21:48 INFO - Output received: 09:21:48 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) 09:21:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:21: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')]} 09:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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')]} 09:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:21: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', 'pip>=1.5'] in /builds/slave/test/build 09:21: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 pip>=1.5 09:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:48 INFO - 'HOME': '/Users/cltbld', 09:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:48 INFO - 'LOGNAME': 'cltbld', 09:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:48 INFO - 'MOZ_NO_REMOTE': '1', 09:21:48 INFO - 'NO_EM_RESTART': '1', 09:21:48 INFO - 'PAGER': '/bin/cat', 09:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:48 INFO - 'PWD': '/builds/slave/test', 09:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:48 INFO - 'SHELL': '/bin/bash', 09:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:21:48 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:48 INFO - 'USER': 'cltbld', 09:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:48 INFO - 'XPC_FLAGS': '0x0', 09:21:48 INFO - 'XPC_SERVICE_NAME': '0', 09:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:48 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 09:21:48 INFO - Cleaning up... 09:21:48 INFO - Return code: 0 09:21:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:21: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')]} 09:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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')]} 09:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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', '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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:21: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:21: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 psutil>=3.1.1 09:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:48 INFO - 'HOME': '/Users/cltbld', 09:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:48 INFO - 'LOGNAME': 'cltbld', 09:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:48 INFO - 'MOZ_NO_REMOTE': '1', 09:21:48 INFO - 'NO_EM_RESTART': '1', 09:21:48 INFO - 'PAGER': '/bin/cat', 09:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:48 INFO - 'PWD': '/builds/slave/test', 09:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:48 INFO - 'SHELL': '/bin/bash', 09:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:21:48 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:48 INFO - 'USER': 'cltbld', 09:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:48 INFO - 'XPC_FLAGS': '0x0', 09:21:48 INFO - 'XPC_SERVICE_NAME': '0', 09:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:49 INFO - Downloading/unpacking psutil>=3.1.1 09:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:21:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:52 INFO - Installing collected packages: psutil 09:21:52 INFO - Running setup.py install for psutil 09:21:52 INFO - building 'psutil._psutil_osx' extension 09:21:52 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 09:21:53 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 09:21:53 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 09:21:53 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 09:21:53 INFO - building 'psutil._psutil_posix' extension 09:21:53 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 09:21:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:21:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:21:53 INFO - ^ 09:21:53 INFO - 1 warning generated. 09:21:53 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 09:21:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:53 INFO - Successfully installed psutil 09:21:53 INFO - Cleaning up... 09:21:53 INFO - Return code: 0 09:21:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:21: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')]} 09:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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')]} 09:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:53 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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:21:53 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 09:21:53 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 09:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:53 INFO - 'HOME': '/Users/cltbld', 09:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:53 INFO - 'LOGNAME': 'cltbld', 09:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:53 INFO - 'MOZ_NO_REMOTE': '1', 09:21:53 INFO - 'NO_EM_RESTART': '1', 09:21:53 INFO - 'PAGER': '/bin/cat', 09:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:53 INFO - 'PWD': '/builds/slave/test', 09:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:53 INFO - 'SHELL': '/bin/bash', 09:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:21:53 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:53 INFO - 'USER': 'cltbld', 09:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:53 INFO - 'XPC_FLAGS': '0x0', 09:21:53 INFO - 'XPC_SERVICE_NAME': '0', 09:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:21:56 INFO - Installing collected packages: mozsystemmonitor 09:21:56 INFO - Running setup.py install for mozsystemmonitor 09:21:57 INFO - Successfully installed mozsystemmonitor 09:21:57 INFO - Cleaning up... 09:21:57 INFO - Return code: 0 09:21:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:21: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')]} 09:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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')]} 09:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:21:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:57 INFO - 'HOME': '/Users/cltbld', 09:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:57 INFO - 'LOGNAME': 'cltbld', 09:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:57 INFO - 'MOZ_NO_REMOTE': '1', 09:21:57 INFO - 'NO_EM_RESTART': '1', 09:21:57 INFO - 'PAGER': '/bin/cat', 09:21:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:57 INFO - 'PWD': '/builds/slave/test', 09:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:57 INFO - 'SHELL': '/bin/bash', 09:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:21:57 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:57 INFO - 'USER': 'cltbld', 09:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:57 INFO - 'XPC_FLAGS': '0x0', 09:21:57 INFO - 'XPC_SERVICE_NAME': '0', 09:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:57 INFO - Downloading/unpacking jsonschema==2.5.1 09:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:21:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:22:00 INFO - Downloading functools32-3.2.3-2.tar.gz 09:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:22:00 INFO - warning: no files found matching '*.txt' 09:22:00 INFO - no previously-included directories found matching 'build' 09:22:00 INFO - no previously-included directories found matching 'dist' 09:22:00 INFO - no previously-included directories found matching '.git*' 09:22:00 INFO - Installing collected packages: jsonschema, functools32 09:22:00 INFO - Running setup.py install for functools32 09:22:00 INFO - warning: no files found matching '*.txt' 09:22:00 INFO - no previously-included directories found matching 'build' 09:22:00 INFO - no previously-included directories found matching 'dist' 09:22:00 INFO - no previously-included directories found matching '.git*' 09:22:00 INFO - Successfully installed jsonschema functools32 09:22:00 INFO - Cleaning up... 09:22:00 INFO - Return code: 0 09:22:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:22:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:22:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:00 INFO - 'HOME': '/Users/cltbld', 09:22:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:00 INFO - 'LOGNAME': 'cltbld', 09:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:00 INFO - 'MOZ_NO_REMOTE': '1', 09:22:00 INFO - 'NO_EM_RESTART': '1', 09:22:00 INFO - 'PAGER': '/bin/cat', 09:22:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:00 INFO - 'PWD': '/builds/slave/test', 09:22:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:00 INFO - 'SHELL': '/bin/bash', 09:22:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:22:00 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:22:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:00 INFO - 'USER': 'cltbld', 09:22:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:00 INFO - 'XPC_FLAGS': '0x0', 09:22:00 INFO - 'XPC_SERVICE_NAME': '0', 09:22:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:22:00 INFO - Cleaning up... 09:22:00 INFO - Return code: 0 09:22:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:22:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:22:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:00 INFO - 'HOME': '/Users/cltbld', 09:22:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:00 INFO - 'LOGNAME': 'cltbld', 09:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:00 INFO - 'MOZ_NO_REMOTE': '1', 09:22:01 INFO - 'NO_EM_RESTART': '1', 09:22:01 INFO - 'PAGER': '/bin/cat', 09:22:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:01 INFO - 'PWD': '/builds/slave/test', 09:22:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:01 INFO - 'SHELL': '/bin/bash', 09:22:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:22:01 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:22:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:01 INFO - 'USER': 'cltbld', 09:22:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:01 INFO - 'XPC_FLAGS': '0x0', 09:22:01 INFO - 'XPC_SERVICE_NAME': '0', 09:22:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:01 INFO - Downloading/unpacking blobuploader==1.2.4 09:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:03 INFO - Downloading blobuploader-1.2.4.tar.gz 09:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:22:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:22:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:22:04 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 09:22:04 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 09:22:04 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 09:22:04 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 09:22:04 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 09:22:04 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 09:22:05 INFO - Downloading docopt-0.6.1.tar.gz 09:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:22:05 INFO - Installing collected packages: blobuploader, requests, docopt 09:22:05 INFO - Running setup.py install for blobuploader 09:22:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:22:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:22:05 INFO - Running setup.py install for requests 09:22:05 INFO - Running setup.py install for docopt 09:22:05 INFO - Successfully installed blobuploader requests docopt 09:22:05 INFO - Cleaning up... 09:22:06 INFO - Return code: 0 09:22:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:06 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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:22:06 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 09:22:06 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 09:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:06 INFO - 'HOME': '/Users/cltbld', 09:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:06 INFO - 'LOGNAME': 'cltbld', 09:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:06 INFO - 'MOZ_NO_REMOTE': '1', 09:22:06 INFO - 'NO_EM_RESTART': '1', 09:22:06 INFO - 'PAGER': '/bin/cat', 09:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:06 INFO - 'PWD': '/builds/slave/test', 09:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:06 INFO - 'SHELL': '/bin/bash', 09:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:22:06 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:06 INFO - 'USER': 'cltbld', 09:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:06 INFO - 'XPC_FLAGS': '0x0', 09:22:06 INFO - 'XPC_SERVICE_NAME': '0', 09:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-hWHQHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-tKxMj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-t2XG71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-JeBMXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-y0bnBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-FZKcwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:06 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-QoOyXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-SMhqLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-rGbjXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-9D5Rtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-fA5GFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-jD4K1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-DU1ZRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-WR9f80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-HDDm8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:07 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-7u4_oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:08 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5liHhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:22:08 INFO - Running setup.py install for manifestparser 09:22:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:22:08 INFO - Running setup.py install for mozcrash 09:22:08 INFO - Running setup.py install for mozdebug 09:22:08 INFO - Running setup.py install for mozdevice 09:22:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:22:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:22:08 INFO - Running setup.py install for mozfile 09:22:08 INFO - Running setup.py install for mozhttpd 09:22:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Running setup.py install for mozinfo 09:22:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Running setup.py install for mozInstall 09:22:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Running setup.py install for mozleak 09:22:09 INFO - Running setup.py install for mozlog 09:22:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Running setup.py install for moznetwork 09:22:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:22:09 INFO - Running setup.py install for mozprocess 09:22:09 INFO - Running setup.py install for mozprofile 09:22:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:22:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:22:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:22:10 INFO - Running setup.py install for mozrunner 09:22:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:22:10 INFO - Running setup.py install for mozscreenshot 09:22:10 INFO - Running setup.py install for moztest 09:22:10 INFO - Running setup.py install for mozversion 09:22:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:22:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:22:10 INFO - Cleaning up... 09:22:10 INFO - Return code: 0 09:22:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22: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')]} 09:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22: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')]} 09:22:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:10 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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:22:10 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 09:22:10 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 09:22:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:22:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:10 INFO - 'HOME': '/Users/cltbld', 09:22:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:10 INFO - 'LOGNAME': 'cltbld', 09:22:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:10 INFO - 'MOZ_NO_REMOTE': '1', 09:22:10 INFO - 'NO_EM_RESTART': '1', 09:22:10 INFO - 'PAGER': '/bin/cat', 09:22:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:10 INFO - 'PWD': '/builds/slave/test', 09:22:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:10 INFO - 'SHELL': '/bin/bash', 09:22:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:22:10 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:22:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:10 INFO - 'USER': 'cltbld', 09:22:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:10 INFO - 'XPC_FLAGS': '0x0', 09:22:10 INFO - 'XPC_SERVICE_NAME': '0', 09:22:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:22:10 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-jIlzX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-9nP9HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-bZPhlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-E3_krN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-dlr476-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-np_nC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-u4hd3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-H7RWz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-YkgMP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:22:11 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)) 09:22:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:22:11 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-nE2zIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:22:12 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)) 09:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:22:12 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-AJt2Kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:22:12 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)) 09:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:22:12 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-sqL_M2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:22:12 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)) 09:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:22:12 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-7ukCtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:22:12 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)) 09:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:22:12 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-7qH2id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:22:12 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)) 09:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:12 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-qgmPmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:22:12 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)) 09:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:22:12 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-_YAEjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:22:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:22:12 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-G6_evm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:22:12 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)) 09:22:12 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)) 09:22:12 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)) 09:22:12 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)) 09:22:12 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)) 09:22:12 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)) 09:22:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:22:12 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 09:22:12 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 09:22:12 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 09:22:12 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 09:22:12 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 09:22:12 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 09:22:15 INFO - Downloading blessings-1.6.tar.gz 09:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:22:15 INFO - Installing collected packages: blessings 09:22:15 INFO - Running setup.py install for blessings 09:22:15 INFO - Successfully installed blessings 09:22:15 INFO - Cleaning up... 09:22:15 INFO - Return code: 0 09:22:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:22:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:16 INFO - Reading from file tmpfile_stdout 09:22:16 INFO - Current package versions: 09:22:16 INFO - blessings == 1.6 09:22:16 INFO - blobuploader == 1.2.4 09:22:16 INFO - docopt == 0.6.1 09:22:16 INFO - functools32 == 3.2.3-2 09:22:16 INFO - jsonschema == 2.5.1 09:22:16 INFO - manifestparser == 1.1 09:22:16 INFO - mozInstall == 1.12 09:22:16 INFO - mozcrash == 1.0 09:22:16 INFO - mozdebug == 0.1 09:22:16 INFO - mozdevice == 0.48 09:22:16 INFO - mozfile == 1.2 09:22:16 INFO - mozhttpd == 0.7 09:22:16 INFO - mozinfo == 0.9 09:22:16 INFO - mozleak == 0.1 09:22:16 INFO - mozlog == 3.4 09:22:16 INFO - moznetwork == 0.27 09:22:16 INFO - mozprocess == 0.23 09:22:16 INFO - mozprofile == 0.28 09:22:16 INFO - mozrunner == 6.13 09:22:16 INFO - mozscreenshot == 0.1 09:22:16 INFO - mozsystemmonitor == 0.3 09:22:16 INFO - moztest == 0.8 09:22:16 INFO - mozversion == 1.4 09:22:16 INFO - psutil == 3.1.1 09:22:16 INFO - requests == 1.2.3 09:22:16 INFO - wsgiref == 0.1.2 09:22:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:16 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')]} 09:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:16 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')]} 09:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:16 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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:22:16 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 09:22:16 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 09:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:16 INFO - 'HOME': '/Users/cltbld', 09:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:16 INFO - 'LOGNAME': 'cltbld', 09:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:16 INFO - 'MOZ_NO_REMOTE': '1', 09:22:16 INFO - 'NO_EM_RESTART': '1', 09:22:16 INFO - 'PAGER': '/bin/cat', 09:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:16 INFO - 'PWD': '/builds/slave/test', 09:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:16 INFO - 'SHELL': '/bin/bash', 09:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:22:16 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:16 INFO - 'USER': 'cltbld', 09:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:16 INFO - 'XPC_FLAGS': '0x0', 09:22:16 INFO - 'XPC_SERVICE_NAME': '0', 09:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 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)) 09:22:17 INFO - Cleaning up... 09:22:17 INFO - Return code: 0 09:22:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:22:17 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')]} 09:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:17 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')]} 09:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:17 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 0x10313c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8db56e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, '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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 09:22:17 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 09:22:17 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 09:22:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:22:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:17 INFO - 'HOME': '/Users/cltbld', 09:22:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:17 INFO - 'LOGNAME': 'cltbld', 09:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:17 INFO - 'MOZ_NO_REMOTE': '1', 09:22:17 INFO - 'NO_EM_RESTART': '1', 09:22:17 INFO - 'PAGER': '/bin/cat', 09:22:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:17 INFO - 'PWD': '/builds/slave/test', 09:22:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:17 INFO - 'SHELL': '/bin/bash', 09:22:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:22:17 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:22:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:17 INFO - 'USER': 'cltbld', 09:22:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:17 INFO - 'XPC_FLAGS': '0x0', 09:22:17 INFO - 'XPC_SERVICE_NAME': '0', 09:22:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:22:17 INFO - Cleaning up... 09:22:17 INFO - Return code: 0 09:22:17 INFO - Running post-action listener: _resource_record_post_action 09:22:17 INFO - Running post-action listener: _start_resource_monitoring 09:22:17 INFO - Starting resource monitoring. 09:22:17 INFO - [mozharness: 2017-01-18 17:22:17.754965Z] Finished create-virtualenv step (success) 09:22:17 INFO - [mozharness: 2017-01-18 17:22:17.755923Z] Running install step. 09:22:17 INFO - Running pre-action listener: _resource_record_pre_action 09:22:17 INFO - Running main action method: install 09:22:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:18 INFO - Reading from file tmpfile_stdout 09:22:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:22:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:22:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:22:18 INFO - Reading from file tmpfile_stdout 09:22:18 INFO - Output received: 09:22:18 INFO - Usage: mozinstall [options] installer 09:22:18 INFO - Options: 09:22:18 INFO - -h, --help show this help message and exit 09:22:18 INFO - -d DEST, --destination=DEST 09:22:18 INFO - Directory to install application into. [default: 09:22:18 INFO - "/builds/slave/test"] 09:22:18 INFO - --app=APP Application being installed. [default: firefox] 09:22:18 INFO - mkdir: /builds/slave/test/build/application 09:22:18 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'] 09:22:18 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 09:22:49 INFO - Reading from file tmpfile_stdout 09:22:49 INFO - Output received: 09:22:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:22:49 INFO - Running post-action listener: _resource_record_post_action 09:22:49 INFO - [mozharness: 2017-01-18 17:22:49.292526Z] Finished install step (success) 09:22:49 INFO - [mozharness: 2017-01-18 17:22:49.292667Z] Running run-tests step. 09:22:49 INFO - Running pre-action listener: _resource_record_pre_action 09:22:49 INFO - Running main action method: run_tests 09:22:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:49 INFO - 2017-01-18 09:22:49.306 screenresolution[1626:7408] starting screenresolution argv=screenresolution get 09:22:49 INFO - 2017-01-18 09:22:49.309 screenresolution[1626:7408] Display 0: 1600x1200x32@50 09:22:49 INFO - 2017-01-18 09:22:49.315 screenresolution[1627:7411] starting screenresolution argv=screenresolution list 09:22:49 INFO - Available Modes on Display 0 09:22:49 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 09:22:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:49 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 09:22:49 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 09:22:49 INFO - Graphics/Displays: 09:22:49 INFO - Intel Iris: 09:22:49 INFO - Chipset Model: Intel Iris 09:22:49 INFO - Type: GPU 09:22:49 INFO - Bus: Built-In 09:22:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:49 INFO - Vendor: Intel (0x8086) 09:22:49 INFO - Device ID: 0x0a2e 09:22:49 INFO - Revision ID: 0x0009 09:22:49 INFO - Displays: 09:22:49 INFO - Display: 09:22:49 INFO - Resolution: 1600 x 1200 @ 50 Hz 09:22:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:49 INFO - Display Serial Number: 1600x1200 50 09:22:49 INFO - Main Display: Yes 09:22:49 INFO - Mirror: Off 09:22:49 INFO - Online: Yes 09:22:49 INFO - Rotation: Supported 09:22:49 INFO - Return code: 0 09:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:22:49 INFO - Python 2.7.3 09:22:49 INFO - Return code: 0 09:22:49 INFO - grabbing minidump binary from tooltool 09:22:49 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')]} 09:22:49 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 0x102f50d30>, '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 0x102db6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f48b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:22:49 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 09:22:49 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 09:22:49 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 09:22:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:22:49 INFO - Return code: 0 09:22:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:22:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:22:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:22:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:22:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:22:49 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/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 09:22:49 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/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --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 09:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0m0BGkdg9g/Render', 09:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:49 INFO - 'HOME': '/Users/cltbld', 09:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:49 INFO - 'LOGNAME': 'cltbld', 09:22:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:22:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:49 INFO - 'MOZ_NO_REMOTE': '1', 09:22:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:22:49 INFO - 'NO_EM_RESTART': '1', 09:22:49 INFO - 'PAGER': '/bin/cat', 09:22:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:49 INFO - 'PWD': '/builds/slave/test', 09:22:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:49 INFO - 'SHELL': '/bin/bash', 09:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qH9fd4dc2x/Listeners', 09:22:49 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 09:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:49 INFO - 'USER': 'cltbld', 09:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:49 INFO - 'XPC_FLAGS': '0x0', 09:22:49 INFO - 'XPC_SERVICE_NAME': '0', 09:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:49 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/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--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 09:22:49 INFO - mozversion application_buildid: 20170118064726 09:22:49 INFO - mozversion application_changeset: 8a7399316d18b199a4eb8006fd339fceb535fd8f 09:22:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:22:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:22:49 INFO - mozversion application_name: Firefox 09:22:49 INFO - mozversion application_remotingname: firefox-dev 09:22:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:49 INFO - mozversion application_vendor: Mozilla 09:22:49 INFO - mozversion application_version: 52.0a2 09:22:49 INFO - mozversion platform_buildid: 20170118064726 09:22:49 INFO - mozversion platform_changeset: 8a7399316d18b199a4eb8006fd339fceb535fd8f 09:22:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:49 INFO - mozversion platform_version: 52.0a2 09:22:49 INFO - using testdate: 1484760169 09:22:49 INFO - actual date: 1484760169 09:22:49 INFO - starting webserver on 'localhost:49241' 09:22:49 INFO - SUITE-START | Running 2 tests 09:22:49 INFO - TEST-START | dromaeo_css 09:22:49 INFO - operating with platform_type : mac_ 09:22:49 INFO - Initialising browser for dromaeo_css test... 09:22:49 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpblxYsP/profile http://localhost:49241/getInfo.html) 09:22:52 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 09:22:52 INFO - PROCESS | 1637 | colorDepth:24 09:22:52 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 09:22:52 INFO - PROCESS | 1637 | __metrics 09:22:52 INFO - PROCESS | 1637 | 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" 09:22:52 INFO - PROCESS | 1637 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:53 INFO - TEST-INFO | 1637: exit 0 09:22:53 INFO - Browser initialized. 09:22:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:22:53 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmpblxYsP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:23:05 INFO - PROCESS | 1639 | RSS: Main: 221966336 09:23:05 INFO - PROCESS | 1639 | 09:26:09 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:26:11 INFO - PROCESS | 1639 | RSS: Main: 374710272 09:26:11 INFO - PROCESS | 1639 | 09:29:14 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:29:15 INFO - PROCESS | 1639 | RSS: Main: 349237248 09:29:15 INFO - PROCESS | 1639 | 09:32:17 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:32:18 INFO - PROCESS | 1639 | RSS: Main: 317145088 09:32:18 INFO - PROCESS | 1639 | 09:35:20 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:35:21 INFO - PROCESS | 1639 | RSS: Main: 316194816 09:35:21 INFO - PROCESS | 1639 | 09:38:24 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:38:25 INFO - PROCESS | 1639 | RSS: Main: 307769344 09:38:25 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:41:28 INFO - PROCESS | 1639 | RSS: Main: 401506304 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | __start_tp_report 09:41:28 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 09:41:28 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 09:41:28 INFO - PROCESS | 1639 | |i|pagename|runs| 09:41:28 INFO - PROCESS | 1639 | |0;dojo.html;2695.407010457699;3135.937281254375;3302.4385854404754;3343.6990670839637;3361.0757041813513;5686.3744988051285;5701.942980570193;5741.827745167651;5777.0756678931375;5811.535077193825;5731.799387021455;5819.592628516006;5822.475977162055;5837.7373018214175;5841.299044114701;5254.448282930294;5268.394134674504;5280.366356037281;5287.312649355578;5326.307580014612;5189.195674670432;5199.402068762103;5199.532042116209;5353.625246232746;5368.409474957752;5212.869678258055;5260.631755777097;5263.868403289929;5264.947010597876;5298.36419629645;5269.051570717288;5307.575393968474;5336.759845806948;5350.464953504657;5352.090144675376;5101.438841727407;5182.015417070782;5274.709890955995;5275.366955965271;5280.155175171993;4681.765911704435;5630.324361076656;5651.943480565183;5652.236948012007;5718.5711071669375;5138.897222055575;5986.802639472079;6171.074338849208;6253.656048917308;6286.402791734778;5247.370315562119;5652.858678533028;6262.937370626281;6269.749210031593;6298.6535740534255;4844.660873738875;5683.630564013367;5775.393583673654;5775.509081728058;5797.971010144923;6285.811425657238;6367.872642547123;6376.585521941059;6383.71273292703;6394.712237949303;6188.628682279078;6216.502679785697;6222;6226.159468471789;6240.251169859646;6076.392360763888;6161.568690191733;6248.31268560458;6259.843503912439;6272.560920735505;6128.208708139665;6184.165137706442;6204.162438070893;6207.6585787782105;6237.937620623827;6198.194234749454;6241.687915604201;6244.750209991549;6250.343713910065;6257.374262573797;4663.973925736333;4947.356843610308;4959.752012399365;4982.9252561211615;4986.152354099811;4360.4767427908855;4387.341898715218;4391.758453285036;4403.3394990751;4421.867343979686;5763.193152958589;5770.596058275881;5771.192033115367;5783.826485205451;5815.563832712563;5292.179712144623;6277.591956522811;6305.936940630627;6308.3060863305;6311;5517.503424691797;5616.213730077792;5637.7181140942785;5768.884622307614;5775.249217601685;5364;5445.047116754552;5462.754176062087;5465.890682186334;5469.507744303032;5697.971510142423;6024.728887200086;6360.586561873463;6389.808305750835;6392.169018027626;6128.816135515942;6137.693115344304;6307.3377295383325;6314.652694101685;6330.335314791881;6218.191635087591;6297.685115744286;6310.2743184534365;6312.210973628296;6371.5221358397375;6231.127642130196;6247.96876015617;6259.436650701458;6262.46769024638;6270.467010304175;5952.553558483044;6298.212723409574;6301.716422761078;6305.621662700252;6316.684165791784;5853.795117170878;6089.964706000079;6093.542984276109;6121.84695382619;6175.2589689237575;6156.661383623944;6164.106204600397;6164.722587483484;6176.81469555914;6189.50483961273;5832.562557808266;5997.760089596367;6014.909776353271;6022.3977602241175;6028.156058151774;5905.202797622438;6043.214382130295;6108.129596501795;6140.631562106288;6158.014717645335;6006.519478441802;6059.151718759465;6069.4840938518955;6076.270847498152;6124.846878828065 09:41:28 INFO - PROCESS | 1639 | |1;ext.html;450.26381865649665;488.6213184781796;517.9637425380223;533.4025891002076;555.6055200807426;28701.569476457844;39336;40252.99367515813;40417.78746637598;42448.787756061174;41423.58576414235;41803.372949405784;41848.37227441591;41883.790581047055;41897;8328.333733301351;9246.214071803905;9424.81150376992;9431.764205894873;9454;8354.582270886462;11080.05819505343;11278.266912650692;11294.830577541343;11437.97058264756;11189.992900638941;11245.55017799287;11256.887431125666;11262.493187806527;11290.661280161608;8392.496450212946;8617.913820861837;9634.325597208199;11238.943805280962;11368.204225704205;10971.725706857313;11260.268082574607;11261.831072533918;11429.428528573539;11445.198836081478;39818.40272395917;40303.39544906829;40343.7982810087;40345;40375;3212.5341825435416;3220.6457289698114;3256.234784825564;3306.669333066698;3322.7507936904585;29397;29723.256918576993;29779.851100744574;30108;30449.695503045128;3020.2600362911007;3084.6144231971;3088.5984821973225;3105.6739042400673;3127.4526957782523;426444;506970;506972;507583;508756;61312.69343653253;61765;61843.690781546706;61987.45031374252;62101.68949155225;34719.13202169966;35180.47229291563;35606.82196589052;36274;36284.455733164024;15856.52430427089;16014.199290035685;16031.599210019609;16049.358025679076;16056.839431605767;9753.463559504295;11120;11444.599439019594;12218.877811221952;12299.569515066929;18791.624167516573;18805.15376808047;18851;19119.33082342139;19354.612907741765;6961.37347638715;7599.846063883537;7750.14748377678;7756.379489640815;7790.649420776079;863.6761214544546;949.3354651743668;968.2496065549115;1073.6510634043966;1091.47063674118;29505.704942950295;29723.256918576775;29950.55074173889;30237;30248.243793905327;764.6788348893479;1023.7663615343643;1051.8737751469873;1056.6882769582992;1123.2586492914636;15361.692766144837;15469.5359139226;15562.922185389001;15694.76457853133;15727.44953926607;688233;779633;790806;791928;796226;57349;57906.42093579094;57968;60453;60491.39508604946;1292.1278137257502;1322.4049177869997;1341.6511706956262;1346.6027521880874;1359.0758284366527;29159.271018224714;31408.842955785076;32184.35631287408;32363.838180808943;32573.348533028733;55413.722931385084;56108;56146.43853561412;56207.15689264583;56395;631127;635894;636834.8158259179;637399;638833;35711;36659.26681466409;37531.624683752816;42432;42922;37629.05927351728;45197.54802452065;46148;46225.76887115543;46615.80457758818;37441.251174975805;37909.62090379061;38239.61760382361;38350;38500.42249366206;36959.815200923826;37737.43393849149;38252.80873595614;38392.424113638874;38857.41713874238;37002.44496332611;37132.44301335428;37617;38089.66686165971;38295.2340953185;32010.35979280448;33664.49503257502;34282;34356.31287374189;34402.48396274008;21522.784772152077;25452;25511.74488255094;25852.482950341266;25856.482870342865 09:41:28 INFO - PROCESS | 1639 | |2;jquery.html;338.85767977449467;351.83463772027136;375.4049831017835;384.76337052712574;402.93754468057443;14648.414063437463;14786.038907471007;15661.53015409538;19606.803931960676;20226.19095236189;19679.901600492012;19783.70324445131;20144.294949676765;20275.986200689982;20567.794322056776;5047.545720885119;5649.1526271059365;5871.383504732005;5881.705914704269;5888.411158884121;5365.739051322936;5503.367112782042;5733.5413166946755;5795.884082318351;5816.563757718188;5751.597388182829;5760.193572899798;5768.625039372428;5769.048107062351;5769.884602307951;5645.689487078209;5935.0503919372695;5991.700414979234;6037.0642550404755;6048.425399587032;5625.099984002581;5766.942330576724;5837.474627283523;5864.941350586482;5868.2664666916635;12691.936540317332;12962;12975.416106275146;12979.286139262336;18083.81916180848;2180.433087397272;2297.127091705158;2327.44141406063;2352.6706261123454;2509.7866681332107;9533.856992145124;9718;9853.556589953398;10264.897351026544;10284.640037598723;1801.7207332863427;1836.6051298970785;1853.8424233940132;1868.4394681595468;1925.7015162649811;94351;99592.50203748935;100665.99334006713;106353.46823265911;106466;11124.332540047622;11185.66443006711;11203.327800332007;11415.20093593457;11668;14446.855531444551;14457.710845783025;14462;14489.275536223147;14490.492832751013;6980.441564674814;8066.5563394012715;8293.460925039853;8435.873461898076;8465.95767021161;8081.757547273592;8086;8088.83822323562;8257.504549726915;8266.13205613414;3822.0827001519433;4355.7822108894425;4373.846915357947;4398.318260669601;4525.389072475174;1629.8940568862986;1874.728164416152;1919.395390452003;1921.164293532318;1922.7884932657396;628.7927179814767;678.0168755304828;752.8218338300542;896.2516298890499;936.6019441737235;17372.534944467636;18439.035607344485;18938.715919261223;19253.61492770137;21217.257395991066;859.7936495241097;902.8961669407971;932.9160375566096;933.3839665820526;942.7878727286305;10058.849117263248;11047.72380690489;11050.226484146033;11057.778844423066;11094.667159985212;526182;529975;532839;543296.2835185799;543997;118124.66999412168;122464.38767806104;123249;127654;128222.35888820497;938.1369140390857;942.6417961174847;946.8248374050822;953.5565961827645;967.9177269931994;17868.731969020737;18172.727409089137;18328;18456.35402760897;18592.977386243885;39780;47572;48011;48470;49654;65804;68270;69203.30796691969;69277.65361173161;69611;6718.294579069127;6748.785218660491;6832.93167068323;7345.632718364167;7388.187299396955;11327.490262938352;12777.913877320532;12778;12868.935655321664;12883.677908052374;10657.413842238751;10763.192760542834;11150.219484635998;11218.35403493884;11317.943410282896;10115.089641932262;10622.681319560426;10691.411972341595;11169.832452513381;11176.050035747052;10924.126069914546;11287;11327.15046371509;11370.602028928948;11605.709857253636;7414.703411863465;11548.94225528867;11585.015273701829;11640.740592634076;12184.233286173278;9809.950950245204;11335.376554289593;11544.19190656646;11680.123990700902;12142 09:41:28 INFO - PROCESS | 1639 | |3;mootools.html;1335.8178012458973;1444.1696024785751;1593.2989484626764;1779.3416435918712;1820.2900868661225;8050.557219352933;8495.447795893262;8519.488830670163;8520.446170998881;8524.829503409928;7325.6703448344815;7618.88571671424;7643.61781910905;7975.680972761097;8234.135415781346;1969.9310524131586;2310.4570425949887;2344.988275058622;2345.812335013195;2348.0255693887016;2328.138588722175;2353.976460235401;2372.240882917467;2372.525494901018;2373.620220764678;2063.998960504154;2307.1463558483383;2308.319045881469;2330.6715172351765;2331.8834058297102;2210.5136869888597;2298.620727579964;2318.374039009475;2322.3381336319235;2327.8719670418122;1744.921478533469;2258.6837842702034;2260.457490202356;2260.660900864867;2261.536385041079;7841.176676448948;7893;7911.723089691834;7912.723054693116;7933.048034235871;4442.2226110430865;5039.916417970154;5205.791768329263;5210.531052205281;5213.113770658997;3197.1847178969215;3288.819114948677;3533.152043509565;3539.451385035298;3550.8757193498104;908.2189317187214;1035.3425574760006;1070.544060078293;1084.452351562456;1087.141158484796;10893.618723344724;11047.668569942834;11075.667729968114;11090.944545277222;11098.500567474483;4480.148771733426;4636.2581986882615;4710.26990816424;4721.622270218374;4728.976355118271;9179.724608261762;9431.48126853016;9446;9448.291378146667;9475.715728528156;5064.7214403207445;5178.145605975047;5197.168453047494;5201.063808514428;5203.713795741195;4199.551010226989;4251.128518653669;4660.300954856799;4699.835505757351;4762.809487620457;3770.0763312988493;3782.8865134046023;3865.6714179295077;3875.1280961783373;3910.432987216838;1251.28676654308;1259.1500737002486;1264.4752427742567;1270.7140893299118;1276.8276282701715;793.3296364572043;871.5772850167664;894.5348418822176;918.3846822628855;922.4834092907993;5779.3642699303045;5806.129080637854;5806.477417032403;5966.075258334965;6095.329513753483;917.8990311065777;935.1817159985013;936.1060187521038;936.7751739582451;963.2342287881012;3727.1986522897705;3843.615638436134;3870.5161854768153;3886.650201481824;3944.4872166618156;9694.951525242328;10125.848112278321;10159.695209143738;10170.949145254375;10180.541875615616;10093.999999999853;10123.544440500047;10132.986701329808;10140.847887281696;10150.746231344128;7422.6288685565505;7756.612169391621;7790.376769858398;7800.102988156321;7839.137694853562;5540.418256083053;5595.5523558116065;5651;5716.914246286392;5735.111057785883;9099.636014559608;9232.538373081185;9345;9365.484898330657;9458.716238512856;9544.809103817746;9547.904520954979;9607.375520591138;9648.083432073941;9684.176844968257;6571.737130514726;6778.8644227116165;6946.340097690711;7129.500934934505;7266.927330726625;7826.686932522635;7990.6004699765945;7992.280694737501;8001.879971800544;8006.759797206093;5897;7163;7163.8567228656175;7177.354038136593;7195.424366050599;6864.24493305726;6982.406495447944;7033.366996970297;7061.540999834994;7102.893456598258;6635.104260924713;6666.666666666744;6754.290799466182;6756.6959486822225;6782.728690852508;3852.595477474937;4530.637548996138;4679.625629949664;4710.081534100955;4719.905601888012;4629.282461218517;4734.745292497422;4764.737939413366;4829.637777166657;4838.903221935471 09:41:28 INFO - PROCESS | 1639 | |4;prototype.html;300.7669056481227;308.822427104415;309.14983794564944;314.1627562545814;317.6839045150076;4562.699630605278;5255.92116118258;5420.83737487875;5472.042392581302;5487.341519017724;4738.312944623028;5159.303494028305;5283.445238249986;5306.283651707018;5367.946320536794;3135.310231749023;3629.2922880038336;3958.5051868516434;4013.8595149169787;4100.65144462721;3552.9111772205642;4043.6967227457912;4088.9182216355657;4089.7750623715833;4123.299039163335;4026.838926442939;4032.2943484890175;4070;4086.4459712679577;4093.97953010236;3889.880015391618;4047.5142982841953;4052.2705912935658;4088.262488442415;4090.202410529948;3468.531748213984;3482.1990942083207;3488.023353461035;3494.9825250873837;3508.3509550733197;4244.61798438139;5165.793368265265;5191.325127733408;5212.348456442945;5217.904240109581;4440.289553671397;4539.083823059339;4568.725876447424;4604.398514839105;4615.076984603059;4216.536181020086;5064.037832811754;5442.673439593597;5575.414581468928;5617.4382561743905;3600.9279814403567;3651.2514934438377;3817.4273858921106;3817.6976914968354;3829.950976227705;5171.456997015334;5365.463453654604;5402.138075731873;5596.300462442195;5869.677167755772;3558.7153027757718;3856.8842934712;3883.339832228528;3893.1824316893203;3925.784081875467;3332.900012999614;3508.8947331580093;3973.8211780469946;3988.441618173458;3997.2405243003736;4091.447654566655;4538.841140560065;4555.681102322806;4571.428571428572;4619.584237418649;4482.884395345204;4824.758762061883;5511.669299842022;5571.916421253684;5637.7181140942785;1258.7985922252396;1265.7531781302741;1268.7589358021946;1272.8727127287382;1276.2087505746497;749.4566439331442;808.2604416958535;821.7781199076215;850.5073595839386;871.2507243770352;4779.928301075487;4789.353437285991;4837.839458066061;5295.735213239323;5359.490848369399;5772.6536407815665;5793.391693872167;5807;5807.796727114531;5866.472017518444;4642.930356044662;4800.73595952226;5267.367915850123;5287;5317.627766056339;2383.010186494262;2814.2542226310043;2877.6690680572;2878.6107530749427;2900.2314386687544;5079.593632509391;5726;5732.656040637575;5752.913706294409;5756.380846714735;5309.858310517802;5332.200169974457;5594.608377413623;5609.523190528769;5623.437656234344;5058.35603428887;5138.456464050319;5633.8309850704545;5733.02210737295;5746.569007324468;4517.860087239468;5111.718855462986;5349.331333583302;5388.595855310789;5397.920415916911;4352.608265256142;4576.254247451636;4774.689645173053;5335.306410166653;5384.623076384615;4690;5208.348956380452;5587.033206423484;5596.440355964533;5731.656100633975;3332.5501057356946;3352.396568617672;3489.865793617034;3618.187810212464;3670.541182352206;4714.905701885875;5203.661761985459;5350.919736203882;5353.505092870013;5482.5613950883035;4295.677824163137;4478.9552104478535;4918.360613120271;5165.431802501797;5210.27056212123;5313.52178303939;5315.3423288355825;5348.973255133699;5354.038011871266;5367.865803354947;4706.964467817074;4998.950010499849;5551.944480555304;5595.748191331319;5601.60788744784;5439.619226654097;5557.833265002057;5604.971975140098;5610.270664813548;5691.174779657009;4827.010462855176;4843.878903027453;5395.028894798974;5598.3282006159525;5607.411221821813 09:41:28 INFO - PROCESS | 1639 | |5;yui.html;684.9999999999999;711.2496316386217;715.5849607227808;740.3558903753733;774.1445702498738;13716.999999999987;14136.434542618283;14375.424983000694;14387.496437624672;14396.712065758691;12753.617391478247;12806.871931280684;12817.615471535846;12871;12885;651.967401629919;667.5160508631252;676.8815457294978;677.8203775999364;816.3224523645375;667.9165104361955;789.1319548496665;818.3739439328918;819.9918000819976;820.1634332980357;861.6725644255174;879.5118709116438;879.7492714576347;880.4365206267962;882.74400423877;870.5473153959937;874.2175752701361;874.6938571499987;875.2254254984321;875.829213303406;514.3210961530781;531.529596307267;630.7413960276265;659.7328082126746;662.6123717625195;13343.73312533744;13920.721585568232;13924.860751392458;13930.815880650156;13934.860651393457;12408.007359411225;12552.184108032949;12657.623612713252;12672.556460523929;12961.740765184737;10529.420881851494;11642.417879106011;11750.353730544817;11757.58848440309;12130.818037729441;4310.374995625648;4337.674674399433;4349.108432771274;4356.106998065421;4515.300128480058;93340;95052.09895802115;95230.52384738032;95327;95906.04093959041;9626.32615716893;9725.951370243103;9916;10270.6405275815;10306.639267625596;19151.521211969535;19176.71234931477;19217.231310747415;19367.903160484108;19447.416577502696;7251.963740181371;7325.853482930418;7368.300011498899;7474.476786624884;7499.362809037612;4021.7788021658503;4081.1429599783783;4114.7942602870335;4138.875833724993;4140.937885931713;3208.3102133041507;3299.6370399256543;3311.519829624739;3312.519684645761;3319.3195394943978;599.5772980049055;609.6860117039728;610.386561505684;622.5331001748689;629.2637613991641;479.85844175967475;496.0957266314131;509.8725318670332;548.2625868207255;571.9685417302088;8517;8767.956160219159;8781.473111613324;8800.295976321879;8998.820023599623;577.5841394196173;625.7841044839523;629.0941044895427;630.3538872655565;663.7444583835259;4202.348635961431;4463.509014008457;4516.36770852081;4523.077841095587;4547.136044151601;119242.40378798051;121292.58042484437;124688;126578.36710816387;127132.7286727121;74453;89613.65579516313;103086.94109474667;105470;107500;66658.33341666618;82244;84695;84852.33312167767;85477.14522854691;7846.921530784619;8476.49141051539;8522.957385213282;8679.218870301704;8684.956575217084;50433.74783126061;57465.966073563344;58427;59166.70416647889;61344.38655613565;81871;83888.96666200047;87078;87227.25545489181;87831.1216887823;11159;11185.832212516982;11735.76528469443;11853;12189.268643881396;11638.88361116378;12021.939890300842;12131.575394861138;12235.388230588256;12279.877201227873;11777.705557361134;11879.168458207843;12145.514179432723;12172.817407739069;12737.808932866199;12460.87539124597;12548.937255313665;12716.491340346653;12793.42429590652;13334.799978000532;12805;13180.209187448783;13300.933495332461;13666.93166534161;13706.588802335946;11139.377158479141;11158.163137764537;11186.32882027081;11210.719732006439;11811.409429528661;11227.607033753779;11273.605423810128;11306.83039754388;11310.434478276218;11484.598039068593 09:41:28 INFO - PROCESS | 1639 | __end_tp_report 09:41:28 INFO - PROCESS | 1639 | __start_cc_report 09:41:28 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,2126 09:41:28 INFO - PROCESS | 1639 | __end_cc_report 09:41:28 INFO - PROCESS | 1639 | __startTimestamp1484761288602__endTimestamp 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | ------- Summary: start ------- 09:41:28 INFO - PROCESS | 1639 | Number of tests: 6 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5730.09 Median:5905.20 stddev:672.58 (11.4%) stddev-sans-first:631.11 09:41:28 INFO - PROCESS | 1639 | Values: 2695.4 3135.9 3302.4 3343.7 3361.1 5686.4 5701.9 5741.8 5777.1 5811.5 5731.8 5819.6 5822.5 5837.7 5841.3 5254.4 5268.4 5280.4 5287.3 5326.3 5189.2 5199.4 5199.5 5353.6 5368.4 5212.9 5260.6 5263.9 5264.9 5298.4 5269.1 5307.6 5336.8 5350.5 5352.1 5101.4 5182.0 5274.7 5275.4 5280.2 4681.8 5630.3 5651.9 5652.2 5718.6 5138.9 5986.8 6171.1 6253.7 6286.4 5247.4 5652.9 6262.9 6269.7 6298.7 4844.7 5683.6 5775.4 5775.5 5798.0 6285.8 6367.9 6376.6 6383.7 6394.7 6188.6 6216.5 6222.0 6226.2 6240.3 6076.4 6161.6 6248.3 6259.8 6272.6 6128.2 6184.2 6204.2 6207.7 6237.9 6198.2 6241.7 6244.8 6250.3 6257.4 4664.0 4947.4 4959.8 4982.9 4986.2 4360.5 4387.3 4391.8 4403.3 4421.9 5763.2 5770.6 5771.2 5783.8 5815.6 5292.2 6277.6 6305.9 6308.3 6311.0 5517.5 5616.2 5637.7 5768.9 5775.2 5364.0 5445.0 5462.8 5465.9 5469.5 5698.0 6024.7 6360.6 6389.8 6392.2 6128.8 6137.7 6307.3 6314.7 6330.3 6218.2 6297.7 6310.3 6312.2 6371.5 6231.1 6248.0 6259.4 6262.5 6270.5 5952.6 6298.2 6301.7 6305.6 6316.7 5853.8 6090.0 6093.5 6121.8 6175.3 6156.7 6164.1 6164.7 6176.8 6189.5 5832.6 5997.8 6014.9 6022.4 6028.2 5905.2 6043.2 6108.1 6140.6 6158.0 6006.5 6059.2 6069.5 6076.3 6124.8 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:75230.46 Median:37067.44 stddev:172671.23 (465.8%) stddev-sans-first:173064.33 09:41:28 INFO - PROCESS | 1639 | Values: 450.3 488.6 518.0 533.4 555.6 28701.6 39336.0 40253.0 40417.8 42448.8 41423.6 41803.4 41848.4 41883.8 41897.0 8328.3 9246.2 9424.8 9431.8 9454.0 8354.6 11080.1 11278.3 11294.8 11438.0 11190.0 11245.6 11256.9 11262.5 11290.7 8392.5 8617.9 9634.3 11238.9 11368.2 10971.7 11260.3 11261.8 11429.4 11445.2 39818.4 40303.4 40343.8 40345.0 40375.0 3212.5 3220.6 3256.2 3306.7 3322.8 29397.0 29723.3 29779.9 30108.0 30449.7 3020.3 3084.6 3088.6 3105.7 3127.5 426444.0 506970.0 506972.0 507583.0 508756.0 61312.7 61765.0 61843.7 61987.5 62101.7 34719.1 35180.5 35606.8 36274.0 36284.5 15856.5 16014.2 16031.6 16049.4 16056.8 9753.5 11120.0 11444.6 12218.9 12299.6 18791.6 18805.2 18851.0 19119.3 19354.6 6961.4 7599.8 7750.1 7756.4 7790.6 863.7 949.3 968.2 1073.7 1091.5 29505.7 29723.3 29950.6 30237.0 30248.2 764.7 1023.8 1051.9 1056.7 1123.3 15361.7 15469.5 15562.9 15694.8 15727.4 688233.0 779633.0 790806.0 791928.0 796226.0 57349.0 57906.4 57968.0 60453.0 60491.4 1292.1 1322.4 1341.7 1346.6 1359.1 29159.3 31408.8 32184.4 32363.8 32573.3 55413.7 56108.0 56146.4 56207.2 56395.0 631127.0 635894.0 636834.8 637399.0 638833.0 35711.0 36659.3 37531.6 42432.0 42922.0 37629.1 45197.5 46148.0 46225.8 46615.8 37441.3 37909.6 38239.6 38350.0 38500.4 36959.8 37737.4 38252.8 38392.4 38857.4 37002.4 37132.4 37617.0 38089.7 38295.2 32010.4 33664.5 34282.0 34356.3 34402.5 21522.8 25452.0 25511.7 25852.5 25856.5 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:31774.65 Median:2340.06 stddev:89393.00 (3820.1%) stddev-sans-first:89612.60 09:41:28 INFO - PROCESS | 1639 | Values: 338.9 351.8 375.4 384.8 402.9 14648.4 14786.0 15661.5 19606.8 20226.2 19679.9 19783.7 20144.3 20276.0 20567.8 5047.5 5649.2 5871.4 5881.7 5888.4 5365.7 5503.4 5733.5 5795.9 5816.6 5751.6 5760.2 5768.6 5769.0 5769.9 5645.7 5935.1 5991.7 6037.1 6048.4 5625.1 5766.9 5837.5 5864.9 5868.3 12691.9 12962.0 12975.4 12979.3 18083.8 2180.4 2297.1 2327.4 2352.7 2509.8 9533.9 9718.0 9853.6 10264.9 10284.6 1801.7 1836.6 1853.8 1868.4 1925.7 94351.0 99592.5 100666.0 106353.5 106466.0 11124.3 11185.7 11203.3 11415.2 11668.0 14446.9 14457.7 14462.0 14489.3 14490.5 6980.4 8066.6 8293.5 8435.9 8466.0 8081.8 8086.0 8088.8 8257.5 8266.1 3822.1 4355.8 4373.8 4398.3 4525.4 1629.9 1874.7 1919.4 1921.2 1922.8 628.8 678.0 752.8 896.3 936.6 17372.5 18439.0 18938.7 19253.6 21217.3 859.8 902.9 932.9 933.4 942.8 10058.8 11047.7 11050.2 11057.8 11094.7 526182.0 529975.0 532839.0 543296.3 543997.0 118124.7 122464.4 123249.0 127654.0 128222.4 938.1 942.6 946.8 953.6 967.9 17868.7 18172.7 18328.0 18456.4 18593.0 39780.0 47572.0 48011.0 48470.0 49654.0 65804.0 68270.0 69203.3 69277.7 69611.0 6718.3 6748.8 6832.9 7345.6 7388.2 11327.5 12777.9 12778.0 12868.9 12883.7 10657.4 10763.2 11150.2 11218.4 11317.9 10115.1 10622.7 10691.4 11169.8 11176.1 10924.1 11287.0 11327.2 11370.6 11605.7 7414.7 11548.9 11585.0 11640.7 12184.2 9810.0 11335.4 11544.2 11680.1 12142.0 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5422.92 Median:5121.43 stddev:2987.49 (58.3%) stddev-sans-first:2980.08 09:41:28 INFO - PROCESS | 1639 | Values: 1335.8 1444.2 1593.3 1779.3 1820.3 8050.6 8495.4 8519.5 8520.4 8524.8 7325.7 7618.9 7643.6 7975.7 8234.1 1969.9 2310.5 2345.0 2345.8 2348.0 2328.1 2354.0 2372.2 2372.5 2373.6 2064.0 2307.1 2308.3 2330.7 2331.9 2210.5 2298.6 2318.4 2322.3 2327.9 1744.9 2258.7 2260.5 2260.7 2261.5 7841.2 7893.0 7911.7 7912.7 7933.0 4442.2 5039.9 5205.8 5210.5 5213.1 3197.2 3288.8 3533.2 3539.5 3550.9 908.2 1035.3 1070.5 1084.5 1087.1 10893.6 11047.7 11075.7 11090.9 11098.5 4480.1 4636.3 4710.3 4721.6 4729.0 9179.7 9431.5 9446.0 9448.3 9475.7 5064.7 5178.1 5197.2 5201.1 5203.7 4199.6 4251.1 4660.3 4699.8 4762.8 3770.1 3782.9 3865.7 3875.1 3910.4 1251.3 1259.2 1264.5 1270.7 1276.8 793.3 871.6 894.5 918.4 922.5 5779.4 5806.1 5806.5 5966.1 6095.3 917.9 935.2 936.1 936.8 963.2 3727.2 3843.6 3870.5 3886.7 3944.5 9695.0 10125.8 10159.7 10170.9 10180.5 10094.0 10123.5 10133.0 10140.8 10150.7 7422.6 7756.6 7790.4 7800.1 7839.1 5540.4 5595.6 5651.0 5716.9 5735.1 9099.6 9232.5 9345.0 9365.5 9458.7 9544.8 9547.9 9607.4 9648.1 9684.2 6571.7 6778.9 6946.3 7129.5 7266.9 7826.7 7990.6 7992.3 8001.9 8006.8 5897.0 7163.0 7163.9 7177.4 7195.4 6864.2 6982.4 7033.4 7061.5 7102.9 6635.1 6666.7 6754.3 6756.7 6782.7 3852.6 4530.6 4679.6 4710.1 4719.9 4629.3 4734.7 4764.7 4829.6 4838.9 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4416.36 Median:4840.86 stddev:1349.65 (27.9%) stddev-sans-first:1317.61 09:41:28 INFO - PROCESS | 1639 | Values: 300.8 308.8 309.1 314.2 317.7 4562.7 5255.9 5420.8 5472.0 5487.3 4738.3 5159.3 5283.4 5306.3 5367.9 3135.3 3629.3 3958.5 4013.9 4100.7 3552.9 4043.7 4088.9 4089.8 4123.3 4026.8 4032.3 4070.0 4086.4 4094.0 3889.9 4047.5 4052.3 4088.3 4090.2 3468.5 3482.2 3488.0 3495.0 3508.4 4244.6 5165.8 5191.3 5212.3 5217.9 4440.3 4539.1 4568.7 4604.4 4615.1 4216.5 5064.0 5442.7 5575.4 5617.4 3600.9 3651.3 3817.4 3817.7 3830.0 5171.5 5365.5 5402.1 5596.3 5869.7 3558.7 3856.9 3883.3 3893.2 3925.8 3332.9 3508.9 3973.8 3988.4 3997.2 4091.4 4538.8 4555.7 4571.4 4619.6 4482.9 4824.8 5511.7 5571.9 5637.7 1258.8 1265.8 1268.8 1272.9 1276.2 749.5 808.3 821.8 850.5 871.3 4779.9 4789.4 4837.8 5295.7 5359.5 5772.7 5793.4 5807.0 5807.8 5866.5 4642.9 4800.7 5267.4 5287.0 5317.6 2383.0 2814.3 2877.7 2878.6 2900.2 5079.6 5726.0 5732.7 5752.9 5756.4 5309.9 5332.2 5594.6 5609.5 5623.4 5058.4 5138.5 5633.8 5733.0 5746.6 4517.9 5111.7 5349.3 5388.6 5397.9 4352.6 4576.3 4774.7 5335.3 5384.6 4690.0 5208.3 5587.0 5596.4 5731.7 3332.6 3352.4 3489.9 3618.2 3670.5 4714.9 5203.7 5350.9 5353.5 5482.6 4295.7 4479.0 4918.4 5165.4 5210.3 5313.5 5315.3 5349.0 5354.0 5367.9 4707.0 4999.0 5551.9 5595.7 5601.6 5439.6 5557.8 5605.0 5610.3 5691.2 4827.0 4843.9 5395.0 5598.3 5607.4 09:41:28 INFO - PROCESS | 1639 | 09:41:28 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:21235.93 Median:4489.40 stddev:32290.10 (719.3%) stddev-sans-first:32343.82 09:41:28 INFO - PROCESS | 1639 | Values: 685.0 711.2 715.6 740.4 774.1 13717.0 14136.4 14375.4 14387.5 14396.7 12753.6 12806.9 12817.6 12871.0 12885.0 652.0 667.5 676.9 677.8 816.3 667.9 789.1 818.4 820.0 820.2 861.7 879.5 879.7 880.4 882.7 870.5 874.2 874.7 875.2 875.8 514.3 531.5 630.7 659.7 662.6 13343.7 13920.7 13924.9 13930.8 13934.9 12408.0 12552.2 12657.6 12672.6 12961.7 10529.4 11642.4 11750.4 11757.6 12130.8 4310.4 4337.7 4349.1 4356.1 4515.3 93340.0 95052.1 95230.5 95327.0 95906.0 9626.3 9726.0 9916.0 10270.6 10306.6 19151.5 19176.7 19217.2 19367.9 19447.4 7252.0 7325.9 7368.3 7474.5 7499.4 4021.8 4081.1 4114.8 4138.9 4140.9 3208.3 3299.6 3311.5 3312.5 3319.3 599.6 609.7 610.4 622.5 629.3 479.9 496.1 509.9 548.3 572.0 8517.0 8768.0 8781.5 8800.3 8998.8 577.6 625.8 629.1 630.4 663.7 4202.3 4463.5 4516.4 4523.1 4547.1 119242.4 121292.6 124688.0 126578.4 127132.7 74453.0 89613.7 103086.9 105470.0 107500.0 66658.3 82244.0 84695.0 84852.3 85477.1 7846.9 8476.5 8523.0 8679.2 8685.0 50433.7 57466.0 58427.0 59166.7 61344.4 81871.0 83889.0 87078.0 87227.3 87831.1 11159.0 11185.8 11735.8 11853.0 12189.3 11638.9 12021.9 12131.6 12235.4 12279.9 11777.7 11879.2 12145.5 12172.8 12737.8 12460.9 12548.9 12716.5 12793.4 13334.8 12805.0 13180.2 13300.9 13666.9 13706.6 11139.4 11158.2 11186.3 11210.7 11811.4 11227.6 11273.6 11306.8 11310.4 11484.6 09:41:28 INFO - PROCESS | 1639 | -------- Summary: end -------- 09:41:28 INFO - PROCESS | 1639 | 09:41:29 INFO - TEST-INFO | 1639: exit 0 09:41:29 INFO - TEST-OK | dromaeo_css | took 1119403ms 09:41:29 INFO - TEST-START | kraken 09:41:29 INFO - operating with platform_type : mac_ 09:41:29 INFO - Initialising browser for kraken test... 09:41:29 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmphTiOfH/profile http://localhost:49241/getInfo.html) 09:41:31 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:41:31 INFO - PROCESS | 1649 | colorDepth:24 09:41:31 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:41:31 INFO - PROCESS | 1649 | __metrics 09:41:32 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:41:32 INFO - PROCESS | 1649 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:41:33 INFO - TEST-INFO | 1649: exit 0 09:41:33 INFO - Browser initialized. 09:41:33 INFO - Running cycle 1/1 for kraken test... 09:41:33 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmphTiOfH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:45 INFO - PROCESS | 1650 | RSS: Main: 222158848 09:41:45 INFO - PROCESS | 1650 | 09:41:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:41:48 INFO - PROCESS | 1650 | RSS: Main: 272072704 09:41:48 INFO - PROCESS | 1650 | 09:41:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:41:52 INFO - PROCESS | 1650 | RSS: Main: 283516928 09:41:52 INFO - PROCESS | 1650 | 09:41:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:41:55 INFO - PROCESS | 1650 | RSS: Main: 379699200 09:41:55 INFO - PROCESS | 1650 | 09:41:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:41:58 INFO - PROCESS | 1650 | RSS: Main: 282484736 09:41:58 INFO - PROCESS | 1650 | 09:42:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:42:01 INFO - PROCESS | 1650 | RSS: Main: 283557888 09:42:01 INFO - PROCESS | 1650 | 09:42:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:42:04 INFO - PROCESS | 1650 | RSS: Main: 324960256 09:42:04 INFO - PROCESS | 1650 | 09:42:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:42:07 INFO - PROCESS | 1650 | RSS: Main: 326230016 09:42:07 INFO - PROCESS | 1650 | 09:42:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:42:11 INFO - PROCESS | 1650 | RSS: Main: 324571136 09:42:11 INFO - PROCESS | 1650 | 09:42:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:42:13 INFO - PROCESS | 1650 | RSS: Main: 300142592 09:42:13 INFO - PROCESS | 1650 | 09:42:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:42:16 INFO - PROCESS | 1650 | RSS: Main: 301580288 09:42:16 INFO - PROCESS | 1650 | 09:42:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:42:18 INFO - PROCESS | 1650 | RSS: Main: 303521792 09:42:18 INFO - PROCESS | 1650 | 09:42:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:42:22 INFO - PROCESS | 1650 | RSS: Main: 318701568 09:42:22 INFO - PROCESS | 1650 | 09:42:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:42:25 INFO - PROCESS | 1650 | RSS: Main: 312205312 09:42:25 INFO - PROCESS | 1650 | 09:42:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:42:28 INFO - PROCESS | 1650 | RSS: Main: 356155392 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | __start_tp_report 09:42:28 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:42:28 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:42:28 INFO - PROCESS | 1650 | |i|pagename|runs| 09:42:28 INFO - PROCESS | 1650 | |0;ai-astar;116;114;115;114;114;115;113;114;114;114 09:42:28 INFO - PROCESS | 1650 | |1;audio-beat-detection;144;110;111;147;116;141;100;131;178;139 09:42:28 INFO - PROCESS | 1650 | |2;audio-dft;186;183;184;183;182;182;182;183;185;181 09:42:28 INFO - PROCESS | 1650 | |3;audio-fft;82;58;58;88;60;60;59;59;82;57 09:42:28 INFO - PROCESS | 1650 | |4;audio-oscillator;89;70;91;70;69;68;91;68;68;92 09:42:28 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;87;85;88;86;87;86;90;86;87;85 09:42:28 INFO - PROCESS | 1650 | |6;imaging-darkroom;95;95;95;96;96;94;96;96;96;95 09:42:28 INFO - PROCESS | 1650 | |7;imaging-desaturate;83;84;80;81;79;71;120;138;122;81 09:42:28 INFO - PROCESS | 1650 | |8;json-parse-financial;54;56;56;56;57;61;59;57;55;58 09:42:28 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;65;74;70;76;72;70;70;70;71;71 09:42:28 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;85;87;84;84;85;86;83;97;84;85 09:42:28 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;141;138;141;138;141;142;141;146;194;161 09:42:28 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;165;166;166;165;168;163;166;165;165;166 09:42:28 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;69;72;82;84;88;76;66;66;65;67 09:42:28 INFO - PROCESS | 1650 | __end_tp_report 09:42:28 INFO - PROCESS | 1650 | __start_cc_report 09:42:28 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1939 09:42:28 INFO - PROCESS | 1650 | __end_cc_report 09:42:28 INFO - PROCESS | 1650 | __startTimestamp1484761348053__endTimestamp 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:42:28 INFO - PROCESS | 1650 | Number of tests: 14 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.60 09:42:28 INFO - PROCESS | 1650 | Values: 116.0 114.0 115.0 114.0 114.0 115.0 113.0 114.0 114.0 114.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:140.00 stddev:23.14 (16.5%) stddev-sans-first:24.11 09:42:28 INFO - PROCESS | 1650 | Values: 144.0 110.0 111.0 147.0 116.0 141.0 100.0 131.0 178.0 139.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.00 stddev:1.52 (0.8%) stddev-sans-first:1.20 09:42:28 INFO - PROCESS | 1650 | Values: 186.0 183.0 184.0 183.0 182.0 182.0 182.0 183.0 185.0 181.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.00 stddev:12.36 (20.6%) stddev-sans-first:11.73 09:42:28 INFO - PROCESS | 1650 | Values: 82.0 58.0 58.0 88.0 60.0 60.0 59.0 59.0 82.0 57.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.50 stddev:11.36 (14.3%) stddev-sans-first:11.28 09:42:28 INFO - PROCESS | 1650 | Values: 89.0 70.0 91.0 70.0 69.0 68.0 91.0 68.0 68.0 92.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.58 09:42:28 INFO - PROCESS | 1650 | Values: 87.0 85.0 88.0 86.0 87.0 86.0 90.0 86.0 87.0 85.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 09:42:28 INFO - PROCESS | 1650 | Values: 95.0 95.0 95.0 96.0 96.0 94.0 96.0 96.0 96.0 95.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:80.50 stddev:23.34 (29.0%) stddev-sans-first:24.43 09:42:28 INFO - PROCESS | 1650 | Values: 83.0 84.0 80.0 81.0 79.0 71.0 120.0 138.0 122.0 81.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:2.02 (3.6%) stddev-sans-first:1.86 09:42:28 INFO - PROCESS | 1650 | Values: 54.0 56.0 56.0 56.0 57.0 61.0 59.0 57.0 55.0 58.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:2.88 (4.1%) stddev-sans-first:2.13 09:42:28 INFO - PROCESS | 1650 | Values: 65.0 74.0 70.0 76.0 72.0 70.0 70.0 70.0 71.0 71.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.00 stddev:4.03 (4.7%) stddev-sans-first:4.26 09:42:28 INFO - PROCESS | 1650 | Values: 85.0 87.0 84.0 84.0 85.0 86.0 83.0 97.0 84.0 85.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:141.50 stddev:17.38 (12.3%) stddev-sans-first:18.24 09:42:28 INFO - PROCESS | 1650 | Values: 141.0 138.0 141.0 138.0 141.0 142.0 141.0 146.0 194.0 161.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:166.00 stddev:1.27 (0.8%) stddev-sans-first:1.33 09:42:28 INFO - PROCESS | 1650 | Values: 165.0 166.0 166.0 165.0 168.0 163.0 166.0 165.0 165.0 166.0 09:42:28 INFO - PROCESS | 1650 | 09:42:28 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:74.00 stddev:8.49 (11.5%) stddev-sans-first:8.85 09:42:28 INFO - PROCESS | 1650 | Values: 69.0 72.0 82.0 84.0 88.0 76.0 66.0 66.0 65.0 67.0 09:42:28 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:42:28 INFO - PROCESS | 1650 | 09:42:29 INFO - TEST-INFO | 1650: exit 0 09:42:29 INFO - TEST-OK | kraken | took 60008ms 09:42:29 INFO - SUITE-END | took 1179s 09:42:29 INFO - Completed test suite (00:19:39) 09:42:29 INFO - javascript benchmark 09:42:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2695.407010457699, 3135.937281254375, 3302.4385854404754, 3343.6990670839637, 3361.0757041813513, 5686.3744988051285, 5701.942980570193, 5741.827745167651, 5777.0756678931375, 5811.535077193825, 5731.799387021455, 5819.592628516006, 5822.475977162055, 5837.7373018214175, 5841.299044114701, 5254.448282930294, 5268.394134674504, 5280.366356037281, 5287.312649355578, 5326.307580014612, 5189.195674670432, 5199.402068762103, 5199.532042116209, 5353.625246232746, 5368.409474957752, 5212.869678258055, 5260.631755777097, 5263.868403289929, 5264.947010597876, 5298.36419629645, 5269.051570717288, 5307.575393968474, 5336.759845806948, 5350.464953504657, 5352.090144675376, 5101.438841727407, 5182.015417070782, 5274.709890955995, 5275.366955965271, 5280.155175171993, 4681.765911704435, 5630.324361076656, 5651.943480565183, 5652.236948012007, 5718.5711071669375, 5138.897222055575, 5986.802639472079, 6171.074338849208, 6253.656048917308, 6286.402791734778, 5247.370315562119, 5652.858678533028, 6262.937370626281, 6269.749210031593, 6298.6535740534255, 4844.660873738875, 5683.630564013367, 5775.393583673654, 5775.509081728058, 5797.971010144923, 6285.811425657238, 6367.872642547123, 6376.585521941059, 6383.71273292703, 6394.712237949303, 6188.628682279078, 6216.502679785697, 6222.0, 6226.159468471789, 6240.251169859646, 6076.392360763888, 6161.568690191733, 6248.31268560458, 6259.843503912439, 6272.560920735505, 6128.208708139665, 6184.165137706442, 6204.162438070893, 6207.6585787782105, 6237.937620623827, 6198.194234749454, 6241.687915604201, 6244.750209991549, 6250.343713910065, 6257.374262573797, 4663.973925736333, 4947.356843610308, 4959.752012399365, 4982.9252561211615, 4986.152354099811, 4360.4767427908855, 4387.341898715218, 4391.758453285036, 4403.3394990751, 4421.867343979686, 5763.193152958589, 5770.596058275881, 5771.192033115367, 5783.826485205451, 5815.563832712563, 5292.179712144623, 6277.591956522811, 6305.936940630627, 6308.3060863305, 6311.0, 5517.503424691797, 5616.213730077792, 5637.7181140942785, 5768.884622307614, 5775.249217601685, 5364.0, 5445.047116754552, 5462.754176062087, 5465.890682186334, 5469.507744303032, 5697.971510142423, 6024.728887200086, 6360.586561873463, 6389.808305750835, 6392.169018027626, 6128.816135515942, 6137.693115344304, 6307.3377295383325, 6314.652694101685, 6330.335314791881, 6218.191635087591, 6297.685115744286, 6310.2743184534365, 6312.210973628296, 6371.5221358397375, 6231.127642130196, 6247.96876015617, 6259.436650701458, 6262.46769024638, 6270.467010304175, 5952.553558483044, 6298.212723409574, 6301.716422761078, 6305.621662700252, 6316.684165791784, 5853.795117170878, 6089.964706000079, 6093.542984276109, 6121.84695382619, 6175.2589689237575, 6156.661383623944, 6164.106204600397, 6164.722587483484, 6176.81469555914, 6189.50483961273, 5832.562557808266, 5997.760089596367, 6014.909776353271, 6022.3977602241175, 6028.156058151774, 5905.202797622438, 6043.214382130295, 6108.129596501795, 6140.631562106288, 6158.014717645335, 6006.519478441802, 6059.151718759465, 6069.4840938518955, 6076.270847498152, 6124.846878828065], "value": 5685.169935445449, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.26381865649665, 488.6213184781796, 517.9637425380223, 533.4025891002076, 555.6055200807426, 28701.569476457844, 39336.0, 40252.99367515813, 40417.78746637598, 42448.787756061174, 41423.58576414235, 41803.372949405784, 41848.37227441591, 41883.790581047055, 41897.0, 8328.333733301351, 9246.214071803905, 9424.81150376992, 9431.764205894873, 9454.0, 8354.582270886462, 11080.05819505343, 11278.266912650692, 11294.830577541343, 11437.97058264756, 11189.992900638941, 11245.55017799287, 11256.887431125666, 11262.493187806527, 11290.661280161608, 8392.496450212946, 8617.913820861837, 9634.325597208199, 11238.943805280962, 11368.204225704205, 10971.725706857313, 11260.268082574607, 11261.831072533918, 11429.428528573539, 11445.198836081478, 39818.40272395917, 40303.39544906829, 40343.7982810087, 40345.0, 40375.0, 3212.5341825435416, 3220.6457289698114, 3256.234784825564, 3306.669333066698, 3322.7507936904585, 29397.0, 29723.256918576993, 29779.851100744574, 30108.0, 30449.695503045128, 3020.2600362911007, 3084.6144231971, 3088.5984821973225, 3105.6739042400673, 3127.4526957782523, 426444.0, 506970.0, 506972.0, 507583.0, 508756.0, 61312.69343653253, 61765.0, 61843.690781546706, 61987.45031374252, 62101.68949155225, 34719.13202169966, 35180.47229291563, 35606.82196589052, 36274.0, 36284.455733164024, 15856.52430427089, 16014.199290035685, 16031.599210019609, 16049.358025679076, 16056.839431605767, 9753.463559504295, 11120.0, 11444.599439019594, 12218.877811221952, 12299.569515066929, 18791.624167516573, 18805.15376808047, 18851.0, 19119.33082342139, 19354.612907741765, 6961.37347638715, 7599.846063883537, 7750.14748377678, 7756.379489640815, 7790.649420776079, 863.6761214544546, 949.3354651743668, 968.2496065549115, 1073.6510634043966, 1091.47063674118, 29505.704942950295, 29723.256918576775, 29950.55074173889, 30237.0, 30248.243793905327, 764.6788348893479, 1023.7663615343643, 1051.8737751469873, 1056.6882769582992, 1123.2586492914636, 15361.692766144837, 15469.5359139226, 15562.922185389001, 15694.76457853133, 15727.44953926607, 688233.0, 779633.0, 790806.0, 791928.0, 796226.0, 57349.0, 57906.42093579094, 57968.0, 60453.0, 60491.39508604946, 1292.1278137257502, 1322.4049177869997, 1341.6511706956262, 1346.6027521880874, 1359.0758284366527, 29159.271018224714, 31408.842955785076, 32184.35631287408, 32363.838180808943, 32573.348533028733, 55413.722931385084, 56108.0, 56146.43853561412, 56207.15689264583, 56395.0, 631127.0, 635894.0, 636834.8158259179, 637399.0, 638833.0, 35711.0, 36659.26681466409, 37531.624683752816, 42432.0, 42922.0, 37629.05927351728, 45197.54802452065, 46148.0, 46225.76887115543, 46615.80457758818, 37441.251174975805, 37909.62090379061, 38239.61760382361, 38350.0, 38500.42249366206, 36959.815200923826, 37737.43393849149, 38252.80873595614, 38392.424113638874, 38857.41713874238, 37002.44496332611, 37132.44301335428, 37617.0, 38089.66686165971, 38295.2340953185, 32010.35979280448, 33664.49503257502, 34282.0, 34356.31287374189, 34402.48396274008, 21522.784772152077, 25452.0, 25511.74488255094, 25852.482950341266, 25856.482870342865], "value": 20119.61059004238, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.85767977449467, 351.83463772027136, 375.4049831017835, 384.76337052712574, 402.93754468057443, 14648.414063437463, 14786.038907471007, 15661.53015409538, 19606.803931960676, 20226.19095236189, 19679.901600492012, 19783.70324445131, 20144.294949676765, 20275.986200689982, 20567.794322056776, 5047.545720885119, 5649.1526271059365, 5871.383504732005, 5881.705914704269, 5888.411158884121, 5365.739051322936, 5503.367112782042, 5733.5413166946755, 5795.884082318351, 5816.563757718188, 5751.597388182829, 5760.193572899798, 5768.625039372428, 5769.048107062351, 5769.884602307951, 5645.689487078209, 5935.0503919372695, 5991.700414979234, 6037.0642550404755, 6048.425399587032, 5625.099984002581, 5766.942330576724, 5837.474627283523, 5864.941350586482, 5868.2664666916635, 12691.936540317332, 12962.0, 12975.416106275146, 12979.286139262336, 18083.81916180848, 2180.433087397272, 2297.127091705158, 2327.44141406063, 2352.6706261123454, 2509.7866681332107, 9533.856992145124, 9718.0, 9853.556589953398, 10264.897351026544, 10284.640037598723, 1801.7207332863427, 1836.6051298970785, 1853.8424233940132, 1868.4394681595468, 1925.7015162649811, 94351.0, 99592.50203748935, 100665.99334006713, 106353.46823265911, 106466.0, 11124.332540047622, 11185.66443006711, 11203.327800332007, 11415.20093593457, 11668.0, 14446.855531444551, 14457.710845783025, 14462.0, 14489.275536223147, 14490.492832751013, 6980.441564674814, 8066.5563394012715, 8293.460925039853, 8435.873461898076, 8465.95767021161, 8081.757547273592, 8086.0, 8088.83822323562, 8257.504549726915, 8266.13205613414, 3822.0827001519433, 4355.7822108894425, 4373.846915357947, 4398.318260669601, 4525.389072475174, 1629.8940568862986, 1874.728164416152, 1919.395390452003, 1921.164293532318, 1922.7884932657396, 628.7927179814767, 678.0168755304828, 752.8218338300542, 896.2516298890499, 936.6019441737235, 17372.534944467636, 18439.035607344485, 18938.715919261223, 19253.61492770137, 21217.257395991066, 859.7936495241097, 902.8961669407971, 932.9160375566096, 933.3839665820526, 942.7878727286305, 10058.849117263248, 11047.72380690489, 11050.226484146033, 11057.778844423066, 11094.667159985212, 526182.0, 529975.0, 532839.0, 543296.2835185799, 543997.0, 118124.66999412168, 122464.38767806104, 123249.0, 127654.0, 128222.35888820497, 938.1369140390857, 942.6417961174847, 946.8248374050822, 953.5565961827645, 967.9177269931994, 17868.731969020737, 18172.727409089137, 18328.0, 18456.35402760897, 18592.977386243885, 39780.0, 47572.0, 48011.0, 48470.0, 49654.0, 65804.0, 68270.0, 69203.30796691969, 69277.65361173161, 69611.0, 6718.294579069127, 6748.785218660491, 6832.93167068323, 7345.632718364167, 7388.187299396955, 11327.490262938352, 12777.913877320532, 12778.0, 12868.935655321664, 12883.677908052374, 10657.413842238751, 10763.192760542834, 11150.219484635998, 11218.35403493884, 11317.943410282896, 10115.089641932262, 10622.681319560426, 10691.411972341595, 11169.832452513381, 11176.050035747052, 10924.126069914546, 11287.0, 11327.15046371509, 11370.602028928948, 11605.709857253636, 7414.703411863465, 11548.94225528867, 11585.015273701829, 11640.740592634076, 12184.233286173278, 9809.950950245204, 11335.376554289593, 11544.19190656646, 11680.123990700902, 12142.0], "value": 9095.426916283945, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.8178012458973, 1444.1696024785751, 1593.2989484626764, 1779.3416435918712, 1820.2900868661225, 8050.557219352933, 8495.447795893262, 8519.488830670163, 8520.446170998881, 8524.829503409928, 7325.6703448344815, 7618.88571671424, 7643.61781910905, 7975.680972761097, 8234.135415781346, 1969.9310524131586, 2310.4570425949887, 2344.988275058622, 2345.812335013195, 2348.0255693887016, 2328.138588722175, 2353.976460235401, 2372.240882917467, 2372.525494901018, 2373.620220764678, 2063.998960504154, 2307.1463558483383, 2308.319045881469, 2330.6715172351765, 2331.8834058297102, 2210.5136869888597, 2298.620727579964, 2318.374039009475, 2322.3381336319235, 2327.8719670418122, 1744.921478533469, 2258.6837842702034, 2260.457490202356, 2260.660900864867, 2261.536385041079, 7841.176676448948, 7893.0, 7911.723089691834, 7912.723054693116, 7933.048034235871, 4442.2226110430865, 5039.916417970154, 5205.791768329263, 5210.531052205281, 5213.113770658997, 3197.1847178969215, 3288.819114948677, 3533.152043509565, 3539.451385035298, 3550.8757193498104, 908.2189317187214, 1035.3425574760006, 1070.544060078293, 1084.452351562456, 1087.141158484796, 10893.618723344724, 11047.668569942834, 11075.667729968114, 11090.944545277222, 11098.500567474483, 4480.148771733426, 4636.2581986882615, 4710.26990816424, 4721.622270218374, 4728.976355118271, 9179.724608261762, 9431.48126853016, 9446.0, 9448.291378146667, 9475.715728528156, 5064.7214403207445, 5178.145605975047, 5197.168453047494, 5201.063808514428, 5203.713795741195, 4199.551010226989, 4251.128518653669, 4660.300954856799, 4699.835505757351, 4762.809487620457, 3770.0763312988493, 3782.8865134046023, 3865.6714179295077, 3875.1280961783373, 3910.432987216838, 1251.28676654308, 1259.1500737002486, 1264.4752427742567, 1270.7140893299118, 1276.8276282701715, 793.3296364572043, 871.5772850167664, 894.5348418822176, 918.3846822628855, 922.4834092907993, 5779.3642699303045, 5806.129080637854, 5806.477417032403, 5966.075258334965, 6095.329513753483, 917.8990311065777, 935.1817159985013, 936.1060187521038, 936.7751739582451, 963.2342287881012, 3727.1986522897705, 3843.615638436134, 3870.5161854768153, 3886.650201481824, 3944.4872166618156, 9694.951525242328, 10125.848112278321, 10159.695209143738, 10170.949145254375, 10180.541875615616, 10093.999999999853, 10123.544440500047, 10132.986701329808, 10140.847887281696, 10150.746231344128, 7422.6288685565505, 7756.612169391621, 7790.376769858398, 7800.102988156321, 7839.137694853562, 5540.418256083053, 5595.5523558116065, 5651.0, 5716.914246286392, 5735.111057785883, 9099.636014559608, 9232.538373081185, 9345.0, 9365.484898330657, 9458.716238512856, 9544.809103817746, 9547.904520954979, 9607.375520591138, 9648.083432073941, 9684.176844968257, 6571.737130514726, 6778.8644227116165, 6946.340097690711, 7129.500934934505, 7266.927330726625, 7826.686932522635, 7990.6004699765945, 7992.280694737501, 8001.879971800544, 8006.759797206093, 5897.0, 7163.0, 7163.8567228656175, 7177.354038136593, 7195.424366050599, 6864.24493305726, 6982.406495447944, 7033.366996970297, 7061.540999834994, 7102.893456598258, 6635.104260924713, 6666.666666666744, 6754.290799466182, 6756.6959486822225, 6782.728690852508, 3852.595477474937, 4530.637548996138, 4679.625629949664, 4710.081534100955, 4719.905601888012, 4629.282461218517, 4734.745292497422, 4764.737939413366, 4829.637777166657, 4838.903221935471], "value": 4393.777147492796, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7669056481227, 308.822427104415, 309.14983794564944, 314.1627562545814, 317.6839045150076, 4562.699630605278, 5255.92116118258, 5420.83737487875, 5472.042392581302, 5487.341519017724, 4738.312944623028, 5159.303494028305, 5283.445238249986, 5306.283651707018, 5367.946320536794, 3135.310231749023, 3629.2922880038336, 3958.5051868516434, 4013.8595149169787, 4100.65144462721, 3552.9111772205642, 4043.6967227457912, 4088.9182216355657, 4089.7750623715833, 4123.299039163335, 4026.838926442939, 4032.2943484890175, 4070.0, 4086.4459712679577, 4093.97953010236, 3889.880015391618, 4047.5142982841953, 4052.2705912935658, 4088.262488442415, 4090.202410529948, 3468.531748213984, 3482.1990942083207, 3488.023353461035, 3494.9825250873837, 3508.3509550733197, 4244.61798438139, 5165.793368265265, 5191.325127733408, 5212.348456442945, 5217.904240109581, 4440.289553671397, 4539.083823059339, 4568.725876447424, 4604.398514839105, 4615.076984603059, 4216.536181020086, 5064.037832811754, 5442.673439593597, 5575.414581468928, 5617.4382561743905, 3600.9279814403567, 3651.2514934438377, 3817.4273858921106, 3817.6976914968354, 3829.950976227705, 5171.456997015334, 5365.463453654604, 5402.138075731873, 5596.300462442195, 5869.677167755772, 3558.7153027757718, 3856.8842934712, 3883.339832228528, 3893.1824316893203, 3925.784081875467, 3332.900012999614, 3508.8947331580093, 3973.8211780469946, 3988.441618173458, 3997.2405243003736, 4091.447654566655, 4538.841140560065, 4555.681102322806, 4571.428571428572, 4619.584237418649, 4482.884395345204, 4824.758762061883, 5511.669299842022, 5571.916421253684, 5637.7181140942785, 1258.7985922252396, 1265.7531781302741, 1268.7589358021946, 1272.8727127287382, 1276.2087505746497, 749.4566439331442, 808.2604416958535, 821.7781199076215, 850.5073595839386, 871.2507243770352, 4779.928301075487, 4789.353437285991, 4837.839458066061, 5295.735213239323, 5359.490848369399, 5772.6536407815665, 5793.391693872167, 5807.0, 5807.796727114531, 5866.472017518444, 4642.930356044662, 4800.73595952226, 5267.367915850123, 5287.0, 5317.627766056339, 2383.010186494262, 2814.2542226310043, 2877.6690680572, 2878.6107530749427, 2900.2314386687544, 5079.593632509391, 5726.0, 5732.656040637575, 5752.913706294409, 5756.380846714735, 5309.858310517802, 5332.200169974457, 5594.608377413623, 5609.523190528769, 5623.437656234344, 5058.35603428887, 5138.456464050319, 5633.8309850704545, 5733.02210737295, 5746.569007324468, 4517.860087239468, 5111.718855462986, 5349.331333583302, 5388.595855310789, 5397.920415916911, 4352.608265256142, 4576.254247451636, 4774.689645173053, 5335.306410166653, 5384.623076384615, 4690.0, 5208.348956380452, 5587.033206423484, 5596.440355964533, 5731.656100633975, 3332.5501057356946, 3352.396568617672, 3489.865793617034, 3618.187810212464, 3670.541182352206, 4714.905701885875, 5203.661761985459, 5350.919736203882, 5353.505092870013, 5482.5613950883035, 4295.677824163137, 4478.9552104478535, 4918.360613120271, 5165.431802501797, 5210.27056212123, 5313.52178303939, 5315.3423288355825, 5348.973255133699, 5354.038011871266, 5367.865803354947, 4706.964467817074, 4998.950010499849, 5551.944480555304, 5595.748191331319, 5601.60788744784, 5439.619226654097, 5557.833265002057, 5604.971975140098, 5610.270664813548, 5691.174779657009, 4827.010462855176, 4843.878903027453, 5395.028894798974, 5598.3282006159525, 5607.411221821813], "value": 3984.9026869515824, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.9999999999999, 711.2496316386217, 715.5849607227808, 740.3558903753733, 774.1445702498738, 13716.999999999987, 14136.434542618283, 14375.424983000694, 14387.496437624672, 14396.712065758691, 12753.617391478247, 12806.871931280684, 12817.615471535846, 12871.0, 12885.0, 651.967401629919, 667.5160508631252, 676.8815457294978, 677.8203775999364, 816.3224523645375, 667.9165104361955, 789.1319548496665, 818.3739439328918, 819.9918000819976, 820.1634332980357, 861.6725644255174, 879.5118709116438, 879.7492714576347, 880.4365206267962, 882.74400423877, 870.5473153959937, 874.2175752701361, 874.6938571499987, 875.2254254984321, 875.829213303406, 514.3210961530781, 531.529596307267, 630.7413960276265, 659.7328082126746, 662.6123717625195, 13343.73312533744, 13920.721585568232, 13924.860751392458, 13930.815880650156, 13934.860651393457, 12408.007359411225, 12552.184108032949, 12657.623612713252, 12672.556460523929, 12961.740765184737, 10529.420881851494, 11642.417879106011, 11750.353730544817, 11757.58848440309, 12130.818037729441, 4310.374995625648, 4337.674674399433, 4349.108432771274, 4356.106998065421, 4515.300128480058, 93340.0, 95052.09895802115, 95230.52384738032, 95327.0, 95906.04093959041, 9626.32615716893, 9725.951370243103, 9916.0, 10270.6405275815, 10306.639267625596, 19151.521211969535, 19176.71234931477, 19217.231310747415, 19367.903160484108, 19447.416577502696, 7251.963740181371, 7325.853482930418, 7368.300011498899, 7474.476786624884, 7499.362809037612, 4021.7788021658503, 4081.1429599783783, 4114.7942602870335, 4138.875833724993, 4140.937885931713, 3208.3102133041507, 3299.6370399256543, 3311.519829624739, 3312.519684645761, 3319.3195394943978, 599.5772980049055, 609.6860117039728, 610.386561505684, 622.5331001748689, 629.2637613991641, 479.85844175967475, 496.0957266314131, 509.8725318670332, 548.2625868207255, 571.9685417302088, 8517.0, 8767.956160219159, 8781.473111613324, 8800.295976321879, 8998.820023599623, 577.5841394196173, 625.7841044839523, 629.0941044895427, 630.3538872655565, 663.7444583835259, 4202.348635961431, 4463.509014008457, 4516.36770852081, 4523.077841095587, 4547.136044151601, 119242.40378798051, 121292.58042484437, 124688.0, 126578.36710816387, 127132.7286727121, 74453.0, 89613.65579516313, 103086.94109474667, 105470.0, 107500.0, 66658.33341666618, 82244.0, 84695.0, 84852.33312167767, 85477.14522854691, 7846.921530784619, 8476.49141051539, 8522.957385213282, 8679.218870301704, 8684.956575217084, 50433.74783126061, 57465.966073563344, 58427.0, 59166.70416647889, 61344.38655613565, 81871.0, 83888.96666200047, 87078.0, 87227.25545489181, 87831.1216887823, 11159.0, 11185.832212516982, 11735.76528469443, 11853.0, 12189.268643881396, 11638.88361116378, 12021.939890300842, 12131.575394861138, 12235.388230588256, 12279.877201227873, 11777.705557361134, 11879.168458207843, 12145.514179432723, 12172.817407739069, 12737.808932866199, 12460.87539124597, 12548.937255313665, 12716.491340346653, 12793.42429590652, 13334.799978000532, 12805.0, 13180.209187448783, 13300.933495332461, 13666.93166534161, 13706.588802335946, 11139.377158479141, 11158.163137764537, 11186.32882027081, 11210.719732006439, 11811.409429528661, 11227.607033753779, 11273.605423810128, 11306.83039754388, 11310.434478276218, 11484.598039068593], "value": 7167.677758945946, "unit": "score"}], "name": "dromaeo_css", "value": 7122.701920559145, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 115.0, 114.0, 114.0, 115.0, 113.0, 114.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 111.0, 147.0, 116.0, 141.0, 100.0, 131.0, 178.0, 139.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 184.0, 183.0, 182.0, 182.0, 182.0, 183.0, 185.0, 181.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 58.0, 88.0, 60.0, 60.0, 59.0, 59.0, 82.0, 57.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 91.0, 70.0, 69.0, 68.0, 91.0, 68.0, 68.0, 92.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 86.0, 87.0, 86.0, 90.0, 86.0, 87.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 94.0, 96.0, 96.0, 96.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 80.0, 81.0, 79.0, 71.0, 120.0, 138.0, 122.0, 81.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 56.0, 56.0, 57.0, 61.0, 59.0, 57.0, 55.0, 58.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 70.0, 76.0, 72.0, 70.0, 70.0, 70.0, 71.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 84.0, 84.0, 85.0, 86.0, 83.0, 97.0, 84.0, 85.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 141.0, 138.0, 141.0, 142.0, 141.0, 146.0, 194.0, 161.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 166.0, 165.0, 168.0, 163.0, 166.0, 165.0, 165.0, 166.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 82.0, 84.0, 88.0, 76.0, 66.0, 66.0, 65.0, 67.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1450.1, "alertThreshold": 2.0}]} 09:42:29 INFO - Return code: 0 09:42:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:42:29 INFO - # TBPL SUCCESS # 09:42:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:42:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:42:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:42:29 INFO - 2017-01-18 09:42:29.878 screenresolution[1654:9810] starting screenresolution argv=screenresolution get 09:42:29 INFO - 2017-01-18 09:42:29.881 screenresolution[1654:9810] Display 0: 1600x1200x32@50 09:42:29 INFO - 2017-01-18 09:42:29.887 screenresolution[1655:9813] starting screenresolution argv=screenresolution list 09:42:29 INFO - Available Modes on Display 0 09:42:29 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 09:42:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:42:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:42:29 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 09:42:29 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 09:42:30 INFO - Graphics/Displays: 09:42:30 INFO - Intel Iris: 09:42:30 INFO - Chipset Model: Intel Iris 09:42:30 INFO - Type: GPU 09:42:30 INFO - Bus: Built-In 09:42:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:42:30 INFO - Vendor: Intel (0x8086) 09:42:30 INFO - Device ID: 0x0a2e 09:42:30 INFO - Revision ID: 0x0009 09:42:30 INFO - Displays: 09:42:30 INFO - Display: 09:42:30 INFO - Resolution: 1600 x 1200 @ 50 Hz 09:42:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:42:30 INFO - Display Serial Number: 1600x1200 50 09:42:30 INFO - Main Display: Yes 09:42:30 INFO - Mirror: Off 09:42:30 INFO - Online: Yes 09:42:30 INFO - Rotation: Supported 09:42:30 INFO - Return code: 0 09:42:30 INFO - Running post-action listener: _resource_record_post_action 09:42:30 INFO - [mozharness: 2017-01-18 17:42:30.128290Z] Finished run-tests step (success) 09:42:30 INFO - Running post-run listener: _resource_record_post_run 09:42:30 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 39015424; Write bytes: 657806848; Read time: 382; Write time: 2326 09:42:30 INFO - TinderboxPrint: CPU usage
53.5% 09:42:30 INFO - TinderboxPrint: I/O read bytes / time
39,015,424 / 382 09:42:30 INFO - TinderboxPrint: I/O write bytes / time
657,806,848 / 2,326 09:42:30 INFO - TinderboxPrint: CPU idle
2,255.2 (46.5%) 09:42:30 INFO - TinderboxPrint: CPU system
966.9 (20.0%) 09:42:30 INFO - TinderboxPrint: CPU user
1,623.6 (33.5%) 09:42:30 INFO - TinderboxPrint: Swap in / out
388,009,984 / 0 09:42:30 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345446912; Write bytes: 346011648; Read time: 26884; Write time: 651 09:42:30 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 37000192; Write bytes: 304897536; Read time: 360; Write time: 1649 09:42:30 INFO - Running post-run listener: _upload_blobber_files 09:42:30 INFO - Blob upload gear active. 09:42:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:42:30 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 09:42:30 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'] 09:42:30 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 09:42:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:42:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:42:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:32 INFO - (blobuploader) - INFO - Done attempting. 09:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:32 INFO - (blobuploader) - INFO - Done attempting. 09:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:35 INFO - (blobuploader) - INFO - Done attempting. 09:42:35 INFO - (blobuploader) - INFO - Iteration through files over. 09:42:35 INFO - Return code: 0 09:42:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:42:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:42:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a656938eaa0bd332417e53dcf762dbd2b2ed684aec5ca9794ef6b4c210f691c190233918f7f7b47e8dfaddc5012f682f394534380b0fdec86bd6b77d3af5543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb9a85a7b12057d0a5d3fa243a9c15314512c013e3289845bdbfd21c4bb0ba09b10f9701374ded2d75034a20b9d5f89e4edf16d7bdddc25af4b6fe693452b35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95606aa80133dbd14f783ae1c7d4c509c80a74809937bb4810d314539f98f6f961fdf8866bd1ae3fdd31d5998138540c7d960c3e96fb5f1053754649056a8de3"} 09:42:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:42:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:42:35 INFO - Contents: 09:42:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a656938eaa0bd332417e53dcf762dbd2b2ed684aec5ca9794ef6b4c210f691c190233918f7f7b47e8dfaddc5012f682f394534380b0fdec86bd6b77d3af5543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb9a85a7b12057d0a5d3fa243a9c15314512c013e3289845bdbfd21c4bb0ba09b10f9701374ded2d75034a20b9d5f89e4edf16d7bdddc25af4b6fe693452b35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95606aa80133dbd14f783ae1c7d4c509c80a74809937bb4810d314539f98f6f961fdf8866bd1ae3fdd31d5998138540c7d960c3e96fb5f1053754649056a8de3"} 09:42:35 INFO - Running post-run listener: copy_logs_to_upload_dir 09:42:35 INFO - Copying logs to upload dir... 09:42:35 INFO - mkdir: /builds/slave/test/build/upload/logs 09:42:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.253435 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-18 09:42:35.193607) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 09:42:35.197195) ========= 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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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/5a656938eaa0bd332417e53dcf762dbd2b2ed684aec5ca9794ef6b4c210f691c190233918f7f7b47e8dfaddc5012f682f394534380b0fdec86bd6b77d3af5543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb9a85a7b12057d0a5d3fa243a9c15314512c013e3289845bdbfd21c4bb0ba09b10f9701374ded2d75034a20b9d5f89e4edf16d7bdddc25af4b6fe693452b35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95606aa80133dbd14f783ae1c7d4c509c80a74809937bb4810d314539f98f6f961fdf8866bd1ae3fdd31d5998138540c7d960c3e96fb5f1053754649056a8de3"} build_url:https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/OwKOVnl7QgKFGyHc78dBrQ/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/5a656938eaa0bd332417e53dcf762dbd2b2ed684aec5ca9794ef6b4c210f691c190233918f7f7b47e8dfaddc5012f682f394534380b0fdec86bd6b77d3af5543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0eb9a85a7b12057d0a5d3fa243a9c15314512c013e3289845bdbfd21c4bb0ba09b10f9701374ded2d75034a20b9d5f89e4edf16d7bdddc25af4b6fe693452b35", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/95606aa80133dbd14f783ae1c7d4c509c80a74809937bb4810d314539f98f6f961fdf8866bd1ae3fdd31d5998138540c7d960c3e96fb5f1053754649056a8de3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 09:42:35.232073) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:42:35.232446) ========= 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.0m0BGkdg9g/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.qH9fd4dc2x/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005108 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:42:35.284817) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 09:42:35.285567) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 09:42:35.286283) ========= ========= Total master_lag: 0.19 =========