builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0179 starttime: 1482342932.61 results: success (0) buildid: 20161221080053 builduid: 711cf7c8c0d7442aa15334aecabdf4df revision: d6ae871ef73bb86575f072e2305d8b2eff2411d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:32.614920) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:32.615345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:32.615615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:32.648166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:32.648472) ========= 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.o14UtmXpF0/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.aWp1OX5w0H/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-21 09:55:32-- 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% 14.4M=0.001s 2016-12-21 09:55:33 (14.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.373061 ========= master_lag: 0.45 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:33.469842) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:33.470149) ========= 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.o14UtmXpF0/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.aWp1OX5w0H/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.026689 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:33.521514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:55:33.522320) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d6ae871ef73bb86575f072e2305d8b2eff2411d7 --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 d6ae871ef73bb86575f072e2305d8b2eff2411d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o14UtmXpF0/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.aWp1OX5w0H/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-21 09:55:33,619 truncating revision to first 12 chars 2016-12-21 09:55:33,619 Setting DEBUG logging. 2016-12-21 09:55:33,619 attempt 1/10 2016-12-21 09:55:33,619 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d6ae871ef73b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 09:55:34,702 unpacking tar archive at: mozilla-aurora-d6ae871ef73b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.353344 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:55:34.904289) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:34.905161) ========= script_repo_revision: d6ae871ef73bb86575f072e2305d8b2eff2411d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:34.906224) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:34.906951) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 09:55:34.925859) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-21 09:55:34.926246) ========= /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.o14UtmXpF0/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.aWp1OX5w0H/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:55:35 INFO - MultiFileLogger online at 20161221 09:55:35 in /builds/slave/test 09:55:35 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:55:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:55:35 INFO - {'append_to_log': False, 09:55:35 INFO - 'base_work_dir': '/builds/slave/test', 09:55:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:55:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:55:35 INFO - 'branch': 'Mozilla-Aurora', 09:55:35 INFO - 'buildbot_json_path': 'buildprops.json', 09:55:35 INFO - 'config_files': ('talos/mac_config.py',), 09:55:35 INFO - 'default_actions': ('clobber', 09:55:35 INFO - 'read-buildbot-config', 09:55:35 INFO - 'download-and-extract', 09:55:35 INFO - 'populate-webroot', 09:55:35 INFO - 'create-virtualenv', 09:55:35 INFO - 'install', 09:55:35 INFO - 'run-tests'), 09:55:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:55:35 INFO - 'download_minidump_stackwalk': True, 09:55:35 INFO - 'download_symbols': 'ondemand', 09:55:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:55:35 INFO - 'tooltool.py': '/tools/tooltool.py', 09:55:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:55:35 INFO - '/tools/misc-python/virtualenv.py')}, 09:55:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:55:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:55:35 INFO - 'installer_path': 'installer.exe', 09:55:35 INFO - 'log_level': 'info', 09:55:35 INFO - 'log_name': 'talos', 09:55:35 INFO - 'log_to_console': True, 09:55:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:55:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:55:35 INFO - 'opt_config_files': (), 09:55:35 INFO - 'pip_index': False, 09:55:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:55:35 INFO - 'cmd': ('bash', 09:55:35 INFO - '-c', 09:55:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:55:35 INFO - 'enabled': True, 09:55:35 INFO - 'halt_on_failure': False, 09:55:35 INFO - 'name': 'check_screen_resolution'},), 09:55:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:55:35 INFO - 'cmd': ('bash', 09:55:35 INFO - '-c', 09:55:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:55:35 INFO - 'enabled': True, 09:55:35 INFO - 'halt_on_failure': False, 09:55:35 INFO - 'name': 'check_screen_resolution'},), 09:55:35 INFO - 'run_cmd_checks_enabled': True, 09:55:35 INFO - 'sps_profile': False, 09:55:35 INFO - 'sps_profile_interval': 0, 09:55:35 INFO - 'suite': 'dromaeojs', 09:55:35 INFO - 'system_bits': '32', 09:55:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:55:35 INFO - 'title': 't-yosemite-r7-0179', 09:55:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:55:35 INFO - 'use_talos_json': True, 09:55:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:55:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:55:35 INFO - 'work_dir': 'build'} 09:55:35 INFO - [mozharness: 2016-12-21 17:55:35.126850Z] Running clobber step. 09:55:35 INFO - Running pre-action listener: _resource_record_pre_action 09:55:35 INFO - Running main action method: clobber 09:55:35 INFO - rmtree: /builds/slave/test/build 09:55:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:55:37 INFO - Running post-action listener: _resource_record_post_action 09:55:37 INFO - [mozharness: 2016-12-21 17:55:37.528377Z] Finished clobber step (success) 09:55:37 INFO - [mozharness: 2016-12-21 17:55:37.528507Z] Running read-buildbot-config step. 09:55:37 INFO - Running pre-action listener: _resource_record_pre_action 09:55:37 INFO - Running main action method: read_buildbot_config 09:55:37 INFO - Using buildbot properties: 09:55:37 INFO - { 09:55:37 INFO - "project": "", 09:55:37 INFO - "product": "firefox", 09:55:37 INFO - "script_repo_revision": "production", 09:55:37 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:55:37 INFO - "repository": "", 09:55:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:55:37 INFO - "buildid": "20161221080053", 09:55:37 INFO - "pgo_build": "False", 09:55:37 INFO - "basedir": "/builds/slave/test", 09:55:37 INFO - "buildnumber": 20, 09:55:37 INFO - "slavename": "t-yosemite-r7-0179", 09:55:37 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:55:37 INFO - "platform": "yosemite_r7", 09:55:37 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:55:37 INFO - "branch": "mozilla-aurora", 09:55:37 INFO - "repo_path": "releases/mozilla-aurora", 09:55:37 INFO - "revision": "d6ae871ef73bb86575f072e2305d8b2eff2411d7", 09:55:37 INFO - "stage_platform": "macosx64", 09:55:37 INFO - "builduid": "711cf7c8c0d7442aa15334aecabdf4df", 09:55:37 INFO - "slavebuilddir": "test" 09:55:37 INFO - } 09:55:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:55:37 INFO - Running post-action listener: _resource_record_post_action 09:55:37 INFO - [mozharness: 2016-12-21 17:55:37.531714Z] Finished read-buildbot-config step (success) 09:55:37 INFO - [mozharness: 2016-12-21 17:55:37.531829Z] Running download-and-extract step. 09:55:37 INFO - Running pre-action listener: _resource_record_pre_action 09:55:37 INFO - Running main action method: download_and_extract 09:55:37 INFO - mkdir: /builds/slave/test/build/tests 09:55:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:37 INFO - trying https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:55:37 INFO - Downloading https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/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:55:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/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:55:38 INFO - Downloaded 1199 bytes. 09:55:38 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:55:38 INFO - Using the following test package requirements: 09:55:38 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:55:38 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:55:38 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:55:38 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'jsshell-mac.zip'], 09:55:38 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:55:38 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:55:38 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:55:38 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:55:38 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:55:38 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:55:38 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:55:38 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:55:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:55:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:55:38 INFO - Fetch https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:55:39 INFO - Http code: 200 09:55:39 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 09:55:39 INFO - x-amz-cf-id: bmsU7sQHL0AqguAWUOcSmcNgZclQ72x6O-eoB0ZPruOx2lksalmr-g== 09:55:39 INFO - x-amz-version-id: wlMGvz6he9HnB7EzUYo98ysAgttp5M40 09:55:39 INFO - Expected file size: 16269575 09:55:39 INFO - Obtained file size: 16269575 09:55:46 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:55:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:55:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:55:46 INFO - Fetch https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:55:46 INFO - Http code: 200 09:55:46 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 09:55:46 INFO - x-amz-cf-id: sPUZRpKvfp5uHeupdJDsDT19yut4V7zmKTenvdRJiuMLmQHy3kWccg== 09:55:46 INFO - x-amz-version-id: wlMGvz6he9HnB7EzUYo98ysAgttp5M40 09:55:47 INFO - Expected file size: 16269575 09:55:47 INFO - Obtained file size: 16269575 09:55:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:55:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:55:53 INFO - Fetch https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:55:54 INFO - Http code: 200 09:55:54 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 09:55:54 INFO - x-amz-cf-id: -8JGjX3kSRDBbrHQeFwrKffExtC0oX8F1AfAH77ErNSUHkEsVpIjyA== 09:55:54 INFO - x-amz-version-id: yZU4e5QSjfWBXpVjf3y5kLUiKVhYuIV0 09:55:54 INFO - Expected file size: 14409038 09:55:54 INFO - Obtained file size: 14409038 09:55:54 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:55:54 INFO - trying https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:55:54 INFO - Downloading https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:55:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/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:55:57 INFO - Downloaded 109760966 bytes. 09:55:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:55:57 INFO - mkdir: /builds/slave/test/properties 09:55:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:55:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:55:57 INFO - Contents: 09:55:57 INFO - build_url:https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:55:58 INFO - Running post-action listener: _resource_record_post_action 09:55:58 INFO - Running post-action listener: set_extra_try_arguments 09:55:58 INFO - [mozharness: 2016-12-21 17:55:58.659051Z] Finished download-and-extract step (success) 09:55:58 INFO - [mozharness: 2016-12-21 17:55:58.659177Z] Running populate-webroot step. 09:55:58 INFO - Running pre-action listener: _resource_record_pre_action 09:55:58 INFO - Running main action method: populate_webroot 09:55:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:55:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:55:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:55:58 INFO - '--tppagecycles', 09:55:58 INFO - '7'], 09:55:58 INFO - 'tests': ['tsvgm']}}, 09:55:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:55:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:55:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:55:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:55:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['damp', 'tps']}, 09:55:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'tests': ['damp', 'tps']}, 09:55:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['dromaeo_dom']}, 09:55:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:55:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['basic_compositor_video']}, 09:55:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:55:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['a11yr', 09:55:58 INFO - 'ts_paint', 09:55:58 INFO - 'tpaint', 09:55:58 INFO - 'sessionrestore', 09:55:58 INFO - 'sessionrestore_no_auto_restore', 09:55:58 INFO - 'tabpaint']}, 09:55:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tsvgx', 09:55:58 INFO - 'tsvgr_opacity', 09:55:58 INFO - 'tart', 09:55:58 INFO - 'tscrollx', 09:55:58 INFO - 'cart']}, 09:55:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:55:58 INFO - 'tsvgr_opacity', 09:55:58 INFO - 'tart', 09:55:58 INFO - 'tscrollx', 09:55:58 INFO - 'cart']}, 09:55:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s'], 09:55:58 INFO - 'tests': ['tp5o']}, 09:55:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'tests': ['tp5o']}, 09:55:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--disable-e10s', 09:55:58 INFO - '--xperf_path', 09:55:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:58 INFO - 'tests': ['tp5n']}, 09:55:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:55:58 INFO - 'talos_options': ['--xperf_path', 09:55:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:58 INFO - 'tests': ['tp5n']}}, 09:55:58 INFO - 'talos.zip': {'path': '', 09:55:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:55:58 INFO - Running post-action listener: _resource_record_post_action 09:55:58 INFO - [mozharness: 2016-12-21 17:55:58.677991Z] Finished populate-webroot step (success) 09:55:58 INFO - [mozharness: 2016-12-21 17:55:58.678128Z] Running create-virtualenv step. 09:55:58 INFO - Running pre-action listener: _resource_record_pre_action 09:55:58 INFO - Running main action method: create_virtualenv 09:55:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:55:58 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:55:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:55:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:55:58 INFO - Using real prefix '/tools/python27' 09:55:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:55:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:56:02 INFO - Installing pip.................done. 09:56:02 INFO - Return code: 0 09:56:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:56:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:56:02 INFO - Reading from file tmpfile_stdout 09:56:02 INFO - Output received: 09:56:02 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:56:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:56:02 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:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:02 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:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:02 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:02 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:56:02 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:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:02 INFO - 'HOME': '/Users/cltbld', 09:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:02 INFO - 'LOGNAME': 'cltbld', 09:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:02 INFO - 'MOZ_NO_REMOTE': '1', 09:56:02 INFO - 'NO_EM_RESTART': '1', 09:56:02 INFO - 'PAGER': '/bin/cat', 09:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:02 INFO - 'PWD': '/builds/slave/test', 09:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:02 INFO - 'SHELL': '/bin/bash', 09:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:02 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:02 INFO - 'USER': 'cltbld', 09:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:02 INFO - 'XPC_FLAGS': '0x0', 09:56:02 INFO - 'XPC_SERVICE_NAME': '0', 09:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:03 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:56:03 INFO - Cleaning up... 09:56:03 INFO - Return code: 0 09:56:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:03 INFO - 'HOME': '/Users/cltbld', 09:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:03 INFO - 'LOGNAME': 'cltbld', 09:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:03 INFO - 'MOZ_NO_REMOTE': '1', 09:56:03 INFO - 'NO_EM_RESTART': '1', 09:56:03 INFO - 'PAGER': '/bin/cat', 09:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:03 INFO - 'PWD': '/builds/slave/test', 09:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:03 INFO - 'SHELL': '/bin/bash', 09:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:03 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:03 INFO - 'USER': 'cltbld', 09:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:03 INFO - 'XPC_FLAGS': '0x0', 09:56:03 INFO - 'XPC_SERVICE_NAME': '0', 09:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:03 INFO - Downloading/unpacking psutil>=3.1.1 09:56: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:56: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:56: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:56: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:56: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:56: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:56:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:56:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:06 INFO - Installing collected packages: psutil 09:56:06 INFO - Running setup.py install for psutil 09:56:07 INFO - building 'psutil._psutil_osx' extension 09:56:07 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:56:07 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:56:07 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:56:07 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:56:07 INFO - building 'psutil._psutil_posix' extension 09:56:07 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:56:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:56:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:56:07 INFO - ^ 09:56:07 INFO - 1 warning generated. 09:56:07 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:56:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:08 INFO - Successfully installed psutil 09:56:08 INFO - Cleaning up... 09:56:08 INFO - Return code: 0 09:56:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:56:08 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:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:08 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:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:08 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:08 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:56:08 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:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:08 INFO - 'HOME': '/Users/cltbld', 09:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:08 INFO - 'LOGNAME': 'cltbld', 09:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:08 INFO - 'MOZ_NO_REMOTE': '1', 09:56:08 INFO - 'NO_EM_RESTART': '1', 09:56:08 INFO - 'PAGER': '/bin/cat', 09:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:08 INFO - 'PWD': '/builds/slave/test', 09:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:08 INFO - 'SHELL': '/bin/bash', 09:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:08 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:08 INFO - 'USER': 'cltbld', 09:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:08 INFO - 'XPC_FLAGS': '0x0', 09:56:08 INFO - 'XPC_SERVICE_NAME': '0', 09:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:56:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:56:11 INFO - Installing collected packages: mozsystemmonitor 09:56:11 INFO - Running setup.py install for mozsystemmonitor 09:56:11 INFO - Successfully installed mozsystemmonitor 09:56:11 INFO - Cleaning up... 09:56:11 INFO - Return code: 0 09:56:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:56:11 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:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:11 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:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:11 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:11 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:56:11 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:56:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:11 INFO - 'HOME': '/Users/cltbld', 09:56:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:11 INFO - 'LOGNAME': 'cltbld', 09:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:11 INFO - 'MOZ_NO_REMOTE': '1', 09:56:11 INFO - 'NO_EM_RESTART': '1', 09:56:11 INFO - 'PAGER': '/bin/cat', 09:56:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:11 INFO - 'PWD': '/builds/slave/test', 09:56:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:11 INFO - 'SHELL': '/bin/bash', 09:56:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:11 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:11 INFO - 'USER': 'cltbld', 09:56:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:11 INFO - 'XPC_FLAGS': '0x0', 09:56:11 INFO - 'XPC_SERVICE_NAME': '0', 09:56:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:56:11 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:56:11 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:56:11 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:56:11 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:56:11 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:56:11 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:56:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:56:14 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:56:14 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:56:14 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:56:14 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:56:14 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:56:14 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:56:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:14 INFO - warning: no files found matching '*.txt' 09:56:14 INFO - no previously-included directories found matching 'build' 09:56:14 INFO - no previously-included directories found matching 'dist' 09:56:14 INFO - no previously-included directories found matching '.git*' 09:56:14 INFO - Installing collected packages: jsonschema, functools32 09:56:14 INFO - Running setup.py install for functools32 09:56:14 INFO - warning: no files found matching '*.txt' 09:56:14 INFO - no previously-included directories found matching 'build' 09:56:14 INFO - no previously-included directories found matching 'dist' 09:56:14 INFO - no previously-included directories found matching '.git*' 09:56:15 INFO - Successfully installed jsonschema functools32 09:56:15 INFO - Cleaning up... 09:56:15 INFO - Return code: 0 09:56:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:56:15 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:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:15 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:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:15 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:15 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:56:15 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:56:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:15 INFO - 'HOME': '/Users/cltbld', 09:56:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:15 INFO - 'LOGNAME': 'cltbld', 09:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:15 INFO - 'MOZ_NO_REMOTE': '1', 09:56:15 INFO - 'NO_EM_RESTART': '1', 09:56:15 INFO - 'PAGER': '/bin/cat', 09:56:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:15 INFO - 'PWD': '/builds/slave/test', 09:56:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:15 INFO - 'SHELL': '/bin/bash', 09:56:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:15 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:15 INFO - 'USER': 'cltbld', 09:56:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:15 INFO - 'XPC_FLAGS': '0x0', 09:56:15 INFO - 'XPC_SERVICE_NAME': '0', 09:56:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:56:15 INFO - Cleaning up... 09:56:15 INFO - Return code: 0 09:56:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:56:15 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:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:15 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:56:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:15 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:15 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:56:15 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:56:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:15 INFO - 'HOME': '/Users/cltbld', 09:56:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:15 INFO - 'LOGNAME': 'cltbld', 09:56:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:15 INFO - 'MOZ_NO_REMOTE': '1', 09:56:15 INFO - 'NO_EM_RESTART': '1', 09:56:15 INFO - 'PAGER': '/bin/cat', 09:56:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:15 INFO - 'PWD': '/builds/slave/test', 09:56:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:15 INFO - 'SHELL': '/bin/bash', 09:56:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:15 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:15 INFO - 'USER': 'cltbld', 09:56:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:15 INFO - 'XPC_FLAGS': '0x0', 09:56:15 INFO - 'XPC_SERVICE_NAME': '0', 09:56:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:15 INFO - Downloading/unpacking blobuploader==1.2.4 09:56:15 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:56:15 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:56:15 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:56:15 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:56:15 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:56:15 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:56:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:18 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:56:18 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:56:18 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:56:18 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:56:18 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:56:18 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:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:56:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56:19 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:56:19 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:56:19 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:56:19 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:56:19 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:56:19 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:56:19 INFO - Downloading docopt-0.6.1.tar.gz 09:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:19 INFO - Running setup.py install for blobuploader 09:56:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:56:19 INFO - Running setup.py install for requests 09:56:20 INFO - Running setup.py install for docopt 09:56:20 INFO - Successfully installed blobuploader requests docopt 09:56:20 INFO - Cleaning up... 09:56:20 INFO - Return code: 0 09:56:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:20 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:20 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:56:20 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:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:20 INFO - 'HOME': '/Users/cltbld', 09:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:20 INFO - 'LOGNAME': 'cltbld', 09:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:20 INFO - 'MOZ_NO_REMOTE': '1', 09:56:20 INFO - 'NO_EM_RESTART': '1', 09:56:20 INFO - 'PAGER': '/bin/cat', 09:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:20 INFO - 'PWD': '/builds/slave/test', 09:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:20 INFO - 'SHELL': '/bin/bash', 09:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:20 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:20 INFO - 'USER': 'cltbld', 09:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:20 INFO - 'XPC_FLAGS': '0x0', 09:56:20 INFO - 'XPC_SERVICE_NAME': '0', 09:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ZeElzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-akYwTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:20 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-cHHvBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Djr_xZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-wkV9wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-kj7Iuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-HSC_tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-wiTwCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-F0q8lT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-5ZE8Tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-eUCYz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:21 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-SEuOEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:22 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-35T199-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:22 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-utagFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:22 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-KZ8Gmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:22 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-zhqVPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:22 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-C9hiIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:22 INFO - Running setup.py install for manifestparser 09:56:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:22 INFO - Running setup.py install for mozcrash 09:56:22 INFO - Running setup.py install for mozdebug 09:56:22 INFO - Running setup.py install for mozdevice 09:56:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Running setup.py install for mozfile 09:56:23 INFO - Running setup.py install for mozhttpd 09:56:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Running setup.py install for mozinfo 09:56:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Running setup.py install for mozInstall 09:56:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:23 INFO - Running setup.py install for mozleak 09:56:23 INFO - Running setup.py install for mozlog 09:56:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:24 INFO - Running setup.py install for moznetwork 09:56:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:24 INFO - Running setup.py install for mozprocess 09:56:24 INFO - Running setup.py install for mozprofile 09:56:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:24 INFO - Running setup.py install for mozrunner 09:56:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:24 INFO - Running setup.py install for mozscreenshot 09:56:24 INFO - Running setup.py install for moztest 09:56:25 INFO - Running setup.py install for mozversion 09:56:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:25 INFO - Cleaning up... 09:56:25 INFO - Return code: 0 09:56:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:25 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:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:25 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:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:25 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:25 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:56:25 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:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:25 INFO - 'HOME': '/Users/cltbld', 09:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:25 INFO - 'LOGNAME': 'cltbld', 09:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:25 INFO - 'MOZ_NO_REMOTE': '1', 09:56:25 INFO - 'NO_EM_RESTART': '1', 09:56:25 INFO - 'PAGER': '/bin/cat', 09:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:25 INFO - 'PWD': '/builds/slave/test', 09:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:25 INFO - 'SHELL': '/bin/bash', 09:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:25 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:25 INFO - 'USER': 'cltbld', 09:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:25 INFO - 'XPC_FLAGS': '0x0', 09:56:25 INFO - 'XPC_SERVICE_NAME': '0', 09:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:25 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-3iIAqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:25 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:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:25 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Xur4dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:25 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:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:25 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-DuS_es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:25 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:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:25 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-q4Y0mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:25 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:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:25 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-wbEg6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:25 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:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:25 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-eahK0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-HBitCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-d2FnMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-AoNifW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-V3fWKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-vnFiHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-nSB5wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-1NTMuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-NjAhaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:26 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:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:26 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-96zCGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:27 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:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:27 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Fy5ScG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:27 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-wNvSul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:27 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:56:27 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:56:27 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:56:27 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:56:27 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:56:27 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:56:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:27 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:56:27 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:56:27 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:56:27 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:56:27 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:56:27 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:56:30 INFO - Downloading blessings-1.6.tar.gz 09:56:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:30 INFO - Installing collected packages: blessings 09:56:30 INFO - Running setup.py install for blessings 09:56:30 INFO - Successfully installed blessings 09:56:30 INFO - Cleaning up... 09:56:30 INFO - Return code: 0 09:56:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:56:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:30 INFO - Reading from file tmpfile_stdout 09:56:30 INFO - Current package versions: 09:56:30 INFO - blessings == 1.6 09:56:30 INFO - blobuploader == 1.2.4 09:56:30 INFO - docopt == 0.6.1 09:56:30 INFO - functools32 == 3.2.3-2 09:56:30 INFO - jsonschema == 2.5.1 09:56:30 INFO - manifestparser == 1.1 09:56:30 INFO - mozInstall == 1.12 09:56:30 INFO - mozcrash == 1.0 09:56:30 INFO - mozdebug == 0.1 09:56:30 INFO - mozdevice == 0.48 09:56:30 INFO - mozfile == 1.2 09:56:30 INFO - mozhttpd == 0.7 09:56:30 INFO - mozinfo == 0.9 09:56:30 INFO - mozleak == 0.1 09:56:30 INFO - mozlog == 3.4 09:56:30 INFO - moznetwork == 0.27 09:56:30 INFO - mozprocess == 0.23 09:56:30 INFO - mozprofile == 0.28 09:56:30 INFO - mozrunner == 6.13 09:56:30 INFO - mozscreenshot == 0.1 09:56:30 INFO - mozsystemmonitor == 0.3 09:56:30 INFO - moztest == 0.7 09:56:30 INFO - mozversion == 1.4 09:56:30 INFO - psutil == 3.1.1 09:56:30 INFO - requests == 1.2.3 09:56:30 INFO - wsgiref == 0.1.2 09:56:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:31 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:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:31 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:31 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:56:31 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:56:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:31 INFO - 'HOME': '/Users/cltbld', 09:56:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:31 INFO - 'LOGNAME': 'cltbld', 09:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:31 INFO - 'MOZ_NO_REMOTE': '1', 09:56:31 INFO - 'NO_EM_RESTART': '1', 09:56:31 INFO - 'PAGER': '/bin/cat', 09:56:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:31 INFO - 'PWD': '/builds/slave/test', 09:56:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:31 INFO - 'SHELL': '/bin/bash', 09:56:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:31 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:31 INFO - 'USER': 'cltbld', 09:56:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:31 INFO - 'XPC_FLAGS': '0x0', 09:56:31 INFO - 'XPC_SERVICE_NAME': '0', 09:56:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:31 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:56:31 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:56:31 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:56:31 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:56:31 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:56:31 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:56:31 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:56:31 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:56:31 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:56:31 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:56:31 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:56:31 INFO - Cleaning up... 09:56:31 INFO - Return code: 0 09:56:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:56:31 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:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:31 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:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:31 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 0x106bc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106b3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9573dc3140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, '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.o14UtmXpF0/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.aWp1OX5w0H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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:56:31 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:56:31 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:56:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:56:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:31 INFO - 'HOME': '/Users/cltbld', 09:56:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:31 INFO - 'LOGNAME': 'cltbld', 09:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:31 INFO - 'MOZ_NO_REMOTE': '1', 09:56:31 INFO - 'NO_EM_RESTART': '1', 09:56:31 INFO - 'PAGER': '/bin/cat', 09:56:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:31 INFO - 'PWD': '/builds/slave/test', 09:56:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:31 INFO - 'SHELL': '/bin/bash', 09:56:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:56:31 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:56:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:31 INFO - 'USER': 'cltbld', 09:56:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:31 INFO - 'XPC_FLAGS': '0x0', 09:56:31 INFO - 'XPC_SERVICE_NAME': '0', 09:56:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:56:31 INFO - Cleaning up... 09:56:31 INFO - Return code: 0 09:56:31 INFO - Running post-action listener: _resource_record_post_action 09:56:31 INFO - Running post-action listener: _start_resource_monitoring 09:56:31 INFO - Starting resource monitoring. 09:56:31 INFO - [mozharness: 2016-12-21 17:56:31.913455Z] Finished create-virtualenv step (success) 09:56:31 INFO - [mozharness: 2016-12-21 17:56:31.914454Z] Running install step. 09:56:31 INFO - Running pre-action listener: _resource_record_pre_action 09:56:31 INFO - Running main action method: install 09:56:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:32 INFO - Reading from file tmpfile_stdout 09:56:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:56:32 INFO - Reading from file tmpfile_stdout 09:56:32 INFO - Output received: 09:56:32 INFO - Usage: mozinstall [options] installer 09:56:32 INFO - Options: 09:56:32 INFO - -h, --help show this help message and exit 09:56:32 INFO - -d DEST, --destination=DEST 09:56:32 INFO - Directory to install application into. [default: 09:56:32 INFO - "/builds/slave/test"] 09:56:32 INFO - --app=APP Application being installed. [default: firefox] 09:56:32 INFO - mkdir: /builds/slave/test/build/application 09:56:32 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:56:32 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:57:03 INFO - Reading from file tmpfile_stdout 09:57:03 INFO - Output received: 09:57:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:57:03 INFO - Running post-action listener: _resource_record_post_action 09:57:03 INFO - [mozharness: 2016-12-21 17:57:03.493160Z] Finished install step (success) 09:57:03 INFO - [mozharness: 2016-12-21 17:57:03.493314Z] Running run-tests step. 09:57:03 INFO - Running pre-action listener: _resource_record_pre_action 09:57:03 INFO - Running main action method: run_tests 09:57:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:57:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:57:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:57:03 INFO - 2016-12-21 09:57:03.507 screenresolution[1621:7273] starting screenresolution argv=screenresolution get 09:57:03 INFO - 2016-12-21 09:57:03.510 screenresolution[1621:7273] Display 0: 1600x1200x32@60 09:57:03 INFO - 2016-12-21 09:57:03.516 screenresolution[1622:7275] starting screenresolution argv=screenresolution list 09:57:03 INFO - Available Modes on Display 0 09:57:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:57:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:57:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:57:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:57:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:57:03 INFO - Intel Iris: 09:57:03 INFO - Chipset Model: Intel Iris 09:57:03 INFO - Type: GPU 09:57:03 INFO - Bus: Built-In 09:57:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:57:03 INFO - Vendor: Intel (0x8086) 09:57:03 INFO - Device ID: 0x0a2e 09:57:03 INFO - Revision ID: 0x0009 09:57:03 INFO - Displays: 09:57:03 INFO - Display: 09:57:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:57:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:57:03 INFO - Display Serial Number: 1600x1200 60 09:57:03 INFO - Main Display: Yes 09:57:03 INFO - Mirror: Off 09:57:03 INFO - Online: Yes 09:57:03 INFO - Rotation: Supported 09:57:03 INFO - Return code: 0 09:57:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:57:03 INFO - Python 2.7.3 09:57:03 INFO - Return code: 0 09:57:03 INFO - grabbing minidump binary from tooltool 09:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:03 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 0x1069dad30>, '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 0x106840db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069d2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:57:03 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:57:03 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:57:03 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:57:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:57:03 INFO - Return code: 0 09:57:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:57:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:57:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:57:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:57:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:57:03 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/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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:57:03 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/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --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:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o14UtmXpF0/Render', 09:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:03 INFO - 'HOME': '/Users/cltbld', 09:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:03 INFO - 'LOGNAME': 'cltbld', 09:57:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:57:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:03 INFO - 'MOZ_NO_REMOTE': '1', 09:57:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:57:03 INFO - 'NO_EM_RESTART': '1', 09:57:03 INFO - 'PAGER': '/bin/cat', 09:57:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:03 INFO - 'PWD': '/builds/slave/test', 09:57:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:03 INFO - 'SHELL': '/bin/bash', 09:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aWp1OX5w0H/Listeners', 09:57:03 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 09:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:03 INFO - 'USER': 'cltbld', 09:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:03 INFO - 'XPC_FLAGS': '0x0', 09:57:03 INFO - 'XPC_SERVICE_NAME': '0', 09:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:03 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/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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:57:04 INFO - mozversion application_buildid: 20161221080053 09:57:04 INFO - mozversion application_changeset: d6ae871ef73bb86575f072e2305d8b2eff2411d7 09:57:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:57:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:57:04 INFO - mozversion application_name: Firefox 09:57:04 INFO - mozversion application_remotingname: firefox-dev 09:57:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:57:04 INFO - mozversion application_vendor: Mozilla 09:57:04 INFO - mozversion application_version: 52.0a2 09:57:04 INFO - mozversion platform_buildid: 20161221080053 09:57:04 INFO - mozversion platform_changeset: d6ae871ef73bb86575f072e2305d8b2eff2411d7 09:57:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:57:04 INFO - mozversion platform_version: 52.0a2 09:57:04 INFO - using testdate: 1482343024 09:57:04 INFO - actual date: 1482343024 09:57:04 INFO - starting webserver on 'localhost:49236' 09:57:04 INFO - SUITE-START | Running 2 tests 09:57:04 INFO - TEST-START | dromaeo_css 09:57:04 INFO - operating with platform_type : mac_ 09:57:04 INFO - Initialising browser for dromaeo_css test... 09:57:04 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpYz5Uzz/profile http://localhost:49236/getInfo.html) 09:57:06 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 09:57:06 INFO - PROCESS | 1632 | colorDepth:24 09:57:06 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 09:57:06 INFO - PROCESS | 1632 | __metrics 09:57:06 INFO - PROCESS | 1632 | 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:57:06 INFO - PROCESS | 1632 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:07 INFO - TEST-INFO | 1632: exit 0 09:57:07 INFO - Browser initialized. 09:57:07 INFO - Running cycle 1/1 for dromaeo_css test... 09:57:07 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpYz5Uzz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:20 INFO - PROCESS | 1634 | RSS: Main: 222523392 09:57:20 INFO - PROCESS | 1634 | 09:58:11 INFO - PROCESS | 1634 | 1482343091627 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:00:25 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 10:00:27 INFO - PROCESS | 1634 | RSS: Main: 444751872 10:00:27 INFO - PROCESS | 1634 | 10:03:30 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 10:03:31 INFO - PROCESS | 1634 | RSS: Main: 348172288 10:03:31 INFO - PROCESS | 1634 | 10:06:33 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 10:06:34 INFO - PROCESS | 1634 | RSS: Main: 346083328 10:06:34 INFO - PROCESS | 1634 | 10:09:36 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 10:09:36 INFO - PROCESS | 1634 | RSS: Main: 325390336 10:09:36 INFO - PROCESS | 1634 | 10:12:40 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 10:12:40 INFO - PROCESS | 1634 | RSS: Main: 315219968 10:12:40 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 10:15:43 INFO - PROCESS | 1634 | RSS: Main: 359170048 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | __start_tp_report 10:15:43 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 10:15:43 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 10:15:43 INFO - PROCESS | 1634 | |i|pagename|runs| 10:15:43 INFO - PROCESS | 1634 | |0;dojo.html;2685.845086612757;2686.959695604566;3281.5898012748407;3302.422076136677;3302.8678852845887;5438.075527160382;5531.197976293435;5532.585056120787;5573.5819813514045;5583.106702927527;5544.473275038865;5560.193771903072;5576.358718747345;5586.190002449643;5597.776088956437;5033.370828646419;5075.568576670988;5079.619028572872;5148.871278218037;5185.325907632003;5018.447970723218;5020.297158397827;5028.874278143039;5092;5122.538971492565;5098.209777484496;5116.386033675964;5119;5119.590432765389;5120.667156634825;5047.167217409123;5071.239314102877;5073.822416215415;5092.236164575306;5107.948920510822;4991.101601711691;5039.21892106724;5062.063518249099;5089.770960306795;5140.537351638371;5417.972910135424;5456.945430545684;5548.361938377098;5580.693061881595;5638.295213098363;5943.494802941756;5955.880882382371;5988.490978266853;6002.489788367994;6017.157597936292;6015.75936962521;6064.060070689006;6087.239095113111;6097.420745029215;6105.419985101408;5508.504234618904;5590.357108932484;5611.831645050655;5616.52259557938;5628.465295796893;5269.789208431696;6131.233595800525;6133.548895741652;6162.753489860445;6171.228596425447;5937.970310148422;5938.406159384113;5943.405659434108;5949.791757288474;5976.671283079472;5932.110183472513;6088.1476593276975;6102.359252278536;6106.542009349228;6111.419415155553;5874.471297583238;5889.087191485327;5919.289685237799;5964.045752679551;5980.461758441761;5969.432903874125;6006.6696331701305;6035.698215089228;6068.120122582289;6086;4313.84901528445;4459.375687403766;4751.95248047522;4782.33047373368;4829.589484893753;4261.169072030986;4273.508546517191;4296.763677997677;4305.935410968838;4348.434703488526;5676.8864622707315;5686.800961966394;5692.231548740867;5694.715264236772;5710.71446427677;5976.193213916065;5979.19280897082;5992.670403127782;5995.820125396245;6030.48740857032;5611.438856114356;5675.148727690879;5691.03252447085;5692.0323544997445;5701.087825947829;5204.531592156724;5207.869803254873;5214.530692237717;5220.112580861262;5221.843344699665;6246.750129994749;6269.31037585856;6278.560500765086;6280.874382512324;6287.528435367274;5972.34304226526;6014.398560143951;6032.819015429544;6044.879102418015;6065.727042283196;6224.36142864955;6265.906011409924;6271.78048768291;6272.8431789203805;6278.591891527219;5749.022666146849;5929.377415371324;5934.991051521338;5937.940620593911;5986.102084687332;5879.911801322898;5881.470667639933;5932.466078052996;5951.1073338999495;6039.063945088518;5663.518600918968;5811.506021988009;5812.883742325214;5839.912401313899;5840;4787.640926930564;5138.820141295037;5769.826905192851;5822.796202132905;5829.795957141479;5641.548676105984;5725.34158571753;5779.479846813807;5794.304683438015;5797.304323481209;5864.208331875313;5868.149118377897;5870.677112758668;5875.764969401176;5885.205497257815;5626.803061892814;5722.971385143048;5734.22587950638;5740.655560666373;5762.798302059407 10:15:43 INFO - PROCESS | 1634 | |1;ext.html;458.2461850256328;479.13037836327067;504.9597828473345;520.778669065647;525.6150044632395;29649.110526684206;31423.05730828076;38359.424608630856;39860.402093968616;40075.398869016986;41265;41639.79180104103;41645.79177104118;41992.37011444823;42034.57965420345;9232.953835230848;9263.166315031629;9424.858627120599;9427.387219830724;9430;8444.02893667227;8635.740927772176;8688.174623410765;8760.123987601253;9385.95307023464;8573.228409443149;11186.496607652674;11418.885811141907;11434.942825285903;11581.652550423458;11306.378149201832;11374.772504549905;11391.259568128127;11394.202405831595;11395.487203075798;8450;11161;11250.437478126061;11255.099592032619;11266.830997535044;31110.066697999097;38600.42099368512;38914.41628375577;39187.02032449183;40409.39385909214;3186.23530352716;3256.5766450361302;3258.234314935988;3428.777129486575;3548.2193917338145;28506.85972561095;29390.85304573485;29487.41025179506;30303;30433.847830760926;2974.182099922517;3201.5677883485664;3204.4872820348864;3268.0195941217776;3312.287858110518;487174;491146.54426727636;492692;494375;501720;48924;60864;61282.693586532674;61371;61428.314301142076;63890.36109638937;64016.67991660105;64023.67988160029;64037;64109.358906411275;15716.21418929049;15906.602334941488;15913.204339782964;15919.761203581955;15927.124008179662;12239.69400764988;12273.938630306791;12289.815652765215;12319.63041108768;12320.876791232153;16438.013719176648;17532.914916710117;18958.336458223897;19003.61992760165;19083.141258643394;6288.779892703734;6512.739490420425;7714.498557593738;7758.883616745753;7759.245467858829;817.1134319263547;917.4128557722929;922.9648624782338;970.6166064404521;988.6440881282732;25283.494330113663;28758.137255882357;29920.251993699898;29962;30433.087007389815;941.5480569326761;985.1330828870548;1049.6536143072767;1127.548980407827;1192.3144192089583;13537.154736565364;14922.477713279983;14980.625484362978;15446.691066178839;15771.68456630861;631537;634952;637866;639414;639511;49701.751491242314;50607.74696126496;55964.44035559592;57605.42394576084;58120.709396452745;1349.1635186184628;1372.1081297156927;1388.2850332078988;1393.0318428691962;1394.2958805803005;29974.70025299719;30681.232969175948;30737.53893691641;30765.769369225876;31372.215694606904;51429.22856157235;52381.47618523918;52430.21354679759;52446.68883277949;52518;644827;644852;847307;847690;848478;36205.45691814572;39937.600623993385;40012.1997560053;40509.39235911405;40546.39180412237;44490.66528004165;44591;44595.331070033324;45044.87387815331;45110.54889451063;30394.78420863141;35374.823125884206;36398.90803275906;36538.346643299206;37949.86150415492;37022.25955480822;37096.814515927246;37559.43660845035;37984.430233545965;38336.80831595824;35398;36753.448698269014;37106.628933710315;37149;37157.88526344214;31618;33437.83281083579;34147.31705365929;34240;34402.65597343994;24587.75412245926;25005;25013.874930625232;25267.494650107263;25382 10:15:43 INFO - PROCESS | 1634 | |2;jquery.html;351.43594530778114;363.21907898019253;371.0963803139354;381.9465274861519;398.78465628560576;14518.637034074154;14808;15443.15062671554;19362;19914.502137446572;18971.33600323989;19332.4200273992;19408.514787130294;19435.902820485877;19774.40676779699;5276.947230527693;5872.9706351468185;5879.529637629001;5881.264841894763;5889.1755154278435;4576.450825900896;5566.840136205703;5750.511206547449;5818.06910894257;5825.038868586678;5752.626079304831;5764.971175144118;5771.653700777946;5774.971125144369;5781.335146458148;5472.5621950243885;5684.516816070639;5740.311162660466;5741.856453588652;5757.107648314518;4836.395450568679;5020.924686129711;5045.924311135299;5062.341895553592;5063.974680126613;24371.659558724263;24598.508029839304;24790.752092479026;24973;25091.24726258215;2351.8588884666974;2360.775726305998;2396.0056576520724;2485.155047283932;2874.152125123094;14687.33906974178;14987.250637468193;15423.922880385639;15687.294071766684;16090.275937582837;1831.9557852024352;1907.1989764299028;1913.23470611755;1930.2182616040527;1937.651222779899;111859;118771.99999999914;120759;122871;122906.38546807299;10425.635102771519;10519.105876000625;10724.624638137784;10938.124950003981;11107.72230694239;13563.186208827501;13577.77799998005;13590.456381744818;13592.728145437035;13598.456061757419;8132.105468398471;8378.79053023667;8381.245687888118;8517.61670724819;8530.019047809457;8317.251447369767;8341.16588341161;8379.539125348043;8490.957545212234;8516.616752246164;6471.288158302583;6548.541602087904;6560.868782624416;6596.70314835824;6693.899591506131;1653.6031352475559;1780.759597454353;1820.1718218210683;1822.3166312632763;1825.2881376263267;806.5563939833069;829.991560254294;915.9129882661136;1006.3509036671452;1076.4294923690456;21511.892440537697;21812;21815.67276490854;21886.9056547172;21948;928.7353104365224;942.5004747483845;952.1620973543376;982.2878413150409;982.7248370456284;11123.944380278046;11498.195126341076;11547.191696581158;11598.260260960924;11603.941980290214;605357;608747;609979;612851;619433;92235;96438.63618321872;111927;137708;142981;927.6011315134557;954.4559601027487;972.027972027972;977.7702239973601;1002.734275417015;18857.622847543247;19073.427797166107;19408.32070877569;19431.514212144808;19635.31276405319;38443;48634;54001.45998540121;55027;55367;78178;78293.60853195697;79520.60239698995;80072.98905164273;81546.3690726194;7144.285571442814;7209.675564599711;7370.926290737024;7409.37020353276;7746.961265193638;12217.572384966483;12349.19730217569;12714.173578717724;12767.106302559103;12874.420651070912;10810.6492665553;10898.673039808818;11078.944605276922;11279.32324060559;11309.71725706864;10686.89313106859;10701.785964280827;10753;10920.798712141499;11143.944280278547;11238.775224495628;11472.713182170513;11809.881901181221;11835.881641183822;11853.407329633312;7196.784096477114;7289.137101398646;7450.180480147071;7935.642896069806;8371.079181289932;6823.28355522663;9403.623855045722;11204.383758893013;11260.099192064452;11420.428978551205 10:15:43 INFO - PROCESS | 1634 | |3;mootools.html;1300.4083142170314;1423.2314550142921;1449.3984996226566;1600.8479194476529;1808.4574627611714;6207.472364848988;7781.961090194548;7991.720289789859;8320.791980200498;8327.708530201444;8167.387445941548;8252.133525979776;8260.586970651473;8271.586420678972;8366.372522060854;2303.1248125712204;2338.4270853640883;2338.5790557699693;2338.7427382987858;2344.847584906984;2300.6549017647403;2345.870977096259;2354.434935615457;2355.269866341431;2357.8585284882874;1926.2295081967254;2288.5308511755134;2297.747247802749;2326.3835083702834;2327.022650486791;2261.321603518946;2278.3392816083315;2313.051648823974;2313.548857972696;2315.0855412112123;2312.3409828198883;2334.36972017556;2334.568104900599;2335.6963594732574;2337.789598936104;8223.42436029478;8228.50628962264;8309.83380332396;8315.875261871077;8330.958345208295;5012.799488020475;5203.323567936144;5213.244079608436;5226.947730522684;5233.267342572043;3326.5383616124136;3537.6285490023693;3573.6605022522817;3602.3695853225845;3614.1868079681863;1027.820131476996;1045.8954104589557;1072.6942821295893;1087.8640169978753;1096.3202814255133;10803.513841877135;10862.619808306672;10921.508532116073;10921.945390273077;10956.12351011925;4221.514525829508;4393.362962370502;4459.353393757951;4469.44131983502;4475;8530.488170709777;8534.658613655509;8534.70128545498;8539.615717292736;8541.658333666708;5073.594112470993;5073.746312684351;5075.670081444769;5075.898482030412;5080.390353157644;4235.576442355739;4266.189424009428;4412.6690498212765;4689.343491911135;4711.693739906964;3031.7271445570004;3567.5897271813556;3599.8380072896784;3627.8730244441317;3660.1516234301507;1120.9551617935354;1127.960521381764;1137.135776809614;1143.422571601336;1151.0618845640777;602.3464540973006;753.1226121568311;891.9509426981579;897.6544030548156;913.8263729891432;5524.475174858382;5653.886922261532;5765.94234057654;6017.00392780792;6019.819405417845;869.4956924983621;897.582624079806;919.2599957034652;921.5023887101024;952.7046615549202;3699.1122130688977;3706.9073273168387;3729.4778730977687;3851.826667799956;3854.7494412863075;8364.498130112213;9806.215502759762;10217.489125543692;10226.335288206243;10227.846582301272;8466.492010479391;10102.39385636864;10180.236482263712;10187.898121018696;10207.846882296772;7651.081870175614;7676.731314403969;7721.65252563636;7821.2960833526395;7836.742021873407;5598.692071936082;5665.801696940587;5717.914231286451;5916.49709774674;5945.989181839184;9120.45277283365;9230.584623691815;9306.767330816783;9414.199793017397;9436.528173591156;9398.20115290208;9412.67055653049;9527.38072025316;9636.75908102275;9677;7129.964350178216;7142.607156606437;7271.200167981624;7272.418206543358;7283.417326613964;7756.573388463688;7777.144514103557;7836.686532538863;7848.215178482106;7853.136155023057;7033.402160816183;7122.964385178041;7143.535670181422;7144.714211431485;7367.8158046049275;7006.649667516705;7083.539569927938;7103.147622285359;7129.287071292829;7144.3570078693165;6477.546571739933;6582.144321238208;6680.899786503109;6684.364985326386;6688.598684078971;4855.708657480563;4858.805647774049;4879.90240195187;4951.480094590161;5033.521815427529;4821.686590371614;4831.903361932671;4888.535589119028;5008.874778130576;5015.724135172601 10:15:43 INFO - PROCESS | 1634 | |4;prototype.html;297.52841745832853;299.17875431939314;306.0344612746784;309.9612548431446;310.9906702798914;5136.794528218871;5149.124648809702;5149.279100925869;5215.739213039352;5227.301127133678;4885.951140488594;5039.949600503994;5054.469280725526;5079.415867175276;5157.690538567688;3249.4800831866905;3957.584453632356;3970.5632380438274;3978.542467616225;3985.6014398560055;3447.5001124836886;3957.426173204898;3981.681465482769;4048;4066.085130845566;3856.86500972466;3956.861509847156;3970.880873573783;3980.263651224518;3987.4018897165365;3293.76483818568;3986.727565287787;3988.441618173458;4007.1985602879395;4010.13782036862;3459.41189997701;3507.6667716566885;3511.2977404518942;3519.788812671248;3521.806300653463;4096.242195193899;4321.7406955582765;4609.289521934841;5019.322391477141;5038.748062596892;3793.886183414502;3949.8275948228647;3956.8615098471414;4371.715838470521;4521.683482156251;5417.918731219035;5427.83716488506;5431.728413579305;5432.809851655173;5442.91835622466;3828.5979972102805;3837.7313588048846;3839.3856982882603;3850.8844734658005;3854.71089668276;5451.945480545183;5630.268065151504;5645;5674.942416172456;5679.280071553139;3690.8892733218045;3896.2597106549665;3900.063984643665;3901.49280593521;3902.70729695274;3905.1603905160528;3945.368741001426;3966.424868394067;3975.8807235782974;3983.649646708398;3791.526059242595;4300.78496075195;4538.704984176084;4554.86128467883;4577.382053422812;4517.480489743722;5432.565394768409;5444.455554444524;5453.563714902798;5468.945310546923;1168;1173.5423184957874;1277.2464246094662;1285.8585555588877;1292.6962163891478;756.2022066719663;844.2486187293314;848.5884346092264;849.5497386385226;849.9248450709857;4436.578525040116;4567.429071366079;4862.884645179358;4999.900001999939;5042.924356134661;4749.667523273407;5328.200769884548;5330.786768529293;5335.279737235423;5349.759260833194;4498.527654596286;4647.744374059392;4925.482824303468;4964.925526117111;4997.800087996512;2329.3710698111413;2659.539925568841;2788.386554957906;2792.1571762636563;2799.1805326537915;5032.84901452957;5098.099953535549;5203.557697595671;5602.730893987941;5616.887662246814;4653.348531205634;5488.914212599267;5641.915371269434;5844.357120716718;5868.559858010666;5045.646804723634;5504.954058728828;5592.748326325325;6040.882436749215;6044.667543285162;4943.937053533507;5151.913475042614;5283.286756288006;5637.408072152365;5673.234113394639;4631.073785242897;5513.5037846594;5538.224648549126;5580.544560393414;5617.269754931996;5739.827805165852;5995.280566332068;6012.368701286477;6021.638701677914;6022.036474164112;3677.5035370225746;3764.5294338207723;3803.1252811853983;3821.4649949006607;3833.233353329401;4942;5594.776208951596;5610.867870209295;5613.410591887956;5636.267285252891;5450.754716037867;5474.479924407175;5483.917741233964;5486.917696234639;5487.8048780488125;4559.612432943104;4753.952460475351;5304.968224149751;5464.890702185855;5504.587155963399;4220.831166753295;4432.490263619596;4604.58558729716;5301.893962120659;5391.514763671288;4445.399871017501;5319.255304257485;5350.705711185922;5508.173773933942;5545.500904918576;5248.055350036877;5372.328458942632;5372.758225879923;5379;5422 10:15:43 INFO - PROCESS | 1634 | |5;yui.html;613.58276372067;675.1358261425374;713.3187805645609;721.6824597177242;748.5957582905231;14380.49668261611;14570.344334504945;14593.343299551532;14594.562163135122;14644.926775366108;12795.296258705768;12889.355532223399;12895.806562901564;12931.159474634142;12935.223886566813;632.9905051424232;644.7131026693123;647.6049609738056;792.7740593930716;809.0049239435498;779.364817673597;781.7732857471354;797.1868693932186;802.4944285100407;808.9474184178039;866.0603245478663;866.9089745576687;867.4578388507183;867.6486023160599;869.387082107113;666.4035688059208;680.5746408494691;860.2730692564772;862.1163307609719;862.6592495964059;616.6454288783967;631.7441436218339;636.1380329653346;638.6551262318343;639.2744235292976;13828.516001939885;13863.4454621815;13864.584062478141;13873.098248613807;13897.930510347485;12592.118551701384;12835;12902.290374029424;12925.418356173996;12973.221606703533;10057.396556206651;11605.361705106216;11971.940140299243;12099.818502722466;12262.386880655931;4039.1719697462245;4240.745555266663;4328.718633288826;4467.285234362486;4471.5081341052455;74648.50702985964;84747;85589.57205213934;90587.99999999935;90872;10244.48777561119;10340.327878688011;10391.272610917315;10401.167906567456;10428.322159059788;19466.902665486865;19554.022298885;19611.705824412646;19630.116644751022;19634;7146.9285307146265;7225;7331.450141239428;7468.402527797872;7489.325960663567;4092.529359123679;4103.4460347852655;4172.958270417318;4217.472815898012;4230.767307798101;3148.275896543808;3230.2408933900515;3233.708966193054;3248.1067706380936;3539.6460353964912;677.1772296659582;684.2507454337592;686.2040033561045;687.9931200688029;690.3234829866759;599.2060519811267;634.9206349206349;637.1144109687538;647.2684675025578;649.6426965169233;8501.319894408556;8508.829823403497;8532.576210400453;8580.270676992526;8594.87107693385;454.50231995964367;535.6678859107378;560;611.3305930006636;634.6953462338012;3822.2164456286396;4078.7348822326453;4145.813438395279;4213.157368526246;4228.70399072062;108754;113527;113712;115174.42412787882;117624.41187794178;64768.676156618916;72699.63650181715;92420;93788;101155;89687;90419;91105;92661.5366923161;92870.53564732269;7959.482633628795;8049.51702897828;8108.594570271345;8211.21993410625;8279.13069127757;53152.73423632857;55612.72193639006;56060;57640.42359576518;60024;81165;82777;83526.58236708777;85079;86580;11112.326369900355;11260.831087533857;11315.830262545904;11396.829047564459;11589.24669896454;11840.348780816794;12088.214266072677;12262.816057758962;12302.446389912297;12449.190802597803;9906.851397229191;11629.59296424621;11760.588379407021;11962.880371196177;12308.317354618483;11776.794576988237;11840.052795776144;11869.5845645402;11885.76228475443;12458.75082498363;9489.66786162481;11327.943360283145;11502.82745758831;11921.82117268259;12008.63974080779;8639.95680021596;8943.508107054175;9321.906780932375;9354.76613084651;9379.202767764817;8951.462912225026;9096.590653420479;9161.541922903962;9191.080891910755;9191.494467804336 10:15:43 INFO - PROCESS | 1634 | __end_tp_report 10:15:43 INFO - PROCESS | 1634 | __start_cc_report 10:15:43 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,1666 10:15:43 INFO - PROCESS | 1634 | __end_cc_report 10:15:43 INFO - PROCESS | 1634 | __startTimestamp1482344143891__endTimestamp 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | ------- Summary: start ------- 10:15:43 INFO - PROCESS | 1634 | Number of tests: 6 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5551.72 Median:5725.34 stddev:648.78 (11.3%) stddev-sans-first:610.57 10:15:43 INFO - PROCESS | 1634 | Values: 2685.8 2687.0 3281.6 3302.4 3302.9 5438.1 5531.2 5532.6 5573.6 5583.1 5544.5 5560.2 5576.4 5586.2 5597.8 5033.4 5075.6 5079.6 5148.9 5185.3 5018.4 5020.3 5028.9 5092.0 5122.5 5098.2 5116.4 5119.0 5119.6 5120.7 5047.2 5071.2 5073.8 5092.2 5107.9 4991.1 5039.2 5062.1 5089.8 5140.5 5418.0 5456.9 5548.4 5580.7 5638.3 5943.5 5955.9 5988.5 6002.5 6017.2 6015.8 6064.1 6087.2 6097.4 6105.4 5508.5 5590.4 5611.8 5616.5 5628.5 5269.8 6131.2 6133.5 6162.8 6171.2 5938.0 5938.4 5943.4 5949.8 5976.7 5932.1 6088.1 6102.4 6106.5 6111.4 5874.5 5889.1 5919.3 5964.0 5980.5 5969.4 6006.7 6035.7 6068.1 6086.0 4313.8 4459.4 4752.0 4782.3 4829.6 4261.2 4273.5 4296.8 4305.9 4348.4 5676.9 5686.8 5692.2 5694.7 5710.7 5976.2 5979.2 5992.7 5995.8 6030.5 5611.4 5675.1 5691.0 5692.0 5701.1 5204.5 5207.9 5214.5 5220.1 5221.8 6246.8 6269.3 6278.6 6280.9 6287.5 5972.3 6014.4 6032.8 6044.9 6065.7 6224.4 6265.9 6271.8 6272.8 6278.6 5749.0 5929.4 5935.0 5937.9 5986.1 5879.9 5881.5 5932.5 5951.1 6039.1 5663.5 5811.5 5812.9 5839.9 5840.0 4787.6 5138.8 5769.8 5822.8 5829.8 5641.5 5725.3 5779.5 5794.3 5797.3 5864.2 5868.1 5870.7 5875.8 5885.2 5626.8 5723.0 5734.2 5740.7 5762.8 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:75360.24 Median:37358.66 stddev:173148.72 (463.5%) stddev-sans-first:173543.12 10:15:43 INFO - PROCESS | 1634 | Values: 458.2 479.1 505.0 520.8 525.6 29649.1 31423.1 38359.4 39860.4 40075.4 41265.0 41639.8 41645.8 41992.4 42034.6 9233.0 9263.2 9424.9 9427.4 9430.0 8444.0 8635.7 8688.2 8760.1 9386.0 8573.2 11186.5 11418.9 11434.9 11581.7 11306.4 11374.8 11391.3 11394.2 11395.5 8450.0 11161.0 11250.4 11255.1 11266.8 31110.1 38600.4 38914.4 39187.0 40409.4 3186.2 3256.6 3258.2 3428.8 3548.2 28506.9 29390.9 29487.4 30303.0 30433.8 2974.2 3201.6 3204.5 3268.0 3312.3 487174.0 491146.5 492692.0 494375.0 501720.0 48924.0 60864.0 61282.7 61371.0 61428.3 63890.4 64016.7 64023.7 64037.0 64109.4 15716.2 15906.6 15913.2 15919.8 15927.1 12239.7 12273.9 12289.8 12319.6 12320.9 16438.0 17532.9 18958.3 19003.6 19083.1 6288.8 6512.7 7714.5 7758.9 7759.2 817.1 917.4 923.0 970.6 988.6 25283.5 28758.1 29920.3 29962.0 30433.1 941.5 985.1 1049.7 1127.5 1192.3 13537.2 14922.5 14980.6 15446.7 15771.7 631537.0 634952.0 637866.0 639414.0 639511.0 49701.8 50607.7 55964.4 57605.4 58120.7 1349.2 1372.1 1388.3 1393.0 1394.3 29974.7 30681.2 30737.5 30765.8 31372.2 51429.2 52381.5 52430.2 52446.7 52518.0 644827.0 644852.0 847307.0 847690.0 848478.0 36205.5 39937.6 40012.2 40509.4 40546.4 44490.7 44591.0 44595.3 45044.9 45110.5 30394.8 35374.8 36398.9 36538.3 37949.9 37022.3 37096.8 37559.4 37984.4 38336.8 35398.0 36753.4 37106.6 37149.0 37157.9 31618.0 33437.8 34147.3 34240.0 34402.7 24587.8 25005.0 25013.9 25267.5 25382.0 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:35046.67 Median:13729.08 stddev:101728.55 (741.0%) stddev-sans-first:101980.57 10:15:43 INFO - PROCESS | 1634 | Values: 351.4 363.2 371.1 381.9 398.8 14518.6 14808.0 15443.2 19362.0 19914.5 18971.3 19332.4 19408.5 19435.9 19774.4 5276.9 5873.0 5879.5 5881.3 5889.2 4576.5 5566.8 5750.5 5818.1 5825.0 5752.6 5765.0 5771.7 5775.0 5781.3 5472.6 5684.5 5740.3 5741.9 5757.1 4836.4 5020.9 5045.9 5062.3 5064.0 24371.7 24598.5 24790.8 24973.0 25091.2 2351.9 2360.8 2396.0 2485.2 2874.2 14687.3 14987.3 15423.9 15687.3 16090.3 1832.0 1907.2 1913.2 1930.2 1937.7 111859.0 118772.0 120759.0 122871.0 122906.4 10425.6 10519.1 10724.6 10938.1 11107.7 13563.2 13577.8 13590.5 13592.7 13598.5 8132.1 8378.8 8381.2 8517.6 8530.0 8317.3 8341.2 8379.5 8491.0 8516.6 6471.3 6548.5 6560.9 6596.7 6693.9 1653.6 1780.8 1820.2 1822.3 1825.3 806.6 830.0 915.9 1006.4 1076.4 21511.9 21812.0 21815.7 21886.9 21948.0 928.7 942.5 952.2 982.3 982.7 11123.9 11498.2 11547.2 11598.3 11603.9 605357.0 608747.0 609979.0 612851.0 619433.0 92235.0 96438.6 111927.0 137708.0 142981.0 927.6 954.5 972.0 977.8 1002.7 18857.6 19073.4 19408.3 19431.5 19635.3 38443.0 48634.0 54001.5 55027.0 55367.0 78178.0 78293.6 79520.6 80073.0 81546.4 7144.3 7209.7 7370.9 7409.4 7747.0 12217.6 12349.2 12714.2 12767.1 12874.4 10810.6 10898.7 11078.9 11279.3 11309.7 10686.9 10701.8 10753.0 10920.8 11143.9 11238.8 11472.7 11809.9 11835.9 11853.4 7196.8 7289.1 7450.2 7935.6 8371.1 6823.3 9403.6 11204.4 11260.1 11420.4 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5390.97 Median:5075.78 stddev:2945.39 (58.0%) stddev-sans-first:2937.61 10:15:43 INFO - PROCESS | 1634 | Values: 1300.4 1423.2 1449.4 1600.8 1808.5 6207.5 7782.0 7991.7 8320.8 8327.7 8167.4 8252.1 8260.6 8271.6 8366.4 2303.1 2338.4 2338.6 2338.7 2344.8 2300.7 2345.9 2354.4 2355.3 2357.9 1926.2 2288.5 2297.7 2326.4 2327.0 2261.3 2278.3 2313.1 2313.5 2315.1 2312.3 2334.4 2334.6 2335.7 2337.8 8223.4 8228.5 8309.8 8315.9 8331.0 5012.8 5203.3 5213.2 5226.9 5233.3 3326.5 3537.6 3573.7 3602.4 3614.2 1027.8 1045.9 1072.7 1087.9 1096.3 10803.5 10862.6 10921.5 10921.9 10956.1 4221.5 4393.4 4459.4 4469.4 4475.0 8530.5 8534.7 8534.7 8539.6 8541.7 5073.6 5073.7 5075.7 5075.9 5080.4 4235.6 4266.2 4412.7 4689.3 4711.7 3031.7 3567.6 3599.8 3627.9 3660.2 1121.0 1128.0 1137.1 1143.4 1151.1 602.3 753.1 892.0 897.7 913.8 5524.5 5653.9 5765.9 6017.0 6019.8 869.5 897.6 919.3 921.5 952.7 3699.1 3706.9 3729.5 3851.8 3854.7 8364.5 9806.2 10217.5 10226.3 10227.8 8466.5 10102.4 10180.2 10187.9 10207.8 7651.1 7676.7 7721.7 7821.3 7836.7 5598.7 5665.8 5717.9 5916.5 5946.0 9120.5 9230.6 9306.8 9414.2 9436.5 9398.2 9412.7 9527.4 9636.8 9677.0 7130.0 7142.6 7271.2 7272.4 7283.4 7756.6 7777.1 7836.7 7848.2 7853.1 7033.4 7123.0 7143.5 7144.7 7367.8 7006.6 7083.5 7103.1 7129.3 7144.4 6477.5 6582.1 6680.9 6684.4 6688.6 4855.7 4858.8 4879.9 4951.5 5033.5 4821.7 4831.9 4888.5 5008.9 5015.7 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4403.73 Median:4942.97 stddev:1353.07 (27.4%) stddev-sans-first:1321.30 10:15:43 INFO - PROCESS | 1634 | Values: 297.5 299.2 306.0 310.0 311.0 5136.8 5149.1 5149.3 5215.7 5227.3 4886.0 5039.9 5054.5 5079.4 5157.7 3249.5 3957.6 3970.6 3978.5 3985.6 3447.5 3957.4 3981.7 4048.0 4066.1 3856.9 3956.9 3970.9 3980.3 3987.4 3293.8 3986.7 3988.4 4007.2 4010.1 3459.4 3507.7 3511.3 3519.8 3521.8 4096.2 4321.7 4609.3 5019.3 5038.7 3793.9 3949.8 3956.9 4371.7 4521.7 5417.9 5427.8 5431.7 5432.8 5442.9 3828.6 3837.7 3839.4 3850.9 3854.7 5451.9 5630.3 5645.0 5674.9 5679.3 3690.9 3896.3 3900.1 3901.5 3902.7 3905.2 3945.4 3966.4 3975.9 3983.6 3791.5 4300.8 4538.7 4554.9 4577.4 4517.5 5432.6 5444.5 5453.6 5468.9 1168.0 1173.5 1277.2 1285.9 1292.7 756.2 844.2 848.6 849.5 849.9 4436.6 4567.4 4862.9 4999.9 5042.9 4749.7 5328.2 5330.8 5335.3 5349.8 4498.5 4647.7 4925.5 4964.9 4997.8 2329.4 2659.5 2788.4 2792.2 2799.2 5032.8 5098.1 5203.6 5602.7 5616.9 4653.3 5488.9 5641.9 5844.4 5868.6 5045.6 5505.0 5592.7 6040.9 6044.7 4943.9 5151.9 5283.3 5637.4 5673.2 4631.1 5513.5 5538.2 5580.5 5617.3 5739.8 5995.3 6012.4 6021.6 6022.0 3677.5 3764.5 3803.1 3821.5 3833.2 4942.0 5594.8 5610.9 5613.4 5636.3 5450.8 5474.5 5483.9 5486.9 5487.8 4559.6 4754.0 5305.0 5464.9 5504.6 4220.8 4432.5 4604.6 5301.9 5391.5 4445.4 5319.3 5350.7 5508.2 5545.5 5248.1 5372.3 5372.8 5379.0 5422.0 10:15:43 INFO - PROCESS | 1634 | 10:15:43 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:20391.36 Median:29119.81 stddev:30700.53 (105.4%) stddev-sans-first:30750.74 10:15:43 INFO - PROCESS | 1634 | Values: 613.6 675.1 713.3 721.7 748.6 14380.5 14570.3 14593.3 14594.6 14644.9 12795.3 12889.4 12895.8 12931.2 12935.2 633.0 644.7 647.6 792.8 809.0 779.4 781.8 797.2 802.5 808.9 866.1 866.9 867.5 867.6 869.4 666.4 680.6 860.3 862.1 862.7 616.6 631.7 636.1 638.7 639.3 13828.5 13863.4 13864.6 13873.1 13897.9 12592.1 12835.0 12902.3 12925.4 12973.2 10057.4 11605.4 11971.9 12099.8 12262.4 4039.2 4240.7 4328.7 4467.3 4471.5 74648.5 84747.0 85589.6 90588.0 90872.0 10244.5 10340.3 10391.3 10401.2 10428.3 19466.9 19554.0 19611.7 19630.1 19634.0 7146.9 7225.0 7331.5 7468.4 7489.3 4092.5 4103.4 4173.0 4217.5 4230.8 3148.3 3230.2 3233.7 3248.1 3539.6 677.2 684.3 686.2 688.0 690.3 599.2 634.9 637.1 647.3 649.6 8501.3 8508.8 8532.6 8580.3 8594.9 454.5 535.7 560.0 611.3 634.7 3822.2 4078.7 4145.8 4213.2 4228.7 108754.0 113527.0 113712.0 115174.4 117624.4 64768.7 72699.6 92420.0 93788.0 101155.0 89687.0 90419.0 91105.0 92661.5 92870.5 7959.5 8049.5 8108.6 8211.2 8279.1 53152.7 55612.7 56060.0 57640.4 60024.0 81165.0 82777.0 83526.6 85079.0 86580.0 11112.3 11260.8 11315.8 11396.8 11589.2 11840.3 12088.2 12262.8 12302.4 12449.2 9906.9 11629.6 11760.6 11962.9 12308.3 11776.8 11840.1 11869.6 11885.8 12458.8 9489.7 11327.9 11502.8 11921.8 12008.6 8640.0 8943.5 9321.9 9354.8 9379.2 8951.5 9096.6 9161.5 9191.1 9191.5 10:15:43 INFO - PROCESS | 1634 | -------- Summary: end -------- 10:15:43 INFO - PROCESS | 1634 | 10:15:44 INFO - TEST-INFO | 1634: exit 0 10:15:44 INFO - TEST-OK | dromaeo_css | took 1120519ms 10:15:44 INFO - TEST-START | kraken 10:15:44 INFO - operating with platform_type : mac_ 10:15:44 INFO - Initialising browser for kraken test... 10:15:44 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpMaBojP/profile http://localhost:49236/getInfo.html) 10:15:47 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 10:15:47 INFO - PROCESS | 1643 | colorDepth:24 10:15:47 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 10:15:47 INFO - PROCESS | 1643 | __metrics 10:15:47 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:15:47 INFO - PROCESS | 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:15:48 INFO - TEST-INFO | 1643: exit 0 10:15:48 INFO - Browser initialized. 10:15:48 INFO - Running cycle 1/1 for kraken test... 10:15:48 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpMaBojP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:16:00 INFO - PROCESS | 1644 | RSS: Main: 223055872 10:16:00 INFO - PROCESS | 1644 | 10:16:03 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 10:16:04 INFO - PROCESS | 1644 | RSS: Main: 273276928 10:16:04 INFO - PROCESS | 1644 | 10:16:06 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 10:16:07 INFO - PROCESS | 1644 | RSS: Main: 283844608 10:16:07 INFO - PROCESS | 1644 | 10:16:10 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 10:16:11 INFO - PROCESS | 1644 | RSS: Main: 382025728 10:16:11 INFO - PROCESS | 1644 | 10:16:13 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 10:16:13 INFO - PROCESS | 1644 | RSS: Main: 284598272 10:16:13 INFO - PROCESS | 1644 | 10:16:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:16:16 INFO - PROCESS | 1644 | RSS: Main: 285896704 10:16:16 INFO - PROCESS | 1644 | 10:16:19 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 10:16:19 INFO - PROCESS | 1644 | RSS: Main: 326279168 10:16:19 INFO - PROCESS | 1644 | 10:16:22 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 10:16:23 INFO - PROCESS | 1644 | RSS: Main: 325427200 10:16:23 INFO - PROCESS | 1644 | 10:16:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 10:16:26 INFO - PROCESS | 1644 | RSS: Main: 325087232 10:16:26 INFO - PROCESS | 1644 | 10:16:28 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:16:28 INFO - PROCESS | 1644 | RSS: Main: 299982848 10:16:28 INFO - PROCESS | 1644 | 10:16:30 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:31 INFO - PROCESS | 1644 | RSS: Main: 300707840 10:16:31 INFO - PROCESS | 1644 | 10:16:33 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:34 INFO - PROCESS | 1644 | RSS: Main: 303112192 10:16:34 INFO - PROCESS | 1644 | 10:16:37 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:38 INFO - PROCESS | 1644 | RSS: Main: 318984192 10:16:38 INFO - PROCESS | 1644 | 10:16:40 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:41 INFO - PROCESS | 1644 | RSS: Main: 312676352 10:16:41 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 10:16:43 INFO - PROCESS | 1644 | RSS: Main: 356777984 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | __start_tp_report 10:16:43 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 10:16:43 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 10:16:43 INFO - PROCESS | 1644 | |i|pagename|runs| 10:16:43 INFO - PROCESS | 1644 | |0;ai-astar;118;115;116;116;116;116;116;116;116;114 10:16:43 INFO - PROCESS | 1644 | |1;audio-beat-detection;144;113;108;146;112;111;112;114;145;139 10:16:43 INFO - PROCESS | 1644 | |2;audio-dft;183;183;186;182;185;185;185;183;186;183 10:16:43 INFO - PROCESS | 1644 | |3;audio-fft;82;59;61;90;62;60;61;60;83;60 10:16:43 INFO - PROCESS | 1644 | |4;audio-oscillator;90;70;95;69;67;69;90;67;69;93 10:16:43 INFO - PROCESS | 1644 | |5;imaging-gaussian-blur;88;86;89;86;87;84;89;86;88;85 10:16:43 INFO - PROCESS | 1644 | |6;imaging-darkroom;99;104;97;97;97;97;97;96;98;97 10:16:43 INFO - PROCESS | 1644 | |7;imaging-desaturate;83;83;79;82;95;132;132;84;80;85 10:16:43 INFO - PROCESS | 1644 | |8;json-parse-financial;54;55;56;52;58;59;59;58;58;58 10:16:43 INFO - PROCESS | 1644 | |9;json-stringify-tinderbox;65;72;68;74;73;70;70;68;69;69 10:16:43 INFO - PROCESS | 1644 | |10;stanford-crypto-aes;87;100;105;82;83;83;87;84;84;85 10:16:43 INFO - PROCESS | 1644 | |11;stanford-crypto-ccm;144;146;146;142;151;143;155;136;203;175 10:16:43 INFO - PROCESS | 1644 | |12;stanford-crypto-pbkdf2;167;194;191;166;163;167;165;165;164;162 10:16:43 INFO - PROCESS | 1644 | |13;stanford-crypto-sha256-iterative;69;68;68;68;68;65;67;67;66;67 10:16:43 INFO - PROCESS | 1644 | __end_tp_report 10:16:43 INFO - PROCESS | 1644 | __start_cc_report 10:16:43 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,2084 10:16:43 INFO - PROCESS | 1644 | __end_cc_report 10:16:43 INFO - PROCESS | 1644 | __startTimestamp1482344203541__endTimestamp 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | ------- Summary: start ------- 10:16:43 INFO - PROCESS | 1644 | Number of tests: 14 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#0] ai-astar Cycles:10 Average:115.90 Median:116.00 stddev:0.99 (0.9%) stddev-sans-first:0.71 10:16:43 INFO - PROCESS | 1644 | Values: 118.0 115.0 116.0 116.0 116.0 116.0 116.0 116.0 116.0 114.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#1] audio-beat-detection Cycles:10 Average:124.40 Median:126.50 stddev:16.61 (13.1%) stddev-sans-first:16.03 10:16:43 INFO - PROCESS | 1644 | Values: 144.0 113.0 108.0 146.0 112.0 111.0 112.0 114.0 145.0 139.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:1.45 (0.8%) stddev-sans-first:1.48 10:16:43 INFO - PROCESS | 1644 | Values: 183.0 183.0 186.0 182.0 185.0 185.0 185.0 183.0 186.0 183.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#3] audio-fft Cycles:10 Average:67.80 Median:61.50 stddev:12.07 (19.6%) stddev-sans-first:11.66 10:16:43 INFO - PROCESS | 1644 | Values: 82.0 59.0 61.0 90.0 62.0 60.0 61.0 60.0 83.0 60.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:80.00 stddev:12.25 (15.3%) stddev-sans-first:12.19 10:16:43 INFO - PROCESS | 1644 | Values: 90.0 70.0 95.0 69.0 67.0 69.0 90.0 67.0 69.0 93.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:1.69 (1.9%) stddev-sans-first:1.73 10:16:43 INFO - PROCESS | 1644 | Values: 88.0 86.0 89.0 86.0 87.0 84.0 89.0 86.0 88.0 85.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:97.00 stddev:2.28 (2.4%) stddev-sans-first:2.39 10:16:43 INFO - PROCESS | 1644 | Values: 99.0 104.0 97.0 97.0 97.0 97.0 97.0 96.0 98.0 97.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:83.00 stddev:20.75 (25.0%) stddev-sans-first:21.66 10:16:43 INFO - PROCESS | 1644 | Values: 83.0 83.0 79.0 82.0 95.0 132.0 132.0 84.0 80.0 85.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:58.00 stddev:2.36 (4.1%) stddev-sans-first:2.29 10:16:43 INFO - PROCESS | 1644 | Values: 54.0 55.0 56.0 52.0 58.0 59.0 59.0 58.0 58.0 58.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:2.66 (3.8%) stddev-sans-first:2.18 10:16:43 INFO - PROCESS | 1644 | Values: 65.0 72.0 68.0 74.0 73.0 70.0 70.0 68.0 69.0 69.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:84.00 stddev:7.90 (9.4%) stddev-sans-first:8.37 10:16:43 INFO - PROCESS | 1644 | Values: 87.0 100.0 105.0 82.0 83.0 83.0 87.0 84.0 84.0 85.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:148.50 stddev:20.19 (13.6%) stddev-sans-first:21.08 10:16:43 INFO - PROCESS | 1644 | Values: 144.0 146.0 146.0 142.0 151.0 143.0 155.0 136.0 203.0 175.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:166.50 stddev:11.78 (7.1%) stddev-sans-first:12.43 10:16:43 INFO - PROCESS | 1644 | Values: 167.0 194.0 191.0 166.0 163.0 167.0 165.0 165.0 164.0 162.0 10:16:43 INFO - PROCESS | 1644 | 10:16:43 INFO - PROCESS | 1644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.05 10:16:43 INFO - PROCESS | 1644 | Values: 69.0 68.0 68.0 68.0 68.0 65.0 67.0 67.0 66.0 67.0 10:16:43 INFO - PROCESS | 1644 | -------- Summary: end -------- 10:16:43 INFO - PROCESS | 1644 | 10:16:44 INFO - TEST-INFO | 1644: exit 0 10:16:44 INFO - TEST-OK | kraken | took 59970ms 10:16:44 INFO - SUITE-END | took 1180s 10:16:44 INFO - Completed test suite (00:19:40) 10:16:44 INFO - javascript benchmark 10:16:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2685.845086612757, 2686.959695604566, 3281.5898012748407, 3302.422076136677, 3302.8678852845887, 5438.075527160382, 5531.197976293435, 5532.585056120787, 5573.5819813514045, 5583.106702927527, 5544.473275038865, 5560.193771903072, 5576.358718747345, 5586.190002449643, 5597.776088956437, 5033.370828646419, 5075.568576670988, 5079.619028572872, 5148.871278218037, 5185.325907632003, 5018.447970723218, 5020.297158397827, 5028.874278143039, 5092.0, 5122.538971492565, 5098.209777484496, 5116.386033675964, 5119.0, 5119.590432765389, 5120.667156634825, 5047.167217409123, 5071.239314102877, 5073.822416215415, 5092.236164575306, 5107.948920510822, 4991.101601711691, 5039.21892106724, 5062.063518249099, 5089.770960306795, 5140.537351638371, 5417.972910135424, 5456.945430545684, 5548.361938377098, 5580.693061881595, 5638.295213098363, 5943.494802941756, 5955.880882382371, 5988.490978266853, 6002.489788367994, 6017.157597936292, 6015.75936962521, 6064.060070689006, 6087.239095113111, 6097.420745029215, 6105.419985101408, 5508.504234618904, 5590.357108932484, 5611.831645050655, 5616.52259557938, 5628.465295796893, 5269.789208431696, 6131.233595800525, 6133.548895741652, 6162.753489860445, 6171.228596425447, 5937.970310148422, 5938.406159384113, 5943.405659434108, 5949.791757288474, 5976.671283079472, 5932.110183472513, 6088.1476593276975, 6102.359252278536, 6106.542009349228, 6111.419415155553, 5874.471297583238, 5889.087191485327, 5919.289685237799, 5964.045752679551, 5980.461758441761, 5969.432903874125, 6006.6696331701305, 6035.698215089228, 6068.120122582289, 6086.0, 4313.84901528445, 4459.375687403766, 4751.95248047522, 4782.33047373368, 4829.589484893753, 4261.169072030986, 4273.508546517191, 4296.763677997677, 4305.935410968838, 4348.434703488526, 5676.8864622707315, 5686.800961966394, 5692.231548740867, 5694.715264236772, 5710.71446427677, 5976.193213916065, 5979.19280897082, 5992.670403127782, 5995.820125396245, 6030.48740857032, 5611.438856114356, 5675.148727690879, 5691.03252447085, 5692.0323544997445, 5701.087825947829, 5204.531592156724, 5207.869803254873, 5214.530692237717, 5220.112580861262, 5221.843344699665, 6246.750129994749, 6269.31037585856, 6278.560500765086, 6280.874382512324, 6287.528435367274, 5972.34304226526, 6014.398560143951, 6032.819015429544, 6044.879102418015, 6065.727042283196, 6224.36142864955, 6265.906011409924, 6271.78048768291, 6272.8431789203805, 6278.591891527219, 5749.022666146849, 5929.377415371324, 5934.991051521338, 5937.940620593911, 5986.102084687332, 5879.911801322898, 5881.470667639933, 5932.466078052996, 5951.1073338999495, 6039.063945088518, 5663.518600918968, 5811.506021988009, 5812.883742325214, 5839.912401313899, 5840.0, 4787.640926930564, 5138.820141295037, 5769.826905192851, 5822.796202132905, 5829.795957141479, 5641.548676105984, 5725.34158571753, 5779.479846813807, 5794.304683438015, 5797.304323481209, 5864.208331875313, 5868.149118377897, 5870.677112758668, 5875.764969401176, 5885.205497257815, 5626.803061892814, 5722.971385143048, 5734.22587950638, 5740.655560666373, 5762.798302059407], "value": 5506.984599190487, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.2461850256328, 479.13037836327067, 504.9597828473345, 520.778669065647, 525.6150044632395, 29649.110526684206, 31423.05730828076, 38359.424608630856, 39860.402093968616, 40075.398869016986, 41265.0, 41639.79180104103, 41645.79177104118, 41992.37011444823, 42034.57965420345, 9232.953835230848, 9263.166315031629, 9424.858627120599, 9427.387219830724, 9430.0, 8444.02893667227, 8635.740927772176, 8688.174623410765, 8760.123987601253, 9385.95307023464, 8573.228409443149, 11186.496607652674, 11418.885811141907, 11434.942825285903, 11581.652550423458, 11306.378149201832, 11374.772504549905, 11391.259568128127, 11394.202405831595, 11395.487203075798, 8450.0, 11161.0, 11250.437478126061, 11255.099592032619, 11266.830997535044, 31110.066697999097, 38600.42099368512, 38914.41628375577, 39187.02032449183, 40409.39385909214, 3186.23530352716, 3256.5766450361302, 3258.234314935988, 3428.777129486575, 3548.2193917338145, 28506.85972561095, 29390.85304573485, 29487.41025179506, 30303.0, 30433.847830760926, 2974.182099922517, 3201.5677883485664, 3204.4872820348864, 3268.0195941217776, 3312.287858110518, 487174.0, 491146.54426727636, 492692.0, 494375.0, 501720.0, 48924.0, 60864.0, 61282.693586532674, 61371.0, 61428.314301142076, 63890.36109638937, 64016.67991660105, 64023.67988160029, 64037.0, 64109.358906411275, 15716.21418929049, 15906.602334941488, 15913.204339782964, 15919.761203581955, 15927.124008179662, 12239.69400764988, 12273.938630306791, 12289.815652765215, 12319.63041108768, 12320.876791232153, 16438.013719176648, 17532.914916710117, 18958.336458223897, 19003.61992760165, 19083.141258643394, 6288.779892703734, 6512.739490420425, 7714.498557593738, 7758.883616745753, 7759.245467858829, 817.1134319263547, 917.4128557722929, 922.9648624782338, 970.6166064404521, 988.6440881282732, 25283.494330113663, 28758.137255882357, 29920.251993699898, 29962.0, 30433.087007389815, 941.5480569326761, 985.1330828870548, 1049.6536143072767, 1127.548980407827, 1192.3144192089583, 13537.154736565364, 14922.477713279983, 14980.625484362978, 15446.691066178839, 15771.68456630861, 631537.0, 634952.0, 637866.0, 639414.0, 639511.0, 49701.751491242314, 50607.74696126496, 55964.44035559592, 57605.42394576084, 58120.709396452745, 1349.1635186184628, 1372.1081297156927, 1388.2850332078988, 1393.0318428691962, 1394.2958805803005, 29974.70025299719, 30681.232969175948, 30737.53893691641, 30765.769369225876, 31372.215694606904, 51429.22856157235, 52381.47618523918, 52430.21354679759, 52446.68883277949, 52518.0, 644827.0, 644852.0, 847307.0, 847690.0, 848478.0, 36205.45691814572, 39937.600623993385, 40012.1997560053, 40509.39235911405, 40546.39180412237, 44490.66528004165, 44591.0, 44595.331070033324, 45044.87387815331, 45110.54889451063, 30394.78420863141, 35374.823125884206, 36398.90803275906, 36538.346643299206, 37949.86150415492, 37022.25955480822, 37096.814515927246, 37559.43660845035, 37984.430233545965, 38336.80831595824, 35398.0, 36753.448698269014, 37106.628933710315, 37149.0, 37157.88526344214, 31618.0, 33437.83281083579, 34147.31705365929, 34240.0, 34402.65597343994, 24587.75412245926, 25005.0, 25013.874930625232, 25267.494650107263, 25382.0], "value": 20097.944029093287, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.43594530778114, 363.21907898019253, 371.0963803139354, 381.9465274861519, 398.78465628560576, 14518.637034074154, 14808.0, 15443.15062671554, 19362.0, 19914.502137446572, 18971.33600323989, 19332.4200273992, 19408.514787130294, 19435.902820485877, 19774.40676779699, 5276.947230527693, 5872.9706351468185, 5879.529637629001, 5881.264841894763, 5889.1755154278435, 4576.450825900896, 5566.840136205703, 5750.511206547449, 5818.06910894257, 5825.038868586678, 5752.626079304831, 5764.971175144118, 5771.653700777946, 5774.971125144369, 5781.335146458148, 5472.5621950243885, 5684.516816070639, 5740.311162660466, 5741.856453588652, 5757.107648314518, 4836.395450568679, 5020.924686129711, 5045.924311135299, 5062.341895553592, 5063.974680126613, 24371.659558724263, 24598.508029839304, 24790.752092479026, 24973.0, 25091.24726258215, 2351.8588884666974, 2360.775726305998, 2396.0056576520724, 2485.155047283932, 2874.152125123094, 14687.33906974178, 14987.250637468193, 15423.922880385639, 15687.294071766684, 16090.275937582837, 1831.9557852024352, 1907.1989764299028, 1913.23470611755, 1930.2182616040527, 1937.651222779899, 111859.0, 118771.99999999914, 120759.0, 122871.0, 122906.38546807299, 10425.635102771519, 10519.105876000625, 10724.624638137784, 10938.124950003981, 11107.72230694239, 13563.186208827501, 13577.77799998005, 13590.456381744818, 13592.728145437035, 13598.456061757419, 8132.105468398471, 8378.79053023667, 8381.245687888118, 8517.61670724819, 8530.019047809457, 8317.251447369767, 8341.16588341161, 8379.539125348043, 8490.957545212234, 8516.616752246164, 6471.288158302583, 6548.541602087904, 6560.868782624416, 6596.70314835824, 6693.899591506131, 1653.6031352475559, 1780.759597454353, 1820.1718218210683, 1822.3166312632763, 1825.2881376263267, 806.5563939833069, 829.991560254294, 915.9129882661136, 1006.3509036671452, 1076.4294923690456, 21511.892440537697, 21812.0, 21815.67276490854, 21886.9056547172, 21948.0, 928.7353104365224, 942.5004747483845, 952.1620973543376, 982.2878413150409, 982.7248370456284, 11123.944380278046, 11498.195126341076, 11547.191696581158, 11598.260260960924, 11603.941980290214, 605357.0, 608747.0, 609979.0, 612851.0, 619433.0, 92235.0, 96438.63618321872, 111927.0, 137708.0, 142981.0, 927.6011315134557, 954.4559601027487, 972.027972027972, 977.7702239973601, 1002.734275417015, 18857.622847543247, 19073.427797166107, 19408.32070877569, 19431.514212144808, 19635.31276405319, 38443.0, 48634.0, 54001.45998540121, 55027.0, 55367.0, 78178.0, 78293.60853195697, 79520.60239698995, 80072.98905164273, 81546.3690726194, 7144.285571442814, 7209.675564599711, 7370.926290737024, 7409.37020353276, 7746.961265193638, 12217.572384966483, 12349.19730217569, 12714.173578717724, 12767.106302559103, 12874.420651070912, 10810.6492665553, 10898.673039808818, 11078.944605276922, 11279.32324060559, 11309.71725706864, 10686.89313106859, 10701.785964280827, 10753.0, 10920.798712141499, 11143.944280278547, 11238.775224495628, 11472.713182170513, 11809.881901181221, 11835.881641183822, 11853.407329633312, 7196.784096477114, 7289.137101398646, 7450.180480147071, 7935.642896069806, 8371.079181289932, 6823.28355522663, 9403.623855045722, 11204.383758893013, 11260.099192064452, 11420.428978551205], "value": 9554.522367606476, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.4083142170314, 1423.2314550142921, 1449.3984996226566, 1600.8479194476529, 1808.4574627611714, 6207.472364848988, 7781.961090194548, 7991.720289789859, 8320.791980200498, 8327.708530201444, 8167.387445941548, 8252.133525979776, 8260.586970651473, 8271.586420678972, 8366.372522060854, 2303.1248125712204, 2338.4270853640883, 2338.5790557699693, 2338.7427382987858, 2344.847584906984, 2300.6549017647403, 2345.870977096259, 2354.434935615457, 2355.269866341431, 2357.8585284882874, 1926.2295081967254, 2288.5308511755134, 2297.747247802749, 2326.3835083702834, 2327.022650486791, 2261.321603518946, 2278.3392816083315, 2313.051648823974, 2313.548857972696, 2315.0855412112123, 2312.3409828198883, 2334.36972017556, 2334.568104900599, 2335.6963594732574, 2337.789598936104, 8223.42436029478, 8228.50628962264, 8309.83380332396, 8315.875261871077, 8330.958345208295, 5012.799488020475, 5203.323567936144, 5213.244079608436, 5226.947730522684, 5233.267342572043, 3326.5383616124136, 3537.6285490023693, 3573.6605022522817, 3602.3695853225845, 3614.1868079681863, 1027.820131476996, 1045.8954104589557, 1072.6942821295893, 1087.8640169978753, 1096.3202814255133, 10803.513841877135, 10862.619808306672, 10921.508532116073, 10921.945390273077, 10956.12351011925, 4221.514525829508, 4393.362962370502, 4459.353393757951, 4469.44131983502, 4475.0, 8530.488170709777, 8534.658613655509, 8534.70128545498, 8539.615717292736, 8541.658333666708, 5073.594112470993, 5073.746312684351, 5075.670081444769, 5075.898482030412, 5080.390353157644, 4235.576442355739, 4266.189424009428, 4412.6690498212765, 4689.343491911135, 4711.693739906964, 3031.7271445570004, 3567.5897271813556, 3599.8380072896784, 3627.8730244441317, 3660.1516234301507, 1120.9551617935354, 1127.960521381764, 1137.135776809614, 1143.422571601336, 1151.0618845640777, 602.3464540973006, 753.1226121568311, 891.9509426981579, 897.6544030548156, 913.8263729891432, 5524.475174858382, 5653.886922261532, 5765.94234057654, 6017.00392780792, 6019.819405417845, 869.4956924983621, 897.582624079806, 919.2599957034652, 921.5023887101024, 952.7046615549202, 3699.1122130688977, 3706.9073273168387, 3729.4778730977687, 3851.826667799956, 3854.7494412863075, 8364.498130112213, 9806.215502759762, 10217.489125543692, 10226.335288206243, 10227.846582301272, 8466.492010479391, 10102.39385636864, 10180.236482263712, 10187.898121018696, 10207.846882296772, 7651.081870175614, 7676.731314403969, 7721.65252563636, 7821.2960833526395, 7836.742021873407, 5598.692071936082, 5665.801696940587, 5717.914231286451, 5916.49709774674, 5945.989181839184, 9120.45277283365, 9230.584623691815, 9306.767330816783, 9414.199793017397, 9436.528173591156, 9398.20115290208, 9412.67055653049, 9527.38072025316, 9636.75908102275, 9677.0, 7129.964350178216, 7142.607156606437, 7271.200167981624, 7272.418206543358, 7283.417326613964, 7756.573388463688, 7777.144514103557, 7836.686532538863, 7848.215178482106, 7853.136155023057, 7033.402160816183, 7122.964385178041, 7143.535670181422, 7144.714211431485, 7367.8158046049275, 7006.649667516705, 7083.539569927938, 7103.147622285359, 7129.287071292829, 7144.3570078693165, 6477.546571739933, 6582.144321238208, 6680.899786503109, 6684.364985326386, 6688.598684078971, 4855.708657480563, 4858.805647774049, 4879.90240195187, 4951.480094590161, 5033.521815427529, 4821.686590371614, 4831.903361932671, 4888.535589119028, 5008.874778130576, 5015.724135172601], "value": 4361.5104890231905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.52841745832853, 299.17875431939314, 306.0344612746784, 309.9612548431446, 310.9906702798914, 5136.794528218871, 5149.124648809702, 5149.279100925869, 5215.739213039352, 5227.301127133678, 4885.951140488594, 5039.949600503994, 5054.469280725526, 5079.415867175276, 5157.690538567688, 3249.4800831866905, 3957.584453632356, 3970.5632380438274, 3978.542467616225, 3985.6014398560055, 3447.5001124836886, 3957.426173204898, 3981.681465482769, 4048.0, 4066.085130845566, 3856.86500972466, 3956.861509847156, 3970.880873573783, 3980.263651224518, 3987.4018897165365, 3293.76483818568, 3986.727565287787, 3988.441618173458, 4007.1985602879395, 4010.13782036862, 3459.41189997701, 3507.6667716566885, 3511.2977404518942, 3519.788812671248, 3521.806300653463, 4096.242195193899, 4321.7406955582765, 4609.289521934841, 5019.322391477141, 5038.748062596892, 3793.886183414502, 3949.8275948228647, 3956.8615098471414, 4371.715838470521, 4521.683482156251, 5417.918731219035, 5427.83716488506, 5431.728413579305, 5432.809851655173, 5442.91835622466, 3828.5979972102805, 3837.7313588048846, 3839.3856982882603, 3850.8844734658005, 3854.71089668276, 5451.945480545183, 5630.268065151504, 5645.0, 5674.942416172456, 5679.280071553139, 3690.8892733218045, 3896.2597106549665, 3900.063984643665, 3901.49280593521, 3902.70729695274, 3905.1603905160528, 3945.368741001426, 3966.424868394067, 3975.8807235782974, 3983.649646708398, 3791.526059242595, 4300.78496075195, 4538.704984176084, 4554.86128467883, 4577.382053422812, 4517.480489743722, 5432.565394768409, 5444.455554444524, 5453.563714902798, 5468.945310546923, 1168.0, 1173.5423184957874, 1277.2464246094662, 1285.8585555588877, 1292.6962163891478, 756.2022066719663, 844.2486187293314, 848.5884346092264, 849.5497386385226, 849.9248450709857, 4436.578525040116, 4567.429071366079, 4862.884645179358, 4999.900001999939, 5042.924356134661, 4749.667523273407, 5328.200769884548, 5330.786768529293, 5335.279737235423, 5349.759260833194, 4498.527654596286, 4647.744374059392, 4925.482824303468, 4964.925526117111, 4997.800087996512, 2329.3710698111413, 2659.539925568841, 2788.386554957906, 2792.1571762636563, 2799.1805326537915, 5032.84901452957, 5098.099953535549, 5203.557697595671, 5602.730893987941, 5616.887662246814, 4653.348531205634, 5488.914212599267, 5641.915371269434, 5844.357120716718, 5868.559858010666, 5045.646804723634, 5504.954058728828, 5592.748326325325, 6040.882436749215, 6044.667543285162, 4943.937053533507, 5151.913475042614, 5283.286756288006, 5637.408072152365, 5673.234113394639, 4631.073785242897, 5513.5037846594, 5538.224648549126, 5580.544560393414, 5617.269754931996, 5739.827805165852, 5995.280566332068, 6012.368701286477, 6021.638701677914, 6022.036474164112, 3677.5035370225746, 3764.5294338207723, 3803.1252811853983, 3821.4649949006607, 3833.233353329401, 4942.0, 5594.776208951596, 5610.867870209295, 5613.410591887956, 5636.267285252891, 5450.754716037867, 5474.479924407175, 5483.917741233964, 5486.917696234639, 5487.8048780488125, 4559.612432943104, 4753.952460475351, 5304.968224149751, 5464.890702185855, 5504.587155963399, 4220.831166753295, 4432.490263619596, 4604.58558729716, 5301.893962120659, 5391.514763671288, 4445.399871017501, 5319.255304257485, 5350.705711185922, 5508.173773933942, 5545.500904918576, 5248.055350036877, 5372.328458942632, 5372.758225879923, 5379.0, 5422.0], "value": 3969.8449979202137, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.58276372067, 675.1358261425374, 713.3187805645609, 721.6824597177242, 748.5957582905231, 14380.49668261611, 14570.344334504945, 14593.343299551532, 14594.562163135122, 14644.926775366108, 12795.296258705768, 12889.355532223399, 12895.806562901564, 12931.159474634142, 12935.223886566813, 632.9905051424232, 644.7131026693123, 647.6049609738056, 792.7740593930716, 809.0049239435498, 779.364817673597, 781.7732857471354, 797.1868693932186, 802.4944285100407, 808.9474184178039, 866.0603245478663, 866.9089745576687, 867.4578388507183, 867.6486023160599, 869.387082107113, 666.4035688059208, 680.5746408494691, 860.2730692564772, 862.1163307609719, 862.6592495964059, 616.6454288783967, 631.7441436218339, 636.1380329653346, 638.6551262318343, 639.2744235292976, 13828.516001939885, 13863.4454621815, 13864.584062478141, 13873.098248613807, 13897.930510347485, 12592.118551701384, 12835.0, 12902.290374029424, 12925.418356173996, 12973.221606703533, 10057.396556206651, 11605.361705106216, 11971.940140299243, 12099.818502722466, 12262.386880655931, 4039.1719697462245, 4240.745555266663, 4328.718633288826, 4467.285234362486, 4471.5081341052455, 74648.50702985964, 84747.0, 85589.57205213934, 90587.99999999935, 90872.0, 10244.48777561119, 10340.327878688011, 10391.272610917315, 10401.167906567456, 10428.322159059788, 19466.902665486865, 19554.022298885, 19611.705824412646, 19630.116644751022, 19634.0, 7146.9285307146265, 7225.0, 7331.450141239428, 7468.402527797872, 7489.325960663567, 4092.529359123679, 4103.4460347852655, 4172.958270417318, 4217.472815898012, 4230.767307798101, 3148.275896543808, 3230.2408933900515, 3233.708966193054, 3248.1067706380936, 3539.6460353964912, 677.1772296659582, 684.2507454337592, 686.2040033561045, 687.9931200688029, 690.3234829866759, 599.2060519811267, 634.9206349206349, 637.1144109687538, 647.2684675025578, 649.6426965169233, 8501.319894408556, 8508.829823403497, 8532.576210400453, 8580.270676992526, 8594.87107693385, 454.50231995964367, 535.6678859107378, 560.0, 611.3305930006636, 634.6953462338012, 3822.2164456286396, 4078.7348822326453, 4145.813438395279, 4213.157368526246, 4228.70399072062, 108754.0, 113527.0, 113712.0, 115174.42412787882, 117624.41187794178, 64768.676156618916, 72699.63650181715, 92420.0, 93788.0, 101155.0, 89687.0, 90419.0, 91105.0, 92661.5366923161, 92870.53564732269, 7959.482633628795, 8049.51702897828, 8108.594570271345, 8211.21993410625, 8279.13069127757, 53152.73423632857, 55612.72193639006, 56060.0, 57640.42359576518, 60024.0, 81165.0, 82777.0, 83526.58236708777, 85079.0, 86580.0, 11112.326369900355, 11260.831087533857, 11315.830262545904, 11396.829047564459, 11589.24669896454, 11840.348780816794, 12088.214266072677, 12262.816057758962, 12302.446389912297, 12449.190802597803, 9906.851397229191, 11629.59296424621, 11760.588379407021, 11962.880371196177, 12308.317354618483, 11776.794576988237, 11840.052795776144, 11869.5845645402, 11885.76228475443, 12458.75082498363, 9489.66786162481, 11327.943360283145, 11502.82745758831, 11921.82117268259, 12008.63974080779, 8639.95680021596, 8943.508107054175, 9321.906780932375, 9354.76613084651, 9379.202767764817, 8951.462912225026, 9096.590653420479, 9161.541922903962, 9191.080891910755, 9191.494467804336], "value": 6990.500802081482, "unit": "score"}], "name": "dromaeo_css", "value": 7099.163839186694, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 114.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 108.0, 146.0, 112.0, 111.0, 112.0, 114.0, 145.0, 139.0], "value": 124.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 186.0, 182.0, 185.0, 185.0, 185.0, 183.0, 186.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 90.0, 62.0, 60.0, 61.0, 60.0, 83.0, 60.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 95.0, 69.0, 67.0, 69.0, 90.0, 67.0, 69.0, 93.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 87.0, 84.0, 89.0, 86.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 104.0, 97.0, 97.0, 97.0, 97.0, 97.0, 96.0, 98.0, 97.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 79.0, 82.0, 95.0, 132.0, 132.0, 84.0, 80.0, 85.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 52.0, 58.0, 59.0, 59.0, 58.0, 58.0, 58.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 68.0, 74.0, 73.0, 70.0, 70.0, 68.0, 69.0, 69.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 100.0, 105.0, 82.0, 83.0, 83.0, 87.0, 84.0, 84.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 146.0, 146.0, 142.0, 151.0, 143.0, 155.0, 136.0, 203.0, 175.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 194.0, 191.0, 166.0, 163.0, 167.0, 165.0, 165.0, 164.0, 162.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 68.0, 68.0, 65.0, 67.0, 67.0, 66.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1454.6, "alertThreshold": 2.0}]} 10:16:44 INFO - Return code: 0 10:16:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:16:44 INFO - # TBPL SUCCESS # 10:16:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:16:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:16:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:16:44 INFO - 2016-12-21 10:16:44.873 screenresolution[1648:9644] starting screenresolution argv=screenresolution get 10:16:44 INFO - 2016-12-21 10:16:44.876 screenresolution[1648:9644] Display 0: 1600x1200x32@60 10:16:44 INFO - 2016-12-21 10:16:44.882 screenresolution[1649:9647] starting screenresolution argv=screenresolution list 10:16:44 INFO - Available Modes on Display 0 10:16:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:16:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:16:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:16:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:16:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:16:45 INFO - Intel Iris: 10:16:45 INFO - Chipset Model: Intel Iris 10:16:45 INFO - Type: GPU 10:16:45 INFO - Bus: Built-In 10:16:45 INFO - VRAM (Dynamic, Max): 1536 MB 10:16:45 INFO - Vendor: Intel (0x8086) 10:16:45 INFO - Device ID: 0x0a2e 10:16:45 INFO - Revision ID: 0x0009 10:16:45 INFO - Displays: 10:16:45 INFO - Display: 10:16:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:16:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:16:45 INFO - Display Serial Number: 1600x1200 60 10:16:45 INFO - Main Display: Yes 10:16:45 INFO - Mirror: Off 10:16:45 INFO - Online: Yes 10:16:45 INFO - Rotation: Supported 10:16:45 INFO - Return code: 0 10:16:45 INFO - Running post-action listener: _resource_record_post_action 10:16:45 INFO - [mozharness: 2016-12-21 18:16:45.132160Z] Finished run-tests step (success) 10:16:45 INFO - Running post-run listener: _resource_record_post_run 10:16:45 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38781952; Write bytes: 652895232; Read time: 388; Write time: 2017 10:16:45 INFO - TinderboxPrint: CPU usage
53.4% 10:16:45 INFO - TinderboxPrint: I/O read bytes / time
38,781,952 / 388 10:16:45 INFO - TinderboxPrint: I/O write bytes / time
652,895,232 / 2,017 10:16:45 INFO - TinderboxPrint: CPU idle
2,262.1 (46.6%) 10:16:45 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 10:16:45 INFO - TinderboxPrint: CPU user
1,618.4 (33.4%) 10:16:45 INFO - TinderboxPrint: Swap in / out
387,190,784 / 0 10:16:45 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344152576; Write bytes: 345430016; Read time: 26967; Write time: 671 10:16:45 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 36824064; Write bytes: 300596224; Read time: 364; Write time: 1318 10:16:45 INFO - Running post-run listener: _upload_blobber_files 10:16:45 INFO - Blob upload gear active. 10:16:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:16:45 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 10:16:45 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'] 10:16:45 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 10:16:46 INFO - (blobuploader) - INFO - Open directory for files ... 10:16:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:16:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:16:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:47 INFO - (blobuploader) - INFO - Done attempting. 10:16:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:16:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:16:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:48 INFO - (blobuploader) - INFO - Done attempting. 10:16:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:16:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:16:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:51 INFO - (blobuploader) - INFO - Done attempting. 10:16:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:16:51 INFO - Return code: 0 10:16:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:16:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:16:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f6fbd4b186d5663735728517d66e6ec703b1692d9567001b0fe18003cc50e2e7e06fb292364b4b36c9c44dd7ff9ea23fd07df4aa0ebe486ca86ef3da5dcae09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/035e15d16ac569fc2f6b85e53c0ecf247e6d88d28418eeedbe21433e3aaac1873c0b96a5cba87bf790762df6755b5f16ab90f117587b524875304187eda76d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e26b26804f392be1b4f6510f3fdf8f3802bd15d5f495d4e6c2b1de25ce4727215842f45bcd0c843b35ad4258f676a614ddc1b8a34f3b3df3c694d59e63765b69"} 10:16:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:16:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:16:51 INFO - Contents: 10:16:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f6fbd4b186d5663735728517d66e6ec703b1692d9567001b0fe18003cc50e2e7e06fb292364b4b36c9c44dd7ff9ea23fd07df4aa0ebe486ca86ef3da5dcae09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/035e15d16ac569fc2f6b85e53c0ecf247e6d88d28418eeedbe21433e3aaac1873c0b96a5cba87bf790762df6755b5f16ab90f117587b524875304187eda76d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e26b26804f392be1b4f6510f3fdf8f3802bd15d5f495d4e6c2b1de25ce4727215842f45bcd0c843b35ad4258f676a614ddc1b8a34f3b3df3c694d59e63765b69"} 10:16:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:16:51 INFO - Copying logs to upload dir... 10:16:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:16:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.897780 ========= master_lag: 1.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-21 10:16:52.947003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 10:16:52.954117) ========= 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.o14UtmXpF0/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.aWp1OX5w0H/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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/8f6fbd4b186d5663735728517d66e6ec703b1692d9567001b0fe18003cc50e2e7e06fb292364b4b36c9c44dd7ff9ea23fd07df4aa0ebe486ca86ef3da5dcae09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/035e15d16ac569fc2f6b85e53c0ecf247e6d88d28418eeedbe21433e3aaac1873c0b96a5cba87bf790762df6755b5f16ab90f117587b524875304187eda76d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e26b26804f392be1b4f6510f3fdf8f3802bd15d5f495d4e6c2b1de25ce4727215842f45bcd0c843b35ad4258f676a614ddc1b8a34f3b3df3c694d59e63765b69"} build_url:https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010813 build_url: 'https://queue.taskcluster.net/v1/task/a16xVuguRZ2eHIHc1j6TvQ/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/8f6fbd4b186d5663735728517d66e6ec703b1692d9567001b0fe18003cc50e2e7e06fb292364b4b36c9c44dd7ff9ea23fd07df4aa0ebe486ca86ef3da5dcae09", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/035e15d16ac569fc2f6b85e53c0ecf247e6d88d28418eeedbe21433e3aaac1873c0b96a5cba87bf790762df6755b5f16ab90f117587b524875304187eda76d91", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e26b26804f392be1b4f6510f3fdf8f3802bd15d5f495d4e6c2b1de25ce4727215842f45bcd0c843b35ad4258f676a614ddc1b8a34f3b3df3c694d59e63765b69"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 10:16:52.991456) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 10:16:52.994795) ========= 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.o14UtmXpF0/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.aWp1OX5w0H/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.005067 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 10:16:53.043178) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 10:16:53.043452) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 10:16:53.046114) ========= ========= Total master_lag: 1.69 =========