builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0229 starttime: 1477948959.31 results: success (0) buildid: 20161031115704 builduid: 367e98a0ef1e47349a805daccf50e7a2 revision: 37ab1d54a08e7e1431660b22377428b74dcd090a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.308652) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.309105) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.309396) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.333444) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.333826) ========= 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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-31 14:22:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.52M=0.001s 2016-10-31 14:22:39 (9.52 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095042 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.451688) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.452051) ========= 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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020066 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:39.501125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-31 14:22:39.501484) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 37ab1d54a08e7e1431660b22377428b74dcd090a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 37ab1d54a08e7e1431660b22377428b74dcd090a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ghXYjMmodO/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.KVtXrhcyzZ/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-31 14:22:39,582 truncating revision to first 12 chars 2016-10-31 14:22:39,582 Setting DEBUG logging. 2016-10-31 14:22:39,582 attempt 1/10 2016-10-31 14:22:39,583 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/37ab1d54a08e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 14:22:41,423 unpacking tar archive at: mozilla-central-37ab1d54a08e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.108695 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-31 14:22:41.632913) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:41.633243) ========= script_repo_revision: 37ab1d54a08e7e1431660b22377428b74dcd090a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:41.633656) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:41.633916) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:41.652269) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-31 14:22:41.652620) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ghXYjMmodO/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.KVtXrhcyzZ/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:22:41 INFO - MultiFileLogger online at 20161031 14:22:41 in /builds/slave/test 14:22:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:22:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:22:41 INFO - {'append_to_log': False, 14:22:41 INFO - 'base_work_dir': '/builds/slave/test', 14:22:41 INFO - 'blob_upload_branch': 'Firefox', 14:22:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:22:41 INFO - 'branch': 'Firefox', 14:22:41 INFO - 'buildbot_json_path': 'buildprops.json', 14:22:41 INFO - 'config_files': ('talos/mac_config.py',), 14:22:41 INFO - 'default_actions': ('clobber', 14:22:41 INFO - 'read-buildbot-config', 14:22:41 INFO - 'download-and-extract', 14:22:41 INFO - 'populate-webroot', 14:22:41 INFO - 'create-virtualenv', 14:22:41 INFO - 'install', 14:22:41 INFO - 'run-tests'), 14:22:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:22:41 INFO - 'download_minidump_stackwalk': True, 14:22:41 INFO - 'download_symbols': 'ondemand', 14:22:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:22:41 INFO - 'tooltool.py': '/tools/tooltool.py', 14:22:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:22:41 INFO - '/tools/misc-python/virtualenv.py')}, 14:22:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:22:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:22:41 INFO - 'installer_path': 'installer.exe', 14:22:41 INFO - 'log_level': 'info', 14:22:41 INFO - 'log_name': 'talos', 14:22:41 INFO - 'log_to_console': True, 14:22:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:22:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:22:41 INFO - 'opt_config_files': (), 14:22:41 INFO - 'pip_index': False, 14:22:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:22:41 INFO - 'cmd': ('bash', 14:22:41 INFO - '-c', 14:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:22:41 INFO - 'enabled': True, 14:22:41 INFO - 'halt_on_failure': False, 14:22:41 INFO - 'name': 'check_screen_resolution'},), 14:22:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:22:41 INFO - 'cmd': ('bash', 14:22:41 INFO - '-c', 14:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:22:41 INFO - 'enabled': True, 14:22:41 INFO - 'halt_on_failure': False, 14:22:41 INFO - 'name': 'check_screen_resolution'},), 14:22:41 INFO - 'run_cmd_checks_enabled': True, 14:22:41 INFO - 'sps_profile': False, 14:22:41 INFO - 'sps_profile_interval': 0, 14:22:41 INFO - 'suite': 'dromaeojs', 14:22:41 INFO - 'system_bits': '32', 14:22:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:22:41 INFO - 'title': 't-yosemite-r7-0229', 14:22:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:22:41 INFO - 'use_talos_json': True, 14:22:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:22:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:22:41 INFO - 'work_dir': 'build'} 14:22:41 INFO - [mozharness: 2016-10-31 21:22:41.854774Z] Running clobber step. 14:22:41 INFO - Running pre-action listener: _resource_record_pre_action 14:22:41 INFO - Running main action method: clobber 14:22:41 INFO - rmtree: /builds/slave/test/build 14:22:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:22:43 INFO - Running post-action listener: _resource_record_post_action 14:22:43 INFO - [mozharness: 2016-10-31 21:22:43.482969Z] Finished clobber step (success) 14:22:43 INFO - [mozharness: 2016-10-31 21:22:43.483195Z] Running read-buildbot-config step. 14:22:43 INFO - Running pre-action listener: _resource_record_pre_action 14:22:43 INFO - Running main action method: read_buildbot_config 14:22:43 INFO - Using buildbot properties: 14:22:43 INFO - { 14:22:43 INFO - "project": "", 14:22:43 INFO - "product": "firefox", 14:22:43 INFO - "script_repo_revision": "production", 14:22:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:22:43 INFO - "repository": "", 14:22:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:22:43 INFO - "buildid": "20161031115704", 14:22:43 INFO - "pgo_build": "False", 14:22:43 INFO - "basedir": "/builds/slave/test", 14:22:43 INFO - "buildnumber": 69, 14:22:43 INFO - "slavename": "t-yosemite-r7-0229", 14:22:43 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:22:43 INFO - "platform": "yosemite_r7", 14:22:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:22:43 INFO - "branch": "mozilla-central", 14:22:43 INFO - "repo_path": "mozilla-central", 14:22:43 INFO - "revision": "37ab1d54a08e7e1431660b22377428b74dcd090a", 14:22:43 INFO - "stage_platform": "macosx64", 14:22:43 INFO - "builduid": "367e98a0ef1e47349a805daccf50e7a2", 14:22:43 INFO - "slavebuilddir": "test" 14:22:43 INFO - } 14:22:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:22:43 INFO - Running post-action listener: _resource_record_post_action 14:22:43 INFO - [mozharness: 2016-10-31 21:22:43.488889Z] Finished read-buildbot-config step (success) 14:22:43 INFO - [mozharness: 2016-10-31 21:22:43.489080Z] Running download-and-extract step. 14:22:43 INFO - Running pre-action listener: _resource_record_pre_action 14:22:43 INFO - Running main action method: download_and_extract 14:22:43 INFO - mkdir: /builds/slave/test/build/tests 14:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:43 INFO - trying https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:22:43 INFO - Downloading https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:22:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 14:22:44 INFO - Downloaded 1199 bytes. 14:22:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:22:44 INFO - Using the following test package requirements: 14:22:44 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:22:44 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:22:44 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:22:44 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'jsshell-mac.zip'], 14:22:44 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:22:44 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:22:44 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:22:44 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:22:44 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:22:44 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:44 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:22:44 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:22:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:22:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:22:44 INFO - Fetch https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:22:45 INFO - Http code: 200 14:22:45 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 14:22:45 INFO - x-amz-cf-id: FMR6gDt9f3LoJAgA-y9u-MRdvCO5jk-QIVyKG-aRvnytLQxnrKkntA== 14:22:45 INFO - x-amz-version-id: 6Dyb.7DWnWe58sJTGX6GphcEy0mEDMWf 14:22:45 INFO - Expected file size: 16404185 14:22:45 INFO - Obtained file size: 16404185 14:22:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:22:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:22:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:22:52 INFO - Fetch https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:22:53 INFO - Http code: 200 14:22:53 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 14:22:53 INFO - x-amz-cf-id: DToUTjne_1tUKuU5Aw_9uKn8w-B9vN5Dg04Kg0JLp62YO5DcMgGeZA== 14:22:53 INFO - x-amz-version-id: 6Dyb.7DWnWe58sJTGX6GphcEy0mEDMWf 14:22:53 INFO - Expected file size: 16404185 14:22:53 INFO - Obtained file size: 16404185 14:22:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:22:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:22:59 INFO - Fetch https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:23:00 INFO - Http code: 200 14:23:00 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 14:23:00 INFO - x-amz-cf-id: P4-ceaFTgC7b6CZKzjPUC8kxI2xU88Agfjs9FPQPRR_FnjwKDy9PQg== 14:23:00 INFO - x-amz-version-id: 7NIkiugcKNyoiwUPJLByQNxbQYnvg9MM 14:23:01 INFO - Expected file size: 14409038 14:23:01 INFO - Obtained file size: 14409038 14:23:01 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')]} 14:23:01 INFO - trying https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:23:01 INFO - Downloading https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:23:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 14:23:04 INFO - Downloaded 112071794 bytes. 14:23:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:23:04 INFO - mkdir: /builds/slave/test/properties 14:23:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:23:04 INFO - Writing to file /builds/slave/test/properties/build_url 14:23:04 INFO - Contents: 14:23:04 INFO - build_url:https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:23:05 INFO - Running post-action listener: _resource_record_post_action 14:23:05 INFO - Running post-action listener: set_extra_try_arguments 14:23:05 INFO - [mozharness: 2016-10-31 21:23:05.061616Z] Finished download-and-extract step (success) 14:23:05 INFO - [mozharness: 2016-10-31 21:23:05.061743Z] Running populate-webroot step. 14:23:05 INFO - Running pre-action listener: _resource_record_pre_action 14:23:05 INFO - Running main action method: populate_webroot 14:23:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:23:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:23:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:23:05 INFO - '--tppagecycles', 14:23:05 INFO - '7'], 14:23:05 INFO - 'tests': ['tsvgm']}}, 14:23:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:23:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:23:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:23:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:23:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['damp', 'tps']}, 14:23:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'tests': ['damp', 'tps']}, 14:23:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['dromaeo_dom']}, 14:23:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:23:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['basic_compositor_video']}, 14:23:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:23:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['a11yr', 14:23:05 INFO - 'ts_paint', 14:23:05 INFO - 'tpaint', 14:23:05 INFO - 'sessionrestore', 14:23:05 INFO - 'sessionrestore_no_auto_restore', 14:23:05 INFO - 'tabpaint']}, 14:23:05 INFO - 'other-e10s': {'tests': ['a11yr', 14:23:05 INFO - 'ts_paint', 14:23:05 INFO - 'tpaint', 14:23:05 INFO - 'sessionrestore', 14:23:05 INFO - 'sessionrestore_no_auto_restore', 14:23:05 INFO - 'tabpaint']}, 14:23:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:23:05 INFO - 'ts_paint', 14:23:05 INFO - 'tpaint', 14:23:05 INFO - 'sessionrestore', 14:23:05 INFO - 'sessionrestore_no_auto_restore', 14:23:05 INFO - 'tabpaint']}, 14:23:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:23:05 INFO - 'ts_paint', 14:23:05 INFO - 'tpaint', 14:23:05 INFO - 'sessionrestore', 14:23:05 INFO - 'sessionrestore_no_auto_restore', 14:23:05 INFO - 'tabpaint']}, 14:23:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['a11yr', 14:23:05 INFO - 'ts_paint', 14:23:05 INFO - 'tpaint', 14:23:05 INFO - 'sessionrestore', 14:23:05 INFO - 'sessionrestore_no_auto_restore', 14:23:05 INFO - 'tabpaint']}, 14:23:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['a11yr', 14:23:05 INFO - 'ts_paint', 14:23:05 INFO - 'tpaint', 14:23:05 INFO - 'sessionrestore', 14:23:05 INFO - 'sessionrestore_no_auto_restore', 14:23:05 INFO - 'tabpaint']}, 14:23:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['tsvgx', 14:23:05 INFO - 'tsvgr_opacity', 14:23:05 INFO - 'tart', 14:23:05 INFO - 'tscrollx', 14:23:05 INFO - 'cart']}, 14:23:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:23:05 INFO - 'tsvgr_opacity', 14:23:05 INFO - 'tart', 14:23:05 INFO - 'tscrollx', 14:23:05 INFO - 'cart']}, 14:23:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'talos_options': ['--disable-e10s'], 14:23:05 INFO - 'tests': ['tp5o']}, 14:23:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'tests': ['tp5o']}, 14:23:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'talos_options': ['--disable-e10s', 14:23:05 INFO - '--xperf_path', 14:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:05 INFO - 'tests': ['tp5n']}, 14:23:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:05 INFO - 'talos_options': ['--xperf_path', 14:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:05 INFO - 'tests': ['tp5n']}}, 14:23:05 INFO - 'talos.zip': {'path': '', 14:23:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:23:05 INFO - Running post-action listener: _resource_record_post_action 14:23:05 INFO - [mozharness: 2016-10-31 21:23:05.080584Z] Finished populate-webroot step (success) 14:23:05 INFO - [mozharness: 2016-10-31 21:23:05.080697Z] Running create-virtualenv step. 14:23:05 INFO - Running pre-action listener: _resource_record_pre_action 14:23:05 INFO - Running main action method: create_virtualenv 14:23:05 INFO - Creating virtualenv /builds/slave/test/build/venv 14:23:05 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 14:23:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:23:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:23:05 INFO - Using real prefix '/tools/python27' 14:23:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:23:05 INFO - Installing distribute.............................................................................................................................................................................................done. 14:23:08 INFO - Installing pip.................done. 14:23:08 INFO - Return code: 0 14:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:23:09 INFO - Reading from file tmpfile_stdout 14:23:09 INFO - Output received: 14:23:09 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) 14:23:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:09 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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:09 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 14:23:09 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 14:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:09 INFO - 'HOME': '/Users/cltbld', 14:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:09 INFO - 'LOGNAME': 'cltbld', 14:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:09 INFO - 'MOZ_NO_REMOTE': '1', 14:23:09 INFO - 'NO_EM_RESTART': '1', 14:23:09 INFO - 'PAGER': '/bin/cat', 14:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:09 INFO - 'PWD': '/builds/slave/test', 14:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:09 INFO - 'SHELL': '/bin/bash', 14:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:09 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:09 INFO - 'USER': 'cltbld', 14:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:09 INFO - 'XPC_FLAGS': '0x0', 14:23:09 INFO - 'XPC_SERVICE_NAME': '0', 14:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:09 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 14:23:09 INFO - Cleaning up... 14:23:09 INFO - Return code: 0 14:23:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:09 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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:09 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 14:23:09 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 14:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:09 INFO - 'HOME': '/Users/cltbld', 14:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:09 INFO - 'LOGNAME': 'cltbld', 14:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:09 INFO - 'MOZ_NO_REMOTE': '1', 14:23:09 INFO - 'NO_EM_RESTART': '1', 14:23:09 INFO - 'PAGER': '/bin/cat', 14:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:09 INFO - 'PWD': '/builds/slave/test', 14:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:09 INFO - 'SHELL': '/bin/bash', 14:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:09 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:09 INFO - 'USER': 'cltbld', 14:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:09 INFO - 'XPC_FLAGS': '0x0', 14:23:09 INFO - 'XPC_SERVICE_NAME': '0', 14:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:09 INFO - Downloading/unpacking psutil>=3.1.1 14:23:09 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 14:23:09 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 14:23:09 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 14:23:09 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 14:23:09 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 14:23:09 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 14:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:23:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:12 INFO - Installing collected packages: psutil 14:23:12 INFO - Running setup.py install for psutil 14:23:13 INFO - building 'psutil._psutil_osx' extension 14:23:13 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 14:23:13 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 14:23:13 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 14:23:13 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 14:23:13 INFO - building 'psutil._psutil_posix' extension 14:23:13 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 14:23:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:23:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:23:13 INFO - ^ 14:23:13 INFO - 1 warning generated. 14:23:13 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 14:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:13 INFO - Successfully installed psutil 14:23:13 INFO - Cleaning up... 14:23:13 INFO - Return code: 0 14:23:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:13 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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:13 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 14:23:13 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 14:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:13 INFO - 'HOME': '/Users/cltbld', 14:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:13 INFO - 'LOGNAME': 'cltbld', 14:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:13 INFO - 'MOZ_NO_REMOTE': '1', 14:23:13 INFO - 'NO_EM_RESTART': '1', 14:23:13 INFO - 'PAGER': '/bin/cat', 14:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:13 INFO - 'PWD': '/builds/slave/test', 14:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:13 INFO - 'SHELL': '/bin/bash', 14:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:13 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:13 INFO - 'USER': 'cltbld', 14:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:13 INFO - 'XPC_FLAGS': '0x0', 14:23:13 INFO - 'XPC_SERVICE_NAME': '0', 14:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:23: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 14:23: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 14:23: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 14:23: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 14:23: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 14:23: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 14:23:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:23:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:23:16 INFO - Installing collected packages: mozsystemmonitor 14:23:16 INFO - Running setup.py install for mozsystemmonitor 14:23:16 INFO - Successfully installed mozsystemmonitor 14:23:16 INFO - Cleaning up... 14:23:17 INFO - Return code: 0 14:23:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:17 INFO - 'HOME': '/Users/cltbld', 14:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:17 INFO - 'LOGNAME': 'cltbld', 14:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:17 INFO - 'MOZ_NO_REMOTE': '1', 14:23:17 INFO - 'NO_EM_RESTART': '1', 14:23:17 INFO - 'PAGER': '/bin/cat', 14:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:17 INFO - 'PWD': '/builds/slave/test', 14:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:17 INFO - 'SHELL': '/bin/bash', 14:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:17 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:17 INFO - 'USER': 'cltbld', 14:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:17 INFO - 'XPC_FLAGS': '0x0', 14:23:17 INFO - 'XPC_SERVICE_NAME': '0', 14:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:17 INFO - Downloading/unpacking jsonschema==2.5.1 14:23:17 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 14:23:17 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 14:23:17 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 14:23:17 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 14:23:17 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 14:23:17 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 14:23:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:23:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:23: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 14:23: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 14:23: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 14:23: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 14:23: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 14:23: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 14:23:20 INFO - Downloading functools32-3.2.3-2.tar.gz 14:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:23:20 INFO - warning: no files found matching '*.txt' 14:23:20 INFO - no previously-included directories found matching 'build' 14:23:20 INFO - no previously-included directories found matching 'dist' 14:23:20 INFO - no previously-included directories found matching '.git*' 14:23:20 INFO - Installing collected packages: jsonschema, functools32 14:23:20 INFO - Running setup.py install for functools32 14:23:20 INFO - warning: no files found matching '*.txt' 14:23:20 INFO - no previously-included directories found matching 'build' 14:23:20 INFO - no previously-included directories found matching 'dist' 14:23:20 INFO - no previously-included directories found matching '.git*' 14:23:20 INFO - Successfully installed jsonschema functools32 14:23:20 INFO - Cleaning up... 14:23:20 INFO - Return code: 0 14:23:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:20 INFO - 'HOME': '/Users/cltbld', 14:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:20 INFO - 'LOGNAME': 'cltbld', 14:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:20 INFO - 'MOZ_NO_REMOTE': '1', 14:23:20 INFO - 'NO_EM_RESTART': '1', 14:23:20 INFO - 'PAGER': '/bin/cat', 14:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:20 INFO - 'PWD': '/builds/slave/test', 14:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:20 INFO - 'SHELL': '/bin/bash', 14:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:20 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:20 INFO - 'USER': 'cltbld', 14:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:20 INFO - 'XPC_FLAGS': '0x0', 14:23:20 INFO - 'XPC_SERVICE_NAME': '0', 14:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:23:20 INFO - Cleaning up... 14:23:20 INFO - Return code: 0 14:23:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:20 INFO - 'HOME': '/Users/cltbld', 14:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:20 INFO - 'LOGNAME': 'cltbld', 14:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:20 INFO - 'MOZ_NO_REMOTE': '1', 14:23:20 INFO - 'NO_EM_RESTART': '1', 14:23:20 INFO - 'PAGER': '/bin/cat', 14:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:20 INFO - 'PWD': '/builds/slave/test', 14:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:20 INFO - 'SHELL': '/bin/bash', 14:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:20 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:20 INFO - 'USER': 'cltbld', 14:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:20 INFO - 'XPC_FLAGS': '0x0', 14:23:20 INFO - 'XPC_SERVICE_NAME': '0', 14:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:21 INFO - Downloading/unpacking blobuploader==1.2.4 14:23:21 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 14:23:21 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 14:23:21 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 14:23:21 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 14:23:21 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 14:23:21 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 14:23:23 INFO - Downloading blobuploader-1.2.4.tar.gz 14:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:23:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:23:23 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 14:23:23 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 14:23:23 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 14:23:23 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 14:23:23 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 14:23:23 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 14:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:23:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:23:24 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 14:23:24 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 14:23:24 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 14:23:24 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 14:23:24 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 14:23:24 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 14:23:24 INFO - Downloading docopt-0.6.1.tar.gz 14:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:23:24 INFO - Installing collected packages: blobuploader, requests, docopt 14:23:24 INFO - Running setup.py install for blobuploader 14:23:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:23:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:23:25 INFO - Running setup.py install for requests 14:23:25 INFO - Running setup.py install for docopt 14:23:25 INFO - Successfully installed blobuploader requests docopt 14:23:25 INFO - Cleaning up... 14:23:25 INFO - Return code: 0 14:23:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:25 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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:25 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 14:23:25 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 14:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:25 INFO - 'HOME': '/Users/cltbld', 14:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:25 INFO - 'LOGNAME': 'cltbld', 14:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:25 INFO - 'MOZ_NO_REMOTE': '1', 14:23:25 INFO - 'NO_EM_RESTART': '1', 14:23:25 INFO - 'PAGER': '/bin/cat', 14:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:25 INFO - 'PWD': '/builds/slave/test', 14:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:25 INFO - 'SHELL': '/bin/bash', 14:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:25 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:25 INFO - 'USER': 'cltbld', 14:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:25 INFO - 'XPC_FLAGS': '0x0', 14:23:25 INFO - 'XPC_SERVICE_NAME': '0', 14:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Zq6x0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-cfjEQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-pMIUHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-0Ibs5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-zm15wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-l4L1m9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-fZuDRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qo7OFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:26 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-GLRCyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-HSQJdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-vK_xl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-bzjFxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-jaz3DU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-L7CEgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-bzhpty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-9IHtgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:27 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-HNUrkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:23:27 INFO - Running setup.py install for manifestparser 14:23:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:23:28 INFO - Running setup.py install for mozcrash 14:23:28 INFO - Running setup.py install for mozdebug 14:23:28 INFO - Running setup.py install for mozdevice 14:23:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:23:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:23:28 INFO - Running setup.py install for mozfile 14:23:28 INFO - Running setup.py install for mozhttpd 14:23:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:23:28 INFO - Running setup.py install for mozinfo 14:23:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:23:28 INFO - Running setup.py install for mozInstall 14:23:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Running setup.py install for mozleak 14:23:29 INFO - Running setup.py install for mozlog 14:23:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Running setup.py install for moznetwork 14:23:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Running setup.py install for mozprocess 14:23:29 INFO - Running setup.py install for mozprofile 14:23:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:23:29 INFO - Running setup.py install for mozrunner 14:23:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:23:30 INFO - Running setup.py install for mozscreenshot 14:23:30 INFO - Running setup.py install for moztest 14:23:30 INFO - Running setup.py install for mozversion 14:23:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:23:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:23:30 INFO - Cleaning up... 14:23:30 INFO - Return code: 0 14:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:31 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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:31 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 14:23:31 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 14:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:31 INFO - 'HOME': '/Users/cltbld', 14:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:31 INFO - 'LOGNAME': 'cltbld', 14:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:31 INFO - 'MOZ_NO_REMOTE': '1', 14:23:31 INFO - 'NO_EM_RESTART': '1', 14:23:31 INFO - 'PAGER': '/bin/cat', 14:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:31 INFO - 'PWD': '/builds/slave/test', 14:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:31 INFO - 'SHELL': '/bin/bash', 14:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:31 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:31 INFO - 'USER': 'cltbld', 14:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:31 INFO - 'XPC_FLAGS': '0x0', 14:23:31 INFO - 'XPC_SERVICE_NAME': '0', 14:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-sD4GpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-2ePF9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-IIbRFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-oBxnL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-PlwGPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-nfzgCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-8CrRwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-MVI7dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:32 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-AOFPj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:32 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)) 14:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-hFAyp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:33 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)) 14:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Y0m8Gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:33 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)) 14:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-mn5Nc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:33 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)) 14:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-nAKd9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:33 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)) 14:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-PMKZtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 14:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-6mwhzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:33 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)) 14:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-jE6gne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:33 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)) 14:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:33 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-yEErxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:33 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)) 14:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:33 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)) 14:23:33 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)) 14:23:33 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)) 14:23:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:23:33 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 14:23:33 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 14:23:33 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 14:23:33 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 14:23:33 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 14:23:33 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 14:23:36 INFO - Downloading blessings-1.6.tar.gz 14:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:23:36 INFO - Installing collected packages: blessings 14:23:36 INFO - Running setup.py install for blessings 14:23:37 INFO - Successfully installed blessings 14:23:37 INFO - Cleaning up... 14:23:37 INFO - Return code: 0 14:23:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:37 INFO - Reading from file tmpfile_stdout 14:23:37 INFO - Current package versions: 14:23:37 INFO - blessings == 1.6 14:23:37 INFO - blobuploader == 1.2.4 14:23:37 INFO - docopt == 0.6.1 14:23:37 INFO - functools32 == 3.2.3-2 14:23:37 INFO - jsonschema == 2.5.1 14:23:37 INFO - manifestparser == 1.1 14:23:37 INFO - mozInstall == 1.12 14:23:37 INFO - mozcrash == 0.17 14:23:37 INFO - mozdebug == 0.1 14:23:37 INFO - mozdevice == 0.48 14:23:37 INFO - mozfile == 1.2 14:23:37 INFO - mozhttpd == 0.7 14:23:37 INFO - mozinfo == 0.9 14:23:37 INFO - mozleak == 0.1 14:23:37 INFO - mozlog == 3.4 14:23:37 INFO - moznetwork == 0.27 14:23:37 INFO - mozprocess == 0.23 14:23:37 INFO - mozprofile == 0.28 14:23:37 INFO - mozrunner == 6.12 14:23:37 INFO - mozscreenshot == 0.1 14:23:37 INFO - mozsystemmonitor == 0.3 14:23:37 INFO - moztest == 0.7 14:23:37 INFO - mozversion == 1.4 14:23:37 INFO - psutil == 3.1.1 14:23:37 INFO - requests == 1.2.3 14:23:37 INFO - wsgiref == 0.1.2 14:23:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:37 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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:37 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 14:23:37 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 14:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:37 INFO - 'HOME': '/Users/cltbld', 14:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:37 INFO - 'LOGNAME': 'cltbld', 14:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:37 INFO - 'MOZ_NO_REMOTE': '1', 14:23:37 INFO - 'NO_EM_RESTART': '1', 14:23:37 INFO - 'PAGER': '/bin/cat', 14:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:37 INFO - 'PWD': '/builds/slave/test', 14:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:37 INFO - 'SHELL': '/bin/bash', 14:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:37 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:37 INFO - 'USER': 'cltbld', 14:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:37 INFO - 'XPC_FLAGS': '0x0', 14:23:37 INFO - 'XPC_SERVICE_NAME': '0', 14:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 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)) 14:23:37 INFO - Cleaning up... 14:23:37 INFO - Return code: 0 14:23:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:37 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 0x106541490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064b7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd73347e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, '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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:37 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 14:23:37 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 14:23:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:23:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:37 INFO - 'HOME': '/Users/cltbld', 14:23:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:37 INFO - 'LOGNAME': 'cltbld', 14:23:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:37 INFO - 'MOZ_NO_REMOTE': '1', 14:23:37 INFO - 'NO_EM_RESTART': '1', 14:23:37 INFO - 'PAGER': '/bin/cat', 14:23:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:37 INFO - 'PWD': '/builds/slave/test', 14:23:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:37 INFO - 'SHELL': '/bin/bash', 14:23:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:23:37 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:23:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:37 INFO - 'USER': 'cltbld', 14:23:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:37 INFO - 'XPC_FLAGS': '0x0', 14:23:37 INFO - 'XPC_SERVICE_NAME': '0', 14:23:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:23:37 INFO - Cleaning up... 14:23:37 INFO - Return code: 0 14:23:37 INFO - Running post-action listener: _resource_record_post_action 14:23:37 INFO - Running post-action listener: _start_resource_monitoring 14:23:37 INFO - Starting resource monitoring. 14:23:37 INFO - [mozharness: 2016-10-31 21:23:37.991326Z] Finished create-virtualenv step (success) 14:23:37 INFO - [mozharness: 2016-10-31 21:23:37.992363Z] Running install step. 14:23:37 INFO - Running pre-action listener: _resource_record_pre_action 14:23:37 INFO - Running main action method: install 14:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:38 INFO - Reading from file tmpfile_stdout 14:23:38 INFO - Detecting whether we're running mozinstall >=1.0... 14:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:23:38 INFO - Reading from file tmpfile_stdout 14:23:38 INFO - Output received: 14:23:38 INFO - Usage: mozinstall [options] installer 14:23:38 INFO - Options: 14:23:38 INFO - -h, --help show this help message and exit 14:23:38 INFO - -d DEST, --destination=DEST 14:23:38 INFO - Directory to install application into. [default: 14:23:38 INFO - "/builds/slave/test"] 14:23:38 INFO - --app=APP Application being installed. [default: firefox] 14:23:38 INFO - mkdir: /builds/slave/test/build/application 14:23:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:23:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:24:12 INFO - Reading from file tmpfile_stdout 14:24:12 INFO - Output received: 14:24:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:24:12 INFO - Running post-action listener: _resource_record_post_action 14:24:12 INFO - [mozharness: 2016-10-31 21:24:12.539443Z] Finished install step (success) 14:24:12 INFO - [mozharness: 2016-10-31 21:24:12.539603Z] Running run-tests step. 14:24:12 INFO - Running pre-action listener: _resource_record_pre_action 14:24:12 INFO - Running main action method: run_tests 14:24:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:24:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:24:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:24:12 INFO - 2016-10-31 14:24:12.554 screenresolution[2774:31705] starting screenresolution argv=screenresolution get 14:24:12 INFO - 2016-10-31 14:24:12.557 screenresolution[2774:31705] Display 0: 1600x1200x32@60 14:24:12 INFO - 2016-10-31 14:24:12.563 screenresolution[2775:31710] starting screenresolution argv=screenresolution list 14:24:12 INFO - Available Modes on Display 0 14:24:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:24:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:24:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:24:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:24:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:24:12 INFO - Intel Iris: 14:24:12 INFO - Chipset Model: Intel Iris 14:24:12 INFO - Type: GPU 14:24:12 INFO - Bus: Built-In 14:24:12 INFO - VRAM (Dynamic, Max): 1536 MB 14:24:12 INFO - Vendor: Intel (0x8086) 14:24:12 INFO - Device ID: 0x0a2e 14:24:12 INFO - Revision ID: 0x0009 14:24:12 INFO - Displays: 14:24:12 INFO - Display: 14:24:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:24:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:24:12 INFO - Display Serial Number: 1600x1200 60 14:24:12 INFO - Main Display: Yes 14:24:12 INFO - Mirror: Off 14:24:12 INFO - Online: Yes 14:24:12 INFO - Rotation: Supported 14:24:12 INFO - Return code: 0 14:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:24:12 INFO - Python 2.7.3 14:24:12 INFO - Return code: 0 14:24:12 INFO - grabbing minidump binary from tooltool 14:24:12 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')]} 14:24:12 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 0x106355d30>, '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 0x10617bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10634db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:24:12 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 14:24:12 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 14:24:12 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 14:24:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:24:12 INFO - Return code: 0 14:24:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:24:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:24:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:24:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:24:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0229 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:24:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ghXYjMmodO/Render', 14:24:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:24:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:24:12 INFO - 'HOME': '/Users/cltbld', 14:24:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:24:12 INFO - 'LOGNAME': 'cltbld', 14:24:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:24:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:12 INFO - 'MOZ_NO_REMOTE': '1', 14:24:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:24:12 INFO - 'NO_EM_RESTART': '1', 14:24:12 INFO - 'PAGER': '/bin/cat', 14:24:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:24:12 INFO - 'PWD': '/builds/slave/test', 14:24:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:24:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:24:12 INFO - 'SHELL': '/bin/bash', 14:24:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KVtXrhcyzZ/Listeners', 14:24:12 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 14:24:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:24:12 INFO - 'USER': 'cltbld', 14:24:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:24:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:24:12 INFO - 'XPC_FLAGS': '0x0', 14:24:12 INFO - 'XPC_SERVICE_NAME': '0', 14:24:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:24:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:24:13 INFO - mozversion application_buildid: 20161031115704 14:24:13 INFO - mozversion application_changeset: 37ab1d54a08e7e1431660b22377428b74dcd090a 14:24:13 INFO - mozversion application_display_name: Nightly 14:24:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:24:13 INFO - mozversion application_name: Firefox 14:24:13 INFO - mozversion application_remotingname: firefox 14:24:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:24:13 INFO - mozversion application_vendor: Mozilla 14:24:13 INFO - mozversion application_version: 52.0a1 14:24:13 INFO - mozversion platform_buildid: 20161031115704 14:24:13 INFO - mozversion platform_changeset: 37ab1d54a08e7e1431660b22377428b74dcd090a 14:24:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:24:13 INFO - mozversion platform_version: 52.0a1 14:24:13 INFO - using testdate: 1477949053 14:24:13 INFO - actual date: 1477949053 14:24:13 INFO - starting webserver on 'localhost:57323' 14:24:13 INFO - SUITE-START | Running 2 tests 14:24:13 INFO - TEST-START | dromaeo_css 14:24:13 INFO - operating with platform_type : mac_ 14:24:13 INFO - Initialising browser for dromaeo_css test... 14:24:13 INFO - TEST-INFO | started process 2785 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpC1Jcvv/profile http://localhost:57323/getInfo.html) 14:24:15 INFO - PROCESS | 2785 | __metrics Screen width/height:1600/1200 14:24:15 INFO - PROCESS | 2785 | colorDepth:24 14:24:15 INFO - PROCESS | 2785 | Browser inner width/height: 1024/689 14:24:15 INFO - PROCESS | 2785 | __metrics 14:24:15 INFO - PROCESS | 2785 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:24:15 INFO - PROCESS | 2785 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:24:16 INFO - TEST-INFO | 2785: exit 0 14:24:16 INFO - Browser initialized. 14:24:16 INFO - Running cycle 1/1 for dromaeo_css test... 14:24:16 INFO - TEST-INFO | started process 2787 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpC1Jcvv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:28 INFO - PROCESS | 2787 | RSS: Main: 241537024 14:24:28 INFO - PROCESS | 2787 | 14:25:20 INFO - PROCESS | 2787 | 1477949120020 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] 14:27:31 INFO - PROCESS | 2787 | Cycle 1(1): loaded http://localhost:57323/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57323/tests/dromaeo/cssquery-ext.html) 14:27:34 INFO - PROCESS | 2787 | RSS: Main: 491331584 14:27:34 INFO - PROCESS | 2787 | 14:30:37 INFO - PROCESS | 2787 | Cycle 1(1): loaded http://localhost:57323/tests/dromaeo/cssquery-ext.html (next: http://localhost:57323/tests/dromaeo/cssquery-jquery.html) 14:30:38 INFO - PROCESS | 2787 | RSS: Main: 398520320 14:30:38 INFO - PROCESS | 2787 | 14:33:40 INFO - PROCESS | 2787 | Cycle 1(1): loaded http://localhost:57323/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57323/tests/dromaeo/cssquery-mootools.html) 14:33:41 INFO - PROCESS | 2787 | RSS: Main: 353480704 14:33:41 INFO - PROCESS | 2787 | 14:36:43 INFO - PROCESS | 2787 | Cycle 1(1): loaded http://localhost:57323/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57323/tests/dromaeo/cssquery-prototype.html) 14:36:44 INFO - PROCESS | 2787 | RSS: Main: 341532672 14:36:44 INFO - PROCESS | 2787 | 14:39:47 INFO - PROCESS | 2787 | Cycle 1(1): loaded http://localhost:57323/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57323/tests/dromaeo/cssquery-yui.html) 14:39:48 INFO - PROCESS | 2787 | RSS: Main: 334151680 14:39:48 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | Cycle 1(1): loaded http://localhost:57323/tests/dromaeo/cssquery-yui.html (next: http://localhost:57323/tests/dromaeo/cssquery-dojo.html) 14:42:51 INFO - PROCESS | 2787 | RSS: Main: 379162624 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | __start_tp_report 14:42:51 INFO - PROCESS | 2787 | _x_x_mozilla_page_load 14:42:51 INFO - PROCESS | 2787 | _x_x_mozilla_page_load_details 14:42:51 INFO - PROCESS | 2787 | |i|pagename|runs| 14:42:51 INFO - PROCESS | 2787 | |0;dojo.html;2508.260063281332;3264.0207937618725;3287.490438981957;3300.092474569493;3301.950470742941;5483.506484416402;5549.556035517159;5550.195221692858;5579.246801681772;5623.3252009758835;5553.527950124234;5578.776848926038;5650.51970582501;5666.235058267132;5703.144528320754;5099.4900509949075;5177.896442071165;5190.169572868342;5208.958208358325;5214.791408343662;5089.694618322913;5156.510131537498;5193.688378697271;5221.817236396727;5228.843134705965;5183.17069268917;5221.843344699665;5244.449332820056;5246.501582349671;5258.973705131469;5027.798888044474;5136.178211486144;5138.97430512845;5168.302279192299;5189.195674670432;5005.0990821652085;5026.924596131061;5083.822066227664;5084.771185296633;5091.719955402451;5505.311836020497;5573.498385145316;5574.9721251393885;5592.86017849557;5619.241402410663;6076.513878889678;6102.145699602059;6114.205153330038;6148.78479253224;6150.384961503859;6142.109394137825;6181.443670069671;6213.5961162524745;6216.347283535209;6220.782272620437;5546.112621980464;5563.388027316992;5611.186377975211;5611.775528978795;5664.376918538957;6206.503479721611;6208.782692605828;6227;6232.968835155795;6256.655883926337;5833.50415214703;5863.853403664857;5865.237519122486;5888.4700376966275;5930.644161350333;5930.199423077829;6081.179040829431;6103.298120716086;6103.8474038148515;6109.053096770008;6090.786822461193;6095.468565591287;6102.768839629773;6110.194261626863;6120.464868152711;6114.753609057711;6130.86834845685;6131.325554189036;6145.66198859067;6146.631202127886;4766.189747742823;4786.2581299898575;4832.323474713543;4855.830045948445;4868.853934381974;3752.45589389537;4304.461942257218;4378.146261479044;4381.9342709859375;4398.516163222043;5639.125935480007;5643.858903527444;5653.858653533613;5662.716864156776;5691.658500489983;6056.970315046453;6178.320384757673;6194.101855230966;6203.100550420164;6221.626702397871;5538.141588053858;5542.473465020817;5570.610057296031;5613.7193140342815;5630.887382252332;5288.312519372457;5318.760655770499;5340.118880384693;5344.946550534522;5348.866278343073;6200.224971878515;6214.161088253119;6219.533534984985;6222.097795819672;6224.564280500322;6010.429009244115;6047;6098.311206366174;6102.877942441215;6160.722767475564;6237.625742455467;6237.7193026314835;6245.594036387621;6257.9687101564205;6263.404976527405;6023.367546407564;6027.367126451835;6030.698465076641;6036.973714468463;6040.848978775566;6046.818595442144;6121.816345509642;6123.173371594956;6126.693665316806;6130.938690613215;5911.674857882857;5922.585419020627;5923.763049478145;5923.940760592512;5930.169776231417;5896.3514013457625;5915.792947246826;5923.644581325134;5924.348321684525;5930.555208359304;5721.313442386941;5742;5744.195812586325;5748.022836117953;5768.336641286138;5983.162357269899;6001.639901605918;6006.759729610767;6010.879782404415;6012;5634.380218176079;5677.630953987978;5814.447627475383;5840.2991641003355;5843.2695913010875 14:42:51 INFO - PROCESS | 2787 | |1;ext.html;440.0956035347361;486.7079752148709;493.0459560749947;493.65690844862826;496.92546118082305;34846.82576587117;36385.81807090964;36407.271854562896;36457.81771091148;37711.245775084484;35400.82299588505;35557.46663800045;35940.46089308656;36152.638473615254;36206.45690314647;7986;8127.918720812805;8339.54132522711;9167.174954254117;9346.766330841716;8342.332613390914;8416.242538171564;8425.199606037417;8436.915630843705;10972.177086718488;9665.951670241675;11145.052670523015;11158.888411115866;11195.552177912874;11249.775004499905;11106.888931110707;11208.27146235492;11221.66335009951;11227.157963152755;11244.943775281072;11054.889451105466;11102.389368584805;11105.111591072695;11116;11117.833232501518;25287.36781580457;34941.825290873385;34973.30053398943;35111.4733279001;35137.64862351369;3072.0630207786617;3086.675899030592;3203.6475987641343;3225.306559089807;3315.900522984314;27495.188342040263;28109.297267568476;28222.012229571865;28302;28338.29154271139;2978.255436140965;3058.7705922055934;3083.8458077096057;3135.5453459248283;3196.216926852913;350153.24923375476;350510;350526;351930.24034879915;355623;29544.113676589735;30168.396632067237;30705.846470767505;48223;49357;30635.69364306373;31455.842720786248;31709.097454152823;31722.841385793385;31812.681873180973;12069.75860482803;12093;12329.951954083825;12546.309952952675;12570.371481425893;10292.382457052601;10452.11157051644;10463.476826158661;10478.580856765797;10503.684889453329;6268.843278917972;6661.966690166518;6843;6847.828804279933;7280.526765760209;3008.443437963984;3039.574459575661;3040.9543856842165;3047.9085627431214;3067.2025273429067;838.1409055717938;861.6036623153294;869.799946012408;945.2390825385493;1006.2352612014921;27079.864600676872;27462.116502633464;27495.587566186525;27528.724712752617;28204.85897570499;934.5374039850318;947.8199142162968;986.8322385194534;1000.6297669862198;1044.1072882685346;14202;14346.21095839718;14346.282685865664;14568.198749068692;15045.172515511751;658869;663028;663687;664861;670537;43811.342829857575;44969.325460118125;47649;48672.26991595129;49184;1205.482713973177;1219.9268043917393;1240.7270400511873;1248.5380409248455;1255.0649765924445;22142.667859982434;27294.590581141696;27826;28030.159095227176;28216.43567128605;41241.381379279934;42831.57168428276;42917.1416571673;42948.78525607352;42991;598374;668865;671130;796749;799132;37491.87524374273;38198.854034379016;38312.616873830906;38504.80747596244;38521;42544.57455425406;42963.570364295956;43230.783846080565;43263;43417.3487397683;35489.64510354863;35491;35646.465303019955;36563.817180913924;36751.816240918626;35228.295434091684;35778.463323050695;36162;36209.81895090508;36591.45112823256;34486;35495.82252088723;35574.28851423009;36011;36764.44853327256;28738.21105926768;31437.842810785798;31834.2491162979;31863.681363187;31872.6812731879;19353.612927741084;23857.761422385553;24273;24293.271201863972;24626.87686561556 14:42:51 INFO - PROCESS | 2787 | |2;jquery.html;339.1448775199657;356.5400633183192;359.47337151073674;367.99264014719705;392.07470369926966;14343;14703;16647.916760416196;18791.530211744714;19288.132034058464;17648.558786030357;18090.4572862814;18223;18407.539811504717;18577.792443491162;4945.950540494603;5518.2826232589705;5704.48659620633;5912.379200183983;6034.4568988791;5489.286392768934;5848.766049358021;5889.911651325234;5930.851728706773;5941.10883367494;5794.217780599607;5911.290645122592;5927.1998280232265;5932.2584676915385;5936.198613187229;5765.769369225226;5836.941630583682;5877.059670452707;5910.940890591103;5934.1692163097205;5709.914351284734;5719.771209151629;5725.799597014085;5730.283714535683;5744.396838331957;12222.633321000385;16298.185090745534;22027.118915243373;23438.296851094496;23555;2112.767595564487;2117.809397154248;2141.550274442369;2153.9787673574165;2248.5840119577765;8324.83452316676;8850.114988501162;9844.704658860246;11041.723956901062;12850.779175978361;1807.6023274879506;1832.2213059449814;1872.6161136967025;1926.0851095729556;1936.6514027475048;105632;113625;113695.43152284186;114681;114755.99999999917;10907.236493445542;10965.50655220517;11154.776904461865;11159.59196117529;11299.661010169708;11896.167268291136;12618.49526018949;12623.558175463815;12924.806127908088;13710.588682339545;7560.848783024308;7579;7702.614869256515;7828.804279893048;7834.294913457816;7305.1233851938105;7318.597477138808;7334.96332518334;7461.067366579177;7498.737544185928;3624.7100231981376;3787.5265591801026;4189;4292.205941900758;4448.666350023761;1604.1658337664583;1809.6109336492718;1828.5520047588425;1831.1393644986836;1840.153529376475;747.5253214209016;789.8933643958107;813.0690359538296;898.9661888827769;913.4291068082448;16294.333774900553;18569.071546422623;18967.17833520529;19795.10922008513;19890.70163947542;801.6669899194861;850.306999795165;862.977371814402;870.1907226279494;914.2731528434911;9186.26509879208;10186.694399168036;10287.22845786569;10307.639232626821;10326;502585;502984;506314;511126;511365;118423.40788296003;119684;121965;122602;125838;924.9260059195249;928.911753383441;972.1785091597588;973.7127547373542;979.5200351827554;13723.176609403468;16585.75121373156;17377.739333910253;17485.562860928578;17893.284268629108;49454.33457814044;51545.71135721487;51708.22437663513;51771.457709477516;51996.74001629968;72757.27242727717;74066.62966685132;76224.23775762171;76695.23304767103;77183.61408192923;7351.926480735338;7567.810804729926;7749.612519374122;7779.260970207822;8206.261436470746;10081.747956301151;10706;11119.888801111885;12203.267803931793;12336.383180841101;8461.280791132576;10617.150627949592;10782.514786834769;10788.838167427652;10841.403722795007;8583.699570515002;10661.840072399076;10737.08734757523;11114.722131946766;11157.553697851996;10757.354558726189;10880.945595271973;11254.212205145888;11291.040261577858;11310.886891130984;7843.254890785594;8043.316318112792;8043.557604331818;8071.273585377344;8236.958815205886;9955.601775928882;9962.800743984935;10510.1066409353;10629.362238265729;11207.551697931991 14:42:51 INFO - PROCESS | 2787 | |3;mootools.html;1152.066825871044;1308.8102225177347;1339.276790533112;1364.7679894417954;1602.0948164287183;7325.890111648329;7709.922900770991;7973.362131029518;8136.796580085486;8225.465344752587;8052.63763130659;8100.716474923379;8185;8193.262606365426;8193.50838949662;2255.4474153832334;2281.441046943493;2285.1202287119413;2292.255017119443;2292.5758734634064;2259.378670865509;2310;2319.7100362454694;2322.605157123293;2327.860328380294;2003.7695664998566;2176.846271476112;2253.0822584752705;2284.7486776454575;2286.4512516995883;2206.9779302206935;2236.7763223677666;2263.988680056589;2264.30938563738;2267.263139479676;1910.1308904448442;2242.248846636379;2256.9774302257097;2283.931482055524;2284.0292875527834;8140.145284745135;8206;8223.42436029478;8225.054118776297;8225.424220304581;4864.756762161877;4879.8780030499165;5054.317667114966;5064.417591976933;5080.695158290514;3257.7393808495262;3430.451127819537;3431.4681224410256;3499.265154317596;3508.8771892983877;981.7790997025684;1022.1873610479687;1040.9063184313372;1060.2366296266675;1060.496264274471;10730.517126729237;10786.676399708022;10901.34591924487;10954.671359859218;10962.616308429246;3361.46216605342;3588.8385022674042;4365.214261432909;4366.126774645084;4377.0808130293;7499.737509187153;8369.079401265855;8452.492850428995;8509.829803403898;8524.40329176964;5002.724850133205;5007.098722229961;5012.04771093491;5036.899262014813;5036.974815125901;3945.467361906163;4199.265128602482;4296.162248361539;4339.327404252346;4725.976370118127;3444.6383129771516;3467.7169447304154;3533.7278579711283;3550.9822450888096;3551.3785087609567;1082.24783775277;1090.4929207918356;1095.649392194506;1104.9779004419865;1111.0833562311125;660.9504287178481;706.4348521183033;833.2875391540267;853.7737499562621;854.5129276312442;5443.646162999393;5558.833235002957;5619.971900140555;5663.065594176997;5760.3951585083805;402.4406075554981;404.2400287459557;412.70904012670997;414.4943169333408;841.4236248169901;3620.4179241014913;3718.2563487302646;3867.613238676166;3935.5670876203594;3938.232044751246;7852.450328476952;9562.521873906277;9765.560549775277;10050.69847904564;10252.23108266883;9741.659041933499;9937.701868943943;10063.647772327933;10145.34055286404;10166.339187952759;7495.587742674096;7772.795216741414;7777.650005749755;7783.494072885358;7791.104023037423;4931.852044438672;5420.7560659769615;5539.833804985857;5557.638753481173;5570.359408668055;7921.445498815027;9204.447733136034;9221.907780922373;9316.860247096163;9407.905920940702;9367.906320936976;9399.624015039322;9485.952570237105;9543.33196676226;9564.856527151958;6607;6734.3602357775935;6743.66281685904;6748.190217173774;6748.527603067738;7655.349295309961;7686.615669216629;7707.997960265129;7746.186650401627;7758.224177582197;6922.06552115458;6985.5808651481075;7025.894611580932;7056.1179852518435;7112.0754301940415;6705.530612857053;6771;6895.689693963884;6961.095057642474;6977.092977912839;6500;6686.197656281277;6714.060031595678;6714.932850671431;6727.629980351127;4583.2666773316105;4769.809207631656;4791.233402655558;4877.804887804448;4960.280759289955;4039.8384064637085;4684.250519916797;4781.713097214179;4890.462049174517;5008.549230569266 14:42:51 INFO - PROCESS | 2787 | |4;prototype.html;302.15547544612804;305.5416874687968;313.4279939111122;319.286394907382;319.32622167227134;4282.743035417877;4744.501827308135;5249.8950020999555;5331.706756128411;5352.27744254526;5051.823186188484;5118.769655365508;5161.071007218699;5168.147255702814;5201.68789872607;3612.9161251624537;4005.3591425371947;4010.939835902464;4027.2952233359047;4046.8956142805796;3757.722374392706;3819.579846216914;3824.0248736572134;4011.318075927085;4063.817128229222;3141.952870706941;3899.922001559967;3967;3974.5429275633237;4011.097503061817;3330.9174518281657;3456.1186897341267;3840.578985775253;3944.7041471889725;4012.615647601602;2886.870090845896;3442.604100528421;3447.7414193935556;3450.913727156816;3513.5256740339983;4178.979105104455;4915.361003069578;5145.665531740462;5169.329775859144;5266.262723218752;3769.9057523561855;4174.368299579355;4323.525151468247;4521.796519156645;4545.499975013729;4769.090854578704;5367.75845086972;5512.889742205173;5518.834434966958;5534.252875861747;3653.3058718843336;3687.2441149564274;3785.728562862288;3793.9810300948593;3800.182960663443;5731.914021289684;5804.970975145097;5809.157672137517;5815.592908496407;5865.442782935615;3204.150900011499;3206.4228438881223;3646.9452958205648;3800.277947190025;3841.5582208045876;3619.4798184762462;3922.8234729437245;3930.783806890591;3953.5058117735284;3955.149642826806;3415.8480157501112;4110.527289361762;4396.27461468854;4422.49141348749;4604.58558729716;4606.723596584148;5011.173156429149;5387.838364849061;5547.417521160301;5561.582881283839;1115.297362661518;1232.4577186038293;1269.7143142792982;1276.5276847566274;1296.2999980010688;838.5513750143643;919.7332773495746;941.7992060123397;951.4006176109007;957.5786653872274;3660.816959152032;4042.777647229431;4193.056562273525;4313.008008158141;4328.458942632171;4094.8469678347506;4479.440069991251;4712.761438316772;4761.833335833298;4774.570288674036;3663.413853783435;5032.446430892599;5113;5187.584993200535;5199.766010529535;2499.987500062488;2922.70772922706;2951.8376489293296;2959.9556006659486;2970.985145074261;4969.1614102782205;5515.310586176728;5826.912596310974;5841.7371218295275;5879.353271140171;4909.735026207603;5485.917711234335;5565.803352279279;5709.371969083398;5778.6821724804695;5303.0378759859095;5392.973035134799;5748.396418376094;5850.6412722521145;5851.385604511465;4679.906401872012;5149.6910185389;5248.343957005374;5316.468353164652;5753.791703742261;4486.416765820422;4909.4845041270155;5659;5663.1788390682295;5672.7730890763905;4941.430456162847;5044.6379477541595;5178.689278643294;5504.954058728828;5945.019071853096;3537.557805274341;3650.4159334506353;3777.829997650167;3789.488419063391;3794.696424286106;5688.317401911797;5716.971415142898;5732.767454997195;5755.7122143893475;5772.94227057724;5551.722413879209;5556.583256255878;5592.804251851166;5638.351589567088;5665.518430933417;4625.472560290421;5087.87280317995;5196.194589838581;5567;5572.554195664418;4609.700369475973;4639;4652.208899573621;5170.457102004236;5481.890362192814;5065.873353166053;5363.651362661414;5532.972335138298;5570.972145139248;5579.6652200868075;4749.50130236334;4831.589314908131;5382.30030096085;5383.838484845461;5566 14:42:51 INFO - PROCESS | 2787 | |5;yui.html;516.811363852194;608.8995315772897;655.4461480049359;664.1565212180531;702.2170280137648;13756.862431375683;13957.232352220637;14378.06542574732;14626.195559244237;14906.627334316647;12120.696982575419;12330.81503777444;12426.130170888042;12434.316112613802;12453;654.8395643067455;677.9593224406551;679.578661230038;680.1905732178716;682.8736683713504;555.0536335547881;573.1689050876225;634.0869148426275;667.5093806052547;674.4233680203429;700.8247938015496;708.4084789201023;708.6102643546055;709.3899246647878;710.435204012811;693.6670398208863;701.8666453373868;702.5644100657574;702.7715992302523;705.6189657584898;425.8041301001543;428.51791734298916;434.32028874811135;497.4006322381522;502.52260352665115;12498.125131240817;12893.806592901114;12895.419706113247;12923.806142907864;12944;11745.706357341134;11917.821232681517;12002.63992080239;12068.336241506713;12305.815412768816;10930.617428390047;11245.550177992953;11531.654050378418;11716.941415292955;11756;3673.3755261605324;3735.2342769732413;3762.0782908187393;3804.9429258560867;3806.8286927088348;84068;88684;90386.54806725924;90512;90599;10246.53890574926;10359.223058270509;10454.42500662471;10466.722998160973;10718.785624287471;18877.244910203437;19015.714764278546;19025.238990440503;19043.61912761737;19254.71117933232;7274.418046556263;7307.19620841707;7312.597807120659;7489.812754681067;7665.386769058462;3979.3235150024566;4018.6383225509844;4109.465769449952;4120.1553681495825;4137.875863724033;3226.4998925166637;3309.1396236978558;3311.602607687045;3348.5312056312137;3349.732021438279;598.7724664627414;599.6941559804444;604.694629212245;609.7926704920349;613.2763339259627;554.8252300525402;605.636618029179;620.9534284928649;643.2666759893725;649.6394501051961;8558.57207139653;8733.126687331216;8904.065073167354;8925.553722313858;9033.81932361349;539.8191605812096;597.4175179200332;609.9451049405575;619.2383368456733;636.7739452494391;3976.681865450757;4358.128374325148;4420.756858372756;4472.798724057425;4484.1031793641405;75422;110593;110630;111236.44381778201;113053.86946130433;96466.51766741122;98039.01960980441;98843;99442.50278748704;100283.49858250663;66398.33601663921;85897.57051214829;86356.5682171585;87941;89765.10234897699;8284.917150828414;8494.532800695773;8713.477191368538;8902.198802107841;8961.731148065308;38772;44272.11455770931;50837;52257.47742522678;52762.68093297698;52760.73619631877;58866;70187.64906175436;72962;75024;11046.668599942015;11373.886261137282;11475.196736228383;11741.17811753169;12260.26438413698;13615.727685446038;13813.309334533435;13828.516001939885;13843.238621875893;14431.134131952116;12317.815232771694;12897;12901.6793755897;13484.865151348753;13540.526081587097;12506.74986500283;12634.115611907078;12945.223286602833;13061.673458163243;13170.539031133865;12191.878081219074;12385.876141238832;12524.311162886128;12614.873851261736;12617.369131543202;10421.583136674752;10850.077743391585;11045.668629941114;11102.500387482743;11221;10786.137109031415;10959.669533635635;11031.448427578429;11038.708713196895;11161.162912781412 14:42:51 INFO - PROCESS | 2787 | __end_tp_report 14:42:51 INFO - PROCESS | 2787 | __start_cc_report 14:42:51 INFO - PROCESS | 2787 | _x_x_mozilla_cycle_collect,3102 14:42:51 INFO - PROCESS | 2787 | __end_cc_report 14:42:51 INFO - PROCESS | 2787 | __startTimestamp1477950171749__endTimestamp 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | ------- Summary: start ------- 14:42:51 INFO - PROCESS | 2787 | Number of tests: 6 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | [#0] dojo.html Cycles:165 Average:5631.26 Median:5814.45 stddev:650.07 (11.2%) stddev-sans-first:604.14 14:42:51 INFO - PROCESS | 2787 | Values: 2508.3 3264.0 3287.5 3300.1 3302.0 5483.5 5549.6 5550.2 5579.2 5623.3 5553.5 5578.8 5650.5 5666.2 5703.1 5099.5 5177.9 5190.2 5209.0 5214.8 5089.7 5156.5 5193.7 5221.8 5228.8 5183.2 5221.8 5244.4 5246.5 5259.0 5027.8 5136.2 5139.0 5168.3 5189.2 5005.1 5026.9 5083.8 5084.8 5091.7 5505.3 5573.5 5575.0 5592.9 5619.2 6076.5 6102.1 6114.2 6148.8 6150.4 6142.1 6181.4 6213.6 6216.3 6220.8 5546.1 5563.4 5611.2 5611.8 5664.4 6206.5 6208.8 6227.0 6233.0 6256.7 5833.5 5863.9 5865.2 5888.5 5930.6 5930.2 6081.2 6103.3 6103.8 6109.1 6090.8 6095.5 6102.8 6110.2 6120.5 6114.8 6130.9 6131.3 6145.7 6146.6 4766.2 4786.3 4832.3 4855.8 4868.9 3752.5 4304.5 4378.1 4381.9 4398.5 5639.1 5643.9 5653.9 5662.7 5691.7 6057.0 6178.3 6194.1 6203.1 6221.6 5538.1 5542.5 5570.6 5613.7 5630.9 5288.3 5318.8 5340.1 5344.9 5348.9 6200.2 6214.2 6219.5 6222.1 6224.6 6010.4 6047.0 6098.3 6102.9 6160.7 6237.6 6237.7 6245.6 6258.0 6263.4 6023.4 6027.4 6030.7 6037.0 6040.8 6046.8 6121.8 6123.2 6126.7 6130.9 5911.7 5922.6 5923.8 5923.9 5930.2 5896.4 5915.8 5923.6 5924.3 5930.6 5721.3 5742.0 5744.2 5748.0 5768.3 5983.2 6001.6 6006.8 6010.9 6012.0 5634.4 5677.6 5814.4 5840.3 5843.3 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | [#1] ext.html Cycles:180 Average:67221.67 Median:192563.27 stddev:161205.13 (83.7%) stddev-sans-first:161579.37 14:42:51 INFO - PROCESS | 2787 | Values: 440.1 486.7 493.0 493.7 496.9 34846.8 36385.8 36407.3 36457.8 37711.2 35400.8 35557.5 35940.5 36152.6 36206.5 7986.0 8127.9 8339.5 9167.2 9346.8 8342.3 8416.2 8425.2 8436.9 10972.2 9666.0 11145.1 11158.9 11195.6 11249.8 11106.9 11208.3 11221.7 11227.2 11244.9 11054.9 11102.4 11105.1 11116.0 11117.8 25287.4 34941.8 34973.3 35111.5 35137.6 3072.1 3086.7 3203.6 3225.3 3315.9 27495.2 28109.3 28222.0 28302.0 28338.3 2978.3 3058.8 3083.8 3135.5 3196.2 350153.2 350510.0 350526.0 351930.2 355623.0 29544.1 30168.4 30705.8 48223.0 49357.0 30635.7 31455.8 31709.1 31722.8 31812.7 12069.8 12093.0 12330.0 12546.3 12570.4 10292.4 10452.1 10463.5 10478.6 10503.7 6268.8 6662.0 6843.0 6847.8 7280.5 3008.4 3039.6 3041.0 3047.9 3067.2 838.1 861.6 869.8 945.2 1006.2 27079.9 27462.1 27495.6 27528.7 28204.9 934.5 947.8 986.8 1000.6 1044.1 14202.0 14346.2 14346.3 14568.2 15045.2 658869.0 663028.0 663687.0 664861.0 670537.0 43811.3 44969.3 47649.0 48672.3 49184.0 1205.5 1219.9 1240.7 1248.5 1255.1 22142.7 27294.6 27826.0 28030.2 28216.4 41241.4 42831.6 42917.1 42948.8 42991.0 598374.0 668865.0 671130.0 796749.0 799132.0 37491.9 38198.9 38312.6 38504.8 38521.0 42544.6 42963.6 43230.8 43263.0 43417.3 35489.6 35491.0 35646.5 36563.8 36751.8 35228.3 35778.5 36162.0 36209.8 36591.5 34486.0 35495.8 35574.3 36011.0 36764.4 28738.2 31437.8 31834.2 31863.7 31872.7 19353.6 23857.8 24273.0 24293.3 24626.9 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | [#2] jquery.html Cycles:180 Average:31329.97 Median:1996.35 stddev:85311.49 (4273.4%) stddev-sans-first:85519.07 14:42:51 INFO - PROCESS | 2787 | Values: 339.1 356.5 359.5 368.0 392.1 14343.0 14703.0 16647.9 18791.5 19288.1 17648.6 18090.5 18223.0 18407.5 18577.8 4946.0 5518.3 5704.5 5912.4 6034.5 5489.3 5848.8 5889.9 5930.9 5941.1 5794.2 5911.3 5927.2 5932.3 5936.2 5765.8 5836.9 5877.1 5910.9 5934.2 5709.9 5719.8 5725.8 5730.3 5744.4 12222.6 16298.2 22027.1 23438.3 23555.0 2112.8 2117.8 2141.6 2154.0 2248.6 8324.8 8850.1 9844.7 11041.7 12850.8 1807.6 1832.2 1872.6 1926.1 1936.7 105632.0 113625.0 113695.4 114681.0 114756.0 10907.2 10965.5 11154.8 11159.6 11299.7 11896.2 12618.5 12623.6 12924.8 13710.6 7560.8 7579.0 7702.6 7828.8 7834.3 7305.1 7318.6 7335.0 7461.1 7498.7 3624.7 3787.5 4189.0 4292.2 4448.7 1604.2 1809.6 1828.6 1831.1 1840.2 747.5 789.9 813.1 899.0 913.4 16294.3 18569.1 18967.2 19795.1 19890.7 801.7 850.3 863.0 870.2 914.3 9186.3 10186.7 10287.2 10307.6 10326.0 502585.0 502984.0 506314.0 511126.0 511365.0 118423.4 119684.0 121965.0 122602.0 125838.0 924.9 928.9 972.2 973.7 979.5 13723.2 16585.8 17377.7 17485.6 17893.3 49454.3 51545.7 51708.2 51771.5 51996.7 72757.3 74066.6 76224.2 76695.2 77183.6 7351.9 7567.8 7749.6 7779.3 8206.3 10081.7 10706.0 11119.9 12203.3 12336.4 8461.3 10617.2 10782.5 10788.8 10841.4 8583.7 10661.8 10737.1 11114.7 11157.6 10757.4 10880.9 11254.2 11291.0 11310.9 7843.3 8043.3 8043.6 8071.3 8237.0 9955.6 9962.8 10510.1 10629.4 11207.6 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | [#3] mootools.html Cycles:180 Average:5267.09 Median:5004.91 stddev:2947.16 (58.9%) stddev-sans-first:2939.20 14:42:51 INFO - PROCESS | 2787 | Values: 1152.1 1308.8 1339.3 1364.8 1602.1 7325.9 7709.9 7973.4 8136.8 8225.5 8052.6 8100.7 8185.0 8193.3 8193.5 2255.4 2281.4 2285.1 2292.3 2292.6 2259.4 2310.0 2319.7 2322.6 2327.9 2003.8 2176.8 2253.1 2284.7 2286.5 2207.0 2236.8 2264.0 2264.3 2267.3 1910.1 2242.2 2257.0 2283.9 2284.0 8140.1 8206.0 8223.4 8225.1 8225.4 4864.8 4879.9 5054.3 5064.4 5080.7 3257.7 3430.5 3431.5 3499.3 3508.9 981.8 1022.2 1040.9 1060.2 1060.5 10730.5 10786.7 10901.3 10954.7 10962.6 3361.5 3588.8 4365.2 4366.1 4377.1 7499.7 8369.1 8452.5 8509.8 8524.4 5002.7 5007.1 5012.0 5036.9 5037.0 3945.5 4199.3 4296.2 4339.3 4726.0 3444.6 3467.7 3533.7 3551.0 3551.4 1082.2 1090.5 1095.6 1105.0 1111.1 661.0 706.4 833.3 853.8 854.5 5443.6 5558.8 5620.0 5663.1 5760.4 402.4 404.2 412.7 414.5 841.4 3620.4 3718.3 3867.6 3935.6 3938.2 7852.5 9562.5 9765.6 10050.7 10252.2 9741.7 9937.7 10063.6 10145.3 10166.3 7495.6 7772.8 7777.7 7783.5 7791.1 4931.9 5420.8 5539.8 5557.6 5570.4 7921.4 9204.4 9221.9 9316.9 9407.9 9367.9 9399.6 9486.0 9543.3 9564.9 6607.0 6734.4 6743.7 6748.2 6748.5 7655.3 7686.6 7708.0 7746.2 7758.2 6922.1 6985.6 7025.9 7056.1 7112.1 6705.5 6771.0 6895.7 6961.1 6977.1 6500.0 6686.2 6714.1 6714.9 6727.6 4583.3 4769.8 4791.2 4877.8 4960.3 4039.8 4684.3 4781.7 4890.5 5008.5 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | [#4] prototype.html Cycles:180 Average:4359.03 Median:4765.46 stddev:1354.27 (28.4%) stddev-sans-first:1323.39 14:42:51 INFO - PROCESS | 2787 | Values: 302.2 305.5 313.4 319.3 319.3 4282.7 4744.5 5249.9 5331.7 5352.3 5051.8 5118.8 5161.1 5168.1 5201.7 3612.9 4005.4 4010.9 4027.3 4046.9 3757.7 3819.6 3824.0 4011.3 4063.8 3142.0 3899.9 3967.0 3974.5 4011.1 3330.9 3456.1 3840.6 3944.7 4012.6 2886.9 3442.6 3447.7 3450.9 3513.5 4179.0 4915.4 5145.7 5169.3 5266.3 3769.9 4174.4 4323.5 4521.8 4545.5 4769.1 5367.8 5512.9 5518.8 5534.3 3653.3 3687.2 3785.7 3794.0 3800.2 5731.9 5805.0 5809.2 5815.6 5865.4 3204.2 3206.4 3646.9 3800.3 3841.6 3619.5 3922.8 3930.8 3953.5 3955.1 3415.8 4110.5 4396.3 4422.5 4604.6 4606.7 5011.2 5387.8 5547.4 5561.6 1115.3 1232.5 1269.7 1276.5 1296.3 838.6 919.7 941.8 951.4 957.6 3660.8 4042.8 4193.1 4313.0 4328.5 4094.8 4479.4 4712.8 4761.8 4774.6 3663.4 5032.4 5113.0 5187.6 5199.8 2500.0 2922.7 2951.8 2960.0 2971.0 4969.2 5515.3 5826.9 5841.7 5879.4 4909.7 5485.9 5565.8 5709.4 5778.7 5303.0 5393.0 5748.4 5850.6 5851.4 4679.9 5149.7 5248.3 5316.5 5753.8 4486.4 4909.5 5659.0 5663.2 5672.8 4941.4 5044.6 5178.7 5505.0 5945.0 3537.6 3650.4 3777.8 3789.5 3794.7 5688.3 5717.0 5732.8 5755.7 5772.9 5551.7 5556.6 5592.8 5638.4 5665.5 4625.5 5087.9 5196.2 5567.0 5572.6 4609.7 4639.0 4652.2 5170.5 5481.9 5065.9 5363.7 5533.0 5571.0 5579.7 4749.5 4831.6 5382.3 5383.8 5566.0 14:42:51 INFO - PROCESS | 2787 | 14:42:51 INFO - PROCESS | 2787 | [#5] yui.html Cycles:180 Average:19802.34 Median:4129.02 stddev:29388.12 (711.7%) stddev-sans-first:29434.88 14:42:51 INFO - PROCESS | 2787 | Values: 516.8 608.9 655.4 664.2 702.2 13756.9 13957.2 14378.1 14626.2 14906.6 12120.7 12330.8 12426.1 12434.3 12453.0 654.8 678.0 679.6 680.2 682.9 555.1 573.2 634.1 667.5 674.4 700.8 708.4 708.6 709.4 710.4 693.7 701.9 702.6 702.8 705.6 425.8 428.5 434.3 497.4 502.5 12498.1 12893.8 12895.4 12923.8 12944.0 11745.7 11917.8 12002.6 12068.3 12305.8 10930.6 11245.6 11531.7 11716.9 11756.0 3673.4 3735.2 3762.1 3804.9 3806.8 84068.0 88684.0 90386.5 90512.0 90599.0 10246.5 10359.2 10454.4 10466.7 10718.8 18877.2 19015.7 19025.2 19043.6 19254.7 7274.4 7307.2 7312.6 7489.8 7665.4 3979.3 4018.6 4109.5 4120.2 4137.9 3226.5 3309.1 3311.6 3348.5 3349.7 598.8 599.7 604.7 609.8 613.3 554.8 605.6 621.0 643.3 649.6 8558.6 8733.1 8904.1 8925.6 9033.8 539.8 597.4 609.9 619.2 636.8 3976.7 4358.1 4420.8 4472.8 4484.1 75422.0 110593.0 110630.0 111236.4 113053.9 96466.5 98039.0 98843.0 99442.5 100283.5 66398.3 85897.6 86356.6 87941.0 89765.1 8284.9 8494.5 8713.5 8902.2 8961.7 38772.0 44272.1 50837.0 52257.5 52762.7 52760.7 58866.0 70187.6 72962.0 75024.0 11046.7 11373.9 11475.2 11741.2 12260.3 13615.7 13813.3 13828.5 13843.2 14431.1 12317.8 12897.0 12901.7 13484.9 13540.5 12506.7 12634.1 12945.2 13061.7 13170.5 12191.9 12385.9 12524.3 12614.9 12617.4 10421.6 10850.1 11045.7 11102.5 11221.0 10786.1 10959.7 11031.4 11038.7 11161.2 14:42:51 INFO - PROCESS | 2787 | -------- Summary: end -------- 14:42:51 INFO - PROCESS | 2787 | 14:42:52 INFO - TEST-INFO | 2787: exit 0 14:42:52 INFO - TEST-OK | dromaeo_css | took 1119236ms 14:42:52 INFO - TEST-START | kraken 14:42:52 INFO - operating with platform_type : mac_ 14:42:52 INFO - Initialising browser for kraken test... 14:42:52 INFO - TEST-INFO | started process 2790 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpT47VK8/profile http://localhost:57323/getInfo.html) 14:42:54 INFO - PROCESS | 2790 | __metrics Screen width/height:1600/1200 14:42:54 INFO - PROCESS | 2790 | colorDepth:24 14:42:54 INFO - PROCESS | 2790 | Browser inner width/height: 1024/689 14:42:54 INFO - PROCESS | 2790 | __metrics 14:42:54 INFO - PROCESS | 2790 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:42:54 INFO - PROCESS | 2790 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:42:55 INFO - TEST-INFO | 2790: exit 0 14:42:55 INFO - Browser initialized. 14:42:55 INFO - Running cycle 1/1 for kraken test... 14:42:55 INFO - TEST-INFO | started process 2791 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpT47VK8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:43:07 INFO - PROCESS | 2791 | RSS: Main: 232882176 14:43:07 INFO - PROCESS | 2791 | 14:43:10 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57323/tests/kraken/driver.html?testName=audio-beat-detection) 14:43:11 INFO - PROCESS | 2791 | RSS: Main: 292286464 14:43:11 INFO - PROCESS | 2791 | 14:43:14 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57323/tests/kraken/driver.html?testName=audio-dft) 14:43:14 INFO - PROCESS | 2791 | RSS: Main: 295309312 14:43:14 INFO - PROCESS | 2791 | 14:43:18 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57323/tests/kraken/driver.html?testName=audio-fft) 14:43:18 INFO - PROCESS | 2791 | RSS: Main: 387178496 14:43:18 INFO - PROCESS | 2791 | 14:43:20 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57323/tests/kraken/driver.html?testName=audio-oscillator) 14:43:21 INFO - PROCESS | 2791 | RSS: Main: 286134272 14:43:21 INFO - PROCESS | 2791 | 14:43:23 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57323/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:43:24 INFO - PROCESS | 2791 | RSS: Main: 291692544 14:43:24 INFO - PROCESS | 2791 | 14:43:26 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57323/tests/kraken/driver.html?testName=imaging-darkroom) 14:43:27 INFO - PROCESS | 2791 | RSS: Main: 328671232 14:43:27 INFO - PROCESS | 2791 | 14:43:30 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57323/tests/kraken/driver.html?testName=imaging-desaturate) 14:43:31 INFO - PROCESS | 2791 | RSS: Main: 328859648 14:43:31 INFO - PROCESS | 2791 | 14:43:33 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57323/tests/kraken/driver.html?testName=json-parse-financial) 14:43:34 INFO - PROCESS | 2791 | RSS: Main: 328298496 14:43:34 INFO - PROCESS | 2791 | 14:43:36 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57323/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:43:36 INFO - PROCESS | 2791 | RSS: Main: 304926720 14:43:36 INFO - PROCESS | 2791 | 14:43:38 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:43:39 INFO - PROCESS | 2791 | RSS: Main: 307531776 14:43:39 INFO - PROCESS | 2791 | 14:43:41 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:43:42 INFO - PROCESS | 2791 | RSS: Main: 311672832 14:43:42 INFO - PROCESS | 2791 | 14:43:45 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:43:46 INFO - PROCESS | 2791 | RSS: Main: 321486848 14:43:46 INFO - PROCESS | 2791 | 14:43:49 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:43:49 INFO - PROCESS | 2791 | RSS: Main: 321204224 14:43:49 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | Cycle 1(1): loaded http://localhost:57323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57323/tests/kraken/driver.html?testName=ai-astar) 14:43:51 INFO - PROCESS | 2791 | RSS: Main: 364851200 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | __start_tp_report 14:43:51 INFO - PROCESS | 2791 | _x_x_mozilla_page_load 14:43:51 INFO - PROCESS | 2791 | _x_x_mozilla_page_load_details 14:43:51 INFO - PROCESS | 2791 | |i|pagename|runs| 14:43:51 INFO - PROCESS | 2791 | |0;ai-astar;111;114;115;112;113;115;114;114;113;114 14:43:51 INFO - PROCESS | 2791 | |1;audio-beat-detection;156;141;130;155;112;113;114;117;141;113 14:43:51 INFO - PROCESS | 2791 | |2;audio-dft;184;180;181;185;184;183;181;178;182;183 14:43:51 INFO - PROCESS | 2791 | |3;audio-fft;82;59;59;91;60;59;59;59;83;58 14:43:51 INFO - PROCESS | 2791 | |4;audio-oscillator;86;70;94;70;67;69;93;68;69;91 14:43:51 INFO - PROCESS | 2791 | |5;imaging-gaussian-blur;88;86;87;87;135;85;89;85;88;84 14:43:51 INFO - PROCESS | 2791 | |6;imaging-darkroom;106;151;118;94;93;94;92;93;94;104 14:43:51 INFO - PROCESS | 2791 | |7;imaging-desaturate;79;84;82;103;128;122;84;84;83;81 14:43:51 INFO - PROCESS | 2791 | |8;json-parse-financial;58;54;53;53;53;55;55;53;56;55 14:43:51 INFO - PROCESS | 2791 | |9;json-stringify-tinderbox;64;69;72;75;71;69;73;101;108;91 14:43:51 INFO - PROCESS | 2791 | |10;stanford-crypto-aes;88;86;85;87;89;89;86;85;89;88 14:43:51 INFO - PROCESS | 2791 | |11;stanford-crypto-ccm;145;138;172;150;157;146;147;168;216;148 14:43:51 INFO - PROCESS | 2791 | |12;stanford-crypto-pbkdf2;177;172;183;235;200;173;174;171;172;172 14:43:51 INFO - PROCESS | 2791 | |13;stanford-crypto-sha256-iterative;67;66;66;67;67;68;66;67;65;67 14:43:51 INFO - PROCESS | 2791 | __end_tp_report 14:43:51 INFO - PROCESS | 2791 | __start_cc_report 14:43:51 INFO - PROCESS | 2791 | _x_x_mozilla_cycle_collect,2016 14:43:51 INFO - PROCESS | 2791 | __end_cc_report 14:43:51 INFO - PROCESS | 2791 | __startTimestamp1477950231788__endTimestamp 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | ------- Summary: start ------- 14:43:51 INFO - PROCESS | 2791 | Number of tests: 14 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.00 stddev:1.27 (1.1%) stddev-sans-first:0.97 14:43:51 INFO - PROCESS | 2791 | Values: 111.0 114.0 115.0 112.0 113.0 115.0 114.0 114.0 113.0 114.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:135.50 stddev:17.84 (13.2%) stddev-sans-first:16.07 14:43:51 INFO - PROCESS | 2791 | Values: 156.0 141.0 130.0 155.0 112.0 113.0 114.0 117.0 141.0 113.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#2] audio-dft Cycles:10 Average:182.10 Median:183.00 stddev:2.13 (1.2%) stddev-sans-first:2.15 14:43:51 INFO - PROCESS | 2791 | Values: 184.0 180.0 181.0 185.0 184.0 183.0 181.0 178.0 182.0 183.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#3] audio-fft Cycles:10 Average:66.90 Median:59.50 stddev:12.94 (21.7%) stddev-sans-first:12.52 14:43:51 INFO - PROCESS | 2791 | Values: 82.0 59.0 59.0 91.0 60.0 59.0 59.0 59.0 83.0 58.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.00 stddev:11.66 (15.0%) stddev-sans-first:11.98 14:43:51 INFO - PROCESS | 2791 | Values: 86.0 70.0 94.0 70.0 67.0 69.0 93.0 68.0 69.0 91.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:87.00 stddev:15.40 (17.7%) stddev-sans-first:16.28 14:43:51 INFO - PROCESS | 2791 | Values: 88.0 86.0 87.0 87.0 135.0 85.0 89.0 85.0 88.0 84.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:93.00 stddev:18.54 (19.9%) stddev-sans-first:19.65 14:43:51 INFO - PROCESS | 2791 | Values: 106.0 151.0 118.0 94.0 93.0 94.0 92.0 93.0 94.0 104.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:82.50 stddev:18.17 (22.0%) stddev-sans-first:18.55 14:43:51 INFO - PROCESS | 2791 | Values: 79.0 84.0 82.0 103.0 128.0 122.0 84.0 84.0 83.0 81.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.65 (3.0%) stddev-sans-first:1.17 14:43:51 INFO - PROCESS | 2791 | Values: 58.0 54.0 53.0 53.0 53.0 55.0 55.0 53.0 56.0 55.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:71.50 stddev:15.12 (21.1%) stddev-sans-first:14.99 14:43:51 INFO - PROCESS | 2791 | Values: 64.0 69.0 72.0 75.0 71.0 69.0 73.0 101.0 108.0 91.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.00 stddev:1.62 (1.8%) stddev-sans-first:1.69 14:43:51 INFO - PROCESS | 2791 | Values: 88.0 86.0 85.0 87.0 89.0 89.0 86.0 85.0 89.0 88.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:153.50 stddev:22.74 (14.8%) stddev-sans-first:23.57 14:43:51 INFO - PROCESS | 2791 | Values: 145.0 138.0 172.0 150.0 157.0 146.0 147.0 168.0 216.0 148.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:175.50 stddev:20.32 (11.6%) stddev-sans-first:21.44 14:43:51 INFO - PROCESS | 2791 | Values: 177.0 172.0 183.0 235.0 200.0 173.0 174.0 171.0 172.0 172.0 14:43:51 INFO - PROCESS | 2791 | 14:43:51 INFO - PROCESS | 2791 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.84 (1.3%) stddev-sans-first:0.88 14:43:51 INFO - PROCESS | 2791 | Values: 67.0 66.0 66.0 67.0 67.0 68.0 66.0 67.0 65.0 67.0 14:43:51 INFO - PROCESS | 2791 | -------- Summary: end -------- 14:43:51 INFO - PROCESS | 2791 | 14:43:52 INFO - TEST-INFO | 2791: exit 0 14:43:52 INFO - TEST-OK | kraken | took 60306ms 14:43:52 INFO - SUITE-END | took 1179s 14:43:52 INFO - Completed test suite (00:19:39) 14:43:52 INFO - javascript benchmark 14:43:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2508.260063281332, 3264.0207937618725, 3287.490438981957, 3300.092474569493, 3301.950470742941, 5483.506484416402, 5549.556035517159, 5550.195221692858, 5579.246801681772, 5623.3252009758835, 5553.527950124234, 5578.776848926038, 5650.51970582501, 5666.235058267132, 5703.144528320754, 5099.4900509949075, 5177.896442071165, 5190.169572868342, 5208.958208358325, 5214.791408343662, 5089.694618322913, 5156.510131537498, 5193.688378697271, 5221.817236396727, 5228.843134705965, 5183.17069268917, 5221.843344699665, 5244.449332820056, 5246.501582349671, 5258.973705131469, 5027.798888044474, 5136.178211486144, 5138.97430512845, 5168.302279192299, 5189.195674670432, 5005.0990821652085, 5026.924596131061, 5083.822066227664, 5084.771185296633, 5091.719955402451, 5505.311836020497, 5573.498385145316, 5574.9721251393885, 5592.86017849557, 5619.241402410663, 6076.513878889678, 6102.145699602059, 6114.205153330038, 6148.78479253224, 6150.384961503859, 6142.109394137825, 6181.443670069671, 6213.5961162524745, 6216.347283535209, 6220.782272620437, 5546.112621980464, 5563.388027316992, 5611.186377975211, 5611.775528978795, 5664.376918538957, 6206.503479721611, 6208.782692605828, 6227.0, 6232.968835155795, 6256.655883926337, 5833.50415214703, 5863.853403664857, 5865.237519122486, 5888.4700376966275, 5930.644161350333, 5930.199423077829, 6081.179040829431, 6103.298120716086, 6103.8474038148515, 6109.053096770008, 6090.786822461193, 6095.468565591287, 6102.768839629773, 6110.194261626863, 6120.464868152711, 6114.753609057711, 6130.86834845685, 6131.325554189036, 6145.66198859067, 6146.631202127886, 4766.189747742823, 4786.2581299898575, 4832.323474713543, 4855.830045948445, 4868.853934381974, 3752.45589389537, 4304.461942257218, 4378.146261479044, 4381.9342709859375, 4398.516163222043, 5639.125935480007, 5643.858903527444, 5653.858653533613, 5662.716864156776, 5691.658500489983, 6056.970315046453, 6178.320384757673, 6194.101855230966, 6203.100550420164, 6221.626702397871, 5538.141588053858, 5542.473465020817, 5570.610057296031, 5613.7193140342815, 5630.887382252332, 5288.312519372457, 5318.760655770499, 5340.118880384693, 5344.946550534522, 5348.866278343073, 6200.224971878515, 6214.161088253119, 6219.533534984985, 6222.097795819672, 6224.564280500322, 6010.429009244115, 6047.0, 6098.311206366174, 6102.877942441215, 6160.722767475564, 6237.625742455467, 6237.7193026314835, 6245.594036387621, 6257.9687101564205, 6263.404976527405, 6023.367546407564, 6027.367126451835, 6030.698465076641, 6036.973714468463, 6040.848978775566, 6046.818595442144, 6121.816345509642, 6123.173371594956, 6126.693665316806, 6130.938690613215, 5911.674857882857, 5922.585419020627, 5923.763049478145, 5923.940760592512, 5930.169776231417, 5896.3514013457625, 5915.792947246826, 5923.644581325134, 5924.348321684525, 5930.555208359304, 5721.313442386941, 5742.0, 5744.195812586325, 5748.022836117953, 5768.336641286138, 5983.162357269899, 6001.639901605918, 6006.759729610767, 6010.879782404415, 6012.0, 5634.380218176079, 5677.630953987978, 5814.447627475383, 5840.2991641003355, 5843.2695913010875], "value": 5586.235327103812, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.0956035347361, 486.7079752148709, 493.0459560749947, 493.65690844862826, 496.92546118082305, 34846.82576587117, 36385.81807090964, 36407.271854562896, 36457.81771091148, 37711.245775084484, 35400.82299588505, 35557.46663800045, 35940.46089308656, 36152.638473615254, 36206.45690314647, 7986.0, 8127.918720812805, 8339.54132522711, 9167.174954254117, 9346.766330841716, 8342.332613390914, 8416.242538171564, 8425.199606037417, 8436.915630843705, 10972.177086718488, 9665.951670241675, 11145.052670523015, 11158.888411115866, 11195.552177912874, 11249.775004499905, 11106.888931110707, 11208.27146235492, 11221.66335009951, 11227.157963152755, 11244.943775281072, 11054.889451105466, 11102.389368584805, 11105.111591072695, 11116.0, 11117.833232501518, 25287.36781580457, 34941.825290873385, 34973.30053398943, 35111.4733279001, 35137.64862351369, 3072.0630207786617, 3086.675899030592, 3203.6475987641343, 3225.306559089807, 3315.900522984314, 27495.188342040263, 28109.297267568476, 28222.012229571865, 28302.0, 28338.29154271139, 2978.255436140965, 3058.7705922055934, 3083.8458077096057, 3135.5453459248283, 3196.216926852913, 350153.24923375476, 350510.0, 350526.0, 351930.24034879915, 355623.0, 29544.113676589735, 30168.396632067237, 30705.846470767505, 48223.0, 49357.0, 30635.69364306373, 31455.842720786248, 31709.097454152823, 31722.841385793385, 31812.681873180973, 12069.75860482803, 12093.0, 12329.951954083825, 12546.309952952675, 12570.371481425893, 10292.382457052601, 10452.11157051644, 10463.476826158661, 10478.580856765797, 10503.684889453329, 6268.843278917972, 6661.966690166518, 6843.0, 6847.828804279933, 7280.526765760209, 3008.443437963984, 3039.574459575661, 3040.9543856842165, 3047.9085627431214, 3067.2025273429067, 838.1409055717938, 861.6036623153294, 869.799946012408, 945.2390825385493, 1006.2352612014921, 27079.864600676872, 27462.116502633464, 27495.587566186525, 27528.724712752617, 28204.85897570499, 934.5374039850318, 947.8199142162968, 986.8322385194534, 1000.6297669862198, 1044.1072882685346, 14202.0, 14346.21095839718, 14346.282685865664, 14568.198749068692, 15045.172515511751, 658869.0, 663028.0, 663687.0, 664861.0, 670537.0, 43811.342829857575, 44969.325460118125, 47649.0, 48672.26991595129, 49184.0, 1205.482713973177, 1219.9268043917393, 1240.7270400511873, 1248.5380409248455, 1255.0649765924445, 22142.667859982434, 27294.590581141696, 27826.0, 28030.159095227176, 28216.43567128605, 41241.381379279934, 42831.57168428276, 42917.1416571673, 42948.78525607352, 42991.0, 598374.0, 668865.0, 671130.0, 796749.0, 799132.0, 37491.87524374273, 38198.854034379016, 38312.616873830906, 38504.80747596244, 38521.0, 42544.57455425406, 42963.570364295956, 43230.783846080565, 43263.0, 43417.3487397683, 35489.64510354863, 35491.0, 35646.465303019955, 36563.817180913924, 36751.816240918626, 35228.295434091684, 35778.463323050695, 36162.0, 36209.81895090508, 36591.45112823256, 34486.0, 35495.82252088723, 35574.28851423009, 36011.0, 36764.44853327256, 28738.21105926768, 31437.842810785798, 31834.2491162979, 31863.681363187, 31872.6812731879, 19353.612927741084, 23857.761422385553, 24273.0, 24293.271201863972, 24626.87686561556], "value": 17335.198272965466, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.1448775199657, 356.5400633183192, 359.47337151073674, 367.99264014719705, 392.07470369926966, 14343.0, 14703.0, 16647.916760416196, 18791.530211744714, 19288.132034058464, 17648.558786030357, 18090.4572862814, 18223.0, 18407.539811504717, 18577.792443491162, 4945.950540494603, 5518.2826232589705, 5704.48659620633, 5912.379200183983, 6034.4568988791, 5489.286392768934, 5848.766049358021, 5889.911651325234, 5930.851728706773, 5941.10883367494, 5794.217780599607, 5911.290645122592, 5927.1998280232265, 5932.2584676915385, 5936.198613187229, 5765.769369225226, 5836.941630583682, 5877.059670452707, 5910.940890591103, 5934.1692163097205, 5709.914351284734, 5719.771209151629, 5725.799597014085, 5730.283714535683, 5744.396838331957, 12222.633321000385, 16298.185090745534, 22027.118915243373, 23438.296851094496, 23555.0, 2112.767595564487, 2117.809397154248, 2141.550274442369, 2153.9787673574165, 2248.5840119577765, 8324.83452316676, 8850.114988501162, 9844.704658860246, 11041.723956901062, 12850.779175978361, 1807.6023274879506, 1832.2213059449814, 1872.6161136967025, 1926.0851095729556, 1936.6514027475048, 105632.0, 113625.0, 113695.43152284186, 114681.0, 114755.99999999917, 10907.236493445542, 10965.50655220517, 11154.776904461865, 11159.59196117529, 11299.661010169708, 11896.167268291136, 12618.49526018949, 12623.558175463815, 12924.806127908088, 13710.588682339545, 7560.848783024308, 7579.0, 7702.614869256515, 7828.804279893048, 7834.294913457816, 7305.1233851938105, 7318.597477138808, 7334.96332518334, 7461.067366579177, 7498.737544185928, 3624.7100231981376, 3787.5265591801026, 4189.0, 4292.205941900758, 4448.666350023761, 1604.1658337664583, 1809.6109336492718, 1828.5520047588425, 1831.1393644986836, 1840.153529376475, 747.5253214209016, 789.8933643958107, 813.0690359538296, 898.9661888827769, 913.4291068082448, 16294.333774900553, 18569.071546422623, 18967.17833520529, 19795.10922008513, 19890.70163947542, 801.6669899194861, 850.306999795165, 862.977371814402, 870.1907226279494, 914.2731528434911, 9186.26509879208, 10186.694399168036, 10287.22845786569, 10307.639232626821, 10326.0, 502585.0, 502984.0, 506314.0, 511126.0, 511365.0, 118423.40788296003, 119684.0, 121965.0, 122602.0, 125838.0, 924.9260059195249, 928.911753383441, 972.1785091597588, 973.7127547373542, 979.5200351827554, 13723.176609403468, 16585.75121373156, 17377.739333910253, 17485.562860928578, 17893.284268629108, 49454.33457814044, 51545.71135721487, 51708.22437663513, 51771.457709477516, 51996.74001629968, 72757.27242727717, 74066.62966685132, 76224.23775762171, 76695.23304767103, 77183.61408192923, 7351.926480735338, 7567.810804729926, 7749.612519374122, 7779.260970207822, 8206.261436470746, 10081.747956301151, 10706.0, 11119.888801111885, 12203.267803931793, 12336.383180841101, 8461.280791132576, 10617.150627949592, 10782.514786834769, 10788.838167427652, 10841.403722795007, 8583.699570515002, 10661.840072399076, 10737.08734757523, 11114.722131946766, 11157.553697851996, 10757.354558726189, 10880.945595271973, 11254.212205145888, 11291.040261577858, 11310.886891130984, 7843.254890785594, 8043.316318112792, 8043.557604331818, 8071.273585377344, 8236.958815205886, 9955.601775928882, 9962.800743984935, 10510.1066409353, 10629.362238265729, 11207.551697931991], "value": 8930.302095972811, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1152.066825871044, 1308.8102225177347, 1339.276790533112, 1364.7679894417954, 1602.0948164287183, 7325.890111648329, 7709.922900770991, 7973.362131029518, 8136.796580085486, 8225.465344752587, 8052.63763130659, 8100.716474923379, 8185.0, 8193.262606365426, 8193.50838949662, 2255.4474153832334, 2281.441046943493, 2285.1202287119413, 2292.255017119443, 2292.5758734634064, 2259.378670865509, 2310.0, 2319.7100362454694, 2322.605157123293, 2327.860328380294, 2003.7695664998566, 2176.846271476112, 2253.0822584752705, 2284.7486776454575, 2286.4512516995883, 2206.9779302206935, 2236.7763223677666, 2263.988680056589, 2264.30938563738, 2267.263139479676, 1910.1308904448442, 2242.248846636379, 2256.9774302257097, 2283.931482055524, 2284.0292875527834, 8140.145284745135, 8206.0, 8223.42436029478, 8225.054118776297, 8225.424220304581, 4864.756762161877, 4879.8780030499165, 5054.317667114966, 5064.417591976933, 5080.695158290514, 3257.7393808495262, 3430.451127819537, 3431.4681224410256, 3499.265154317596, 3508.8771892983877, 981.7790997025684, 1022.1873610479687, 1040.9063184313372, 1060.2366296266675, 1060.496264274471, 10730.517126729237, 10786.676399708022, 10901.34591924487, 10954.671359859218, 10962.616308429246, 3361.46216605342, 3588.8385022674042, 4365.214261432909, 4366.126774645084, 4377.0808130293, 7499.737509187153, 8369.079401265855, 8452.492850428995, 8509.829803403898, 8524.40329176964, 5002.724850133205, 5007.098722229961, 5012.04771093491, 5036.899262014813, 5036.974815125901, 3945.467361906163, 4199.265128602482, 4296.162248361539, 4339.327404252346, 4725.976370118127, 3444.6383129771516, 3467.7169447304154, 3533.7278579711283, 3550.9822450888096, 3551.3785087609567, 1082.24783775277, 1090.4929207918356, 1095.649392194506, 1104.9779004419865, 1111.0833562311125, 660.9504287178481, 706.4348521183033, 833.2875391540267, 853.7737499562621, 854.5129276312442, 5443.646162999393, 5558.833235002957, 5619.971900140555, 5663.065594176997, 5760.3951585083805, 402.4406075554981, 404.2400287459557, 412.70904012670997, 414.4943169333408, 841.4236248169901, 3620.4179241014913, 3718.2563487302646, 3867.613238676166, 3935.5670876203594, 3938.232044751246, 7852.450328476952, 9562.521873906277, 9765.560549775277, 10050.69847904564, 10252.23108266883, 9741.659041933499, 9937.701868943943, 10063.647772327933, 10145.34055286404, 10166.339187952759, 7495.587742674096, 7772.795216741414, 7777.650005749755, 7783.494072885358, 7791.104023037423, 4931.852044438672, 5420.7560659769615, 5539.833804985857, 5557.638753481173, 5570.359408668055, 7921.445498815027, 9204.447733136034, 9221.907780922373, 9316.860247096163, 9407.905920940702, 9367.906320936976, 9399.624015039322, 9485.952570237105, 9543.33196676226, 9564.856527151958, 6607.0, 6734.3602357775935, 6743.66281685904, 6748.190217173774, 6748.527603067738, 7655.349295309961, 7686.615669216629, 7707.997960265129, 7746.186650401627, 7758.224177582197, 6922.06552115458, 6985.5808651481075, 7025.894611580932, 7056.1179852518435, 7112.0754301940415, 6705.530612857053, 6771.0, 6895.689693963884, 6961.095057642474, 6977.092977912839, 6500.0, 6686.197656281277, 6714.060031595678, 6714.932850671431, 6727.629980351127, 4583.2666773316105, 4769.809207631656, 4791.233402655558, 4877.804887804448, 4960.280759289955, 4039.8384064637085, 4684.250519916797, 4781.713097214179, 4890.462049174517, 5008.549230569266], "value": 4178.233712806988, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.15547544612804, 305.5416874687968, 313.4279939111122, 319.286394907382, 319.32622167227134, 4282.743035417877, 4744.501827308135, 5249.8950020999555, 5331.706756128411, 5352.27744254526, 5051.823186188484, 5118.769655365508, 5161.071007218699, 5168.147255702814, 5201.68789872607, 3612.9161251624537, 4005.3591425371947, 4010.939835902464, 4027.2952233359047, 4046.8956142805796, 3757.722374392706, 3819.579846216914, 3824.0248736572134, 4011.318075927085, 4063.817128229222, 3141.952870706941, 3899.922001559967, 3967.0, 3974.5429275633237, 4011.097503061817, 3330.9174518281657, 3456.1186897341267, 3840.578985775253, 3944.7041471889725, 4012.615647601602, 2886.870090845896, 3442.604100528421, 3447.7414193935556, 3450.913727156816, 3513.5256740339983, 4178.979105104455, 4915.361003069578, 5145.665531740462, 5169.329775859144, 5266.262723218752, 3769.9057523561855, 4174.368299579355, 4323.525151468247, 4521.796519156645, 4545.499975013729, 4769.090854578704, 5367.75845086972, 5512.889742205173, 5518.834434966958, 5534.252875861747, 3653.3058718843336, 3687.2441149564274, 3785.728562862288, 3793.9810300948593, 3800.182960663443, 5731.914021289684, 5804.970975145097, 5809.157672137517, 5815.592908496407, 5865.442782935615, 3204.150900011499, 3206.4228438881223, 3646.9452958205648, 3800.277947190025, 3841.5582208045876, 3619.4798184762462, 3922.8234729437245, 3930.783806890591, 3953.5058117735284, 3955.149642826806, 3415.8480157501112, 4110.527289361762, 4396.27461468854, 4422.49141348749, 4604.58558729716, 4606.723596584148, 5011.173156429149, 5387.838364849061, 5547.417521160301, 5561.582881283839, 1115.297362661518, 1232.4577186038293, 1269.7143142792982, 1276.5276847566274, 1296.2999980010688, 838.5513750143643, 919.7332773495746, 941.7992060123397, 951.4006176109007, 957.5786653872274, 3660.816959152032, 4042.777647229431, 4193.056562273525, 4313.008008158141, 4328.458942632171, 4094.8469678347506, 4479.440069991251, 4712.761438316772, 4761.833335833298, 4774.570288674036, 3663.413853783435, 5032.446430892599, 5113.0, 5187.584993200535, 5199.766010529535, 2499.987500062488, 2922.70772922706, 2951.8376489293296, 2959.9556006659486, 2970.985145074261, 4969.1614102782205, 5515.310586176728, 5826.912596310974, 5841.7371218295275, 5879.353271140171, 4909.735026207603, 5485.917711234335, 5565.803352279279, 5709.371969083398, 5778.6821724804695, 5303.0378759859095, 5392.973035134799, 5748.396418376094, 5850.6412722521145, 5851.385604511465, 4679.906401872012, 5149.6910185389, 5248.343957005374, 5316.468353164652, 5753.791703742261, 4486.416765820422, 4909.4845041270155, 5659.0, 5663.1788390682295, 5672.7730890763905, 4941.430456162847, 5044.6379477541595, 5178.689278643294, 5504.954058728828, 5945.019071853096, 3537.557805274341, 3650.4159334506353, 3777.829997650167, 3789.488419063391, 3794.696424286106, 5688.317401911797, 5716.971415142898, 5732.767454997195, 5755.7122143893475, 5772.94227057724, 5551.722413879209, 5556.583256255878, 5592.804251851166, 5638.351589567088, 5665.518430933417, 4625.472560290421, 5087.87280317995, 5196.194589838581, 5567.0, 5572.554195664418, 4609.700369475973, 4639.0, 4652.208899573621, 5170.457102004236, 5481.890362192814, 5065.873353166053, 5363.651362661414, 5532.972335138298, 5570.972145139248, 5579.6652200868075, 4749.50130236334, 4831.589314908131, 5382.30030096085, 5383.838484845461, 5566.0], "value": 3940.9339201565913, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.811363852194, 608.8995315772897, 655.4461480049359, 664.1565212180531, 702.2170280137648, 13756.862431375683, 13957.232352220637, 14378.06542574732, 14626.195559244237, 14906.627334316647, 12120.696982575419, 12330.81503777444, 12426.130170888042, 12434.316112613802, 12453.0, 654.8395643067455, 677.9593224406551, 679.578661230038, 680.1905732178716, 682.8736683713504, 555.0536335547881, 573.1689050876225, 634.0869148426275, 667.5093806052547, 674.4233680203429, 700.8247938015496, 708.4084789201023, 708.6102643546055, 709.3899246647878, 710.435204012811, 693.6670398208863, 701.8666453373868, 702.5644100657574, 702.7715992302523, 705.6189657584898, 425.8041301001543, 428.51791734298916, 434.32028874811135, 497.4006322381522, 502.52260352665115, 12498.125131240817, 12893.806592901114, 12895.419706113247, 12923.806142907864, 12944.0, 11745.706357341134, 11917.821232681517, 12002.63992080239, 12068.336241506713, 12305.815412768816, 10930.617428390047, 11245.550177992953, 11531.654050378418, 11716.941415292955, 11756.0, 3673.3755261605324, 3735.2342769732413, 3762.0782908187393, 3804.9429258560867, 3806.8286927088348, 84068.0, 88684.0, 90386.54806725924, 90512.0, 90599.0, 10246.53890574926, 10359.223058270509, 10454.42500662471, 10466.722998160973, 10718.785624287471, 18877.244910203437, 19015.714764278546, 19025.238990440503, 19043.61912761737, 19254.71117933232, 7274.418046556263, 7307.19620841707, 7312.597807120659, 7489.812754681067, 7665.386769058462, 3979.3235150024566, 4018.6383225509844, 4109.465769449952, 4120.1553681495825, 4137.875863724033, 3226.4998925166637, 3309.1396236978558, 3311.602607687045, 3348.5312056312137, 3349.732021438279, 598.7724664627414, 599.6941559804444, 604.694629212245, 609.7926704920349, 613.2763339259627, 554.8252300525402, 605.636618029179, 620.9534284928649, 643.2666759893725, 649.6394501051961, 8558.57207139653, 8733.126687331216, 8904.065073167354, 8925.553722313858, 9033.81932361349, 539.8191605812096, 597.4175179200332, 609.9451049405575, 619.2383368456733, 636.7739452494391, 3976.681865450757, 4358.128374325148, 4420.756858372756, 4472.798724057425, 4484.1031793641405, 75422.0, 110593.0, 110630.0, 111236.44381778201, 113053.86946130433, 96466.51766741122, 98039.01960980441, 98843.0, 99442.50278748704, 100283.49858250663, 66398.33601663921, 85897.57051214829, 86356.5682171585, 87941.0, 89765.10234897699, 8284.917150828414, 8494.532800695773, 8713.477191368538, 8902.198802107841, 8961.731148065308, 38772.0, 44272.11455770931, 50837.0, 52257.47742522678, 52762.68093297698, 52760.73619631877, 58866.0, 70187.64906175436, 72962.0, 75024.0, 11046.668599942015, 11373.886261137282, 11475.196736228383, 11741.17811753169, 12260.26438413698, 13615.727685446038, 13813.309334533435, 13828.516001939885, 13843.238621875893, 14431.134131952116, 12317.815232771694, 12897.0, 12901.6793755897, 13484.865151348753, 13540.526081587097, 12506.74986500283, 12634.115611907078, 12945.223286602833, 13061.673458163243, 13170.539031133865, 12191.878081219074, 12385.876141238832, 12524.311162886128, 12614.873851261736, 12617.369131543202, 10421.583136674752, 10850.077743391585, 11045.668629941114, 11102.500387482743, 11221.0, 10786.137109031415, 10959.669533635635, 11031.448427578429, 11038.708713196895, 11161.162912781412], "value": 6815.968900489417, "unit": "score"}], "name": "dromaeo_css", "value": 6779.220532786939, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 115.0, 112.0, 113.0, 115.0, 114.0, 114.0, 113.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 141.0, 130.0, 155.0, 112.0, 113.0, 114.0, 117.0, 141.0, 113.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 180.0, 181.0, 185.0, 184.0, 183.0, 181.0, 178.0, 182.0, 183.0], "value": 182.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 91.0, 60.0, 59.0, 59.0, 59.0, 83.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 94.0, 70.0, 67.0, 69.0, 93.0, 68.0, 69.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 87.0, 135.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 151.0, 118.0, 94.0, 93.0, 94.0, 92.0, 93.0, 94.0, 104.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 82.0, 103.0, 128.0, 122.0, 84.0, 84.0, 83.0, 81.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 53.0, 53.0, 53.0, 55.0, 55.0, 53.0, 56.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 72.0, 75.0, 71.0, 69.0, 73.0, 101.0, 108.0, 91.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 87.0, 89.0, 89.0, 86.0, 85.0, 89.0, 88.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 138.0, 172.0, 150.0, 157.0, 146.0, 147.0, 168.0, 216.0, 148.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 172.0, 183.0, 235.0, 200.0, 173.0, 174.0, 171.0, 172.0, 172.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 67.0, 68.0, 66.0, 67.0, 65.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1486.8999999999999, "alertThreshold": 2.0}]} 14:43:53 INFO - Return code: 0 14:43:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:43:53 INFO - # TBPL SUCCESS # 14:43:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:43:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:43:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:43:53 INFO - 2016-10-31 14:43:53.119 screenresolution[2795:33652] starting screenresolution argv=screenresolution get 14:43:53 INFO - 2016-10-31 14:43:53.122 screenresolution[2795:33652] Display 0: 1600x1200x32@60 14:43:53 INFO - 2016-10-31 14:43:53.128 screenresolution[2796:33655] starting screenresolution argv=screenresolution list 14:43:53 INFO - Available Modes on Display 0 14:43:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:43:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:43:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:43:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:43:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:43:53 INFO - Intel Iris: 14:43:53 INFO - Chipset Model: Intel Iris 14:43:53 INFO - Type: GPU 14:43:53 INFO - Bus: Built-In 14:43:53 INFO - VRAM (Dynamic, Max): 1536 MB 14:43:53 INFO - Vendor: Intel (0x8086) 14:43:53 INFO - Device ID: 0x0a2e 14:43:53 INFO - Revision ID: 0x0009 14:43:53 INFO - Displays: 14:43:53 INFO - Display: 14:43:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:43:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:43:53 INFO - Display Serial Number: 1600x1200 60 14:43:53 INFO - Main Display: Yes 14:43:53 INFO - Mirror: Off 14:43:53 INFO - Online: Yes 14:43:53 INFO - Rotation: Supported 14:43:53 INFO - Return code: 0 14:43:53 INFO - Running post-action listener: _resource_record_post_action 14:43:53 INFO - [mozharness: 2016-10-31 21:43:53.365498Z] Finished run-tests step (success) 14:43:53 INFO - Running post-run listener: _resource_record_post_run 14:43:53 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 0; Write bytes: 704505856; Read time: 0; Write time: 1922 14:43:53 INFO - TinderboxPrint: CPU usage
53.5% 14:43:53 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:43:53 INFO - TinderboxPrint: I/O write bytes / time
704,505,856 / 1,922 14:43:53 INFO - TinderboxPrint: CPU idle
2,256.8 (46.5%) 14:43:53 INFO - TinderboxPrint: CPU system
972.8 (20.0%) 14:43:53 INFO - TinderboxPrint: CPU user
1,628.2 (33.5%) 14:43:53 INFO - TinderboxPrint: Swap in / out
406,601,728 / 0 14:43:53 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398004736; Write bytes: 392509440; Read time: 29734; Write time: 787 14:43:53 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 0; Write bytes: 293609472; Read time: 0; Write time: 1081 14:43:53 INFO - Running post-run listener: _upload_blobber_files 14:43:53 INFO - Blob upload gear active. 14:43:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:43:53 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 14:43:53 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:43:53 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:43:54 INFO - (blobuploader) - INFO - Open directory for files ... 14:43:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:43:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:43:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:55 INFO - (blobuploader) - INFO - Done attempting. 14:43:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:43:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:43:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:56 INFO - (blobuploader) - INFO - Done attempting. 14:43:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:43:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:43:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:58 INFO - (blobuploader) - INFO - Done attempting. 14:43:58 INFO - (blobuploader) - INFO - Iteration through files over. 14:43:58 INFO - Return code: 0 14:43:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:43:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:43:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2c1b8fca2fc43e8a4b2996cd05854ec12c5f1c652907d817e94dcd2835da41cfcea41f234bee37d3fd5e258b577b4aad819f0099fe05e12a9fe0648dba22ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8a216383fc25b85f01725fba430e9dd2f56b18ee4401869faaa2f79ad12ba29f1f458982a02a336c1f74488a452974f9b1967d3b5ef5eeb9aa55e3574f2c71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f690aa393702783ff291578845925c3cb7decce147eb9a76069bca7adc06b9eb5d854ee634131654c60cdc65145add7071acb421335b242ab769a38c1619c1f"} 14:43:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:43:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:43:58 INFO - Contents: 14:43:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2c1b8fca2fc43e8a4b2996cd05854ec12c5f1c652907d817e94dcd2835da41cfcea41f234bee37d3fd5e258b577b4aad819f0099fe05e12a9fe0648dba22ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8a216383fc25b85f01725fba430e9dd2f56b18ee4401869faaa2f79ad12ba29f1f458982a02a336c1f74488a452974f9b1967d3b5ef5eeb9aa55e3574f2c71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f690aa393702783ff291578845925c3cb7decce147eb9a76069bca7adc06b9eb5d854ee634131654c60cdc65145add7071acb421335b242ab769a38c1619c1f"} 14:43:58 INFO - Running post-run listener: copy_logs_to_upload_dir 14:43:58 INFO - Copying logs to upload dir... 14:43:58 INFO - mkdir: /builds/slave/test/build/upload/logs 14:43:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.798148 ========= master_lag: 1.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-31 14:43:59.573524) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 14:43:59.577527) ========= 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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2c1b8fca2fc43e8a4b2996cd05854ec12c5f1c652907d817e94dcd2835da41cfcea41f234bee37d3fd5e258b577b4aad819f0099fe05e12a9fe0648dba22ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8a216383fc25b85f01725fba430e9dd2f56b18ee4401869faaa2f79ad12ba29f1f458982a02a336c1f74488a452974f9b1967d3b5ef5eeb9aa55e3574f2c71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f690aa393702783ff291578845925c3cb7decce147eb9a76069bca7adc06b9eb5d854ee634131654c60cdc65145add7071acb421335b242ab769a38c1619c1f"} build_url:https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011468 build_url: 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2c1b8fca2fc43e8a4b2996cd05854ec12c5f1c652907d817e94dcd2835da41cfcea41f234bee37d3fd5e258b577b4aad819f0099fe05e12a9fe0648dba22ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8a216383fc25b85f01725fba430e9dd2f56b18ee4401869faaa2f79ad12ba29f1f458982a02a336c1f74488a452974f9b1967d3b5ef5eeb9aa55e3574f2c71", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5f690aa393702783ff291578845925c3cb7decce147eb9a76069bca7adc06b9eb5d854ee634131654c60cdc65145add7071acb421335b242ab769a38c1619c1f"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 14:43:59.626778) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:43:59.627208) ========= 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.ghXYjMmodO/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.KVtXrhcyzZ/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005286 ========= master_lag: 0.38 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:44:00.014014) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 14:44:00.014381) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 14:44:00.015108) ========= ========= Total master_lag: 1.62 =========