builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0133 starttime: 1479955818.24 results: success (0) buildid: 20161123172836 builduid: 201ab6d47fba471c92850b76f917e570 revision: 08b67f1167751fd9cde52cd0f3da8da46e65426e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.244021) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.244489) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.244792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.271558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.272001) ========= 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.UfZjBshVk4/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.oXnQVoY3T8/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-23 18:50:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.4M=0s 2016-11-23 18:50:18 (29.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113408 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.410743) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.411111) ========= 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.UfZjBshVk4/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.oXnQVoY3T8/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.027510 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:18.461004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 18:50:18.461365) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 08b67f1167751fd9cde52cd0f3da8da46e65426e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 08b67f1167751fd9cde52cd0f3da8da46e65426e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UfZjBshVk4/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.oXnQVoY3T8/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-23 18:50:18,550 truncating revision to first 12 chars 2016-11-23 18:50:18,550 Setting DEBUG logging. 2016-11-23 18:50:18,550 attempt 1/10 2016-11-23 18:50:18,551 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/08b67f116775?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 18:50:20,213 unpacking tar archive at: mozilla-aurora-08b67f116775/testing/mozharness/ program finished with exit code 0 elapsedTime=1.922300 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 18:50:20.408436) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:20.408773) ========= script_repo_revision: 08b67f1167751fd9cde52cd0f3da8da46e65426e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:20.409189) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:20.409505) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 18:50:20.429516) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-23 18:50:20.429981) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UfZjBshVk4/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.oXnQVoY3T8/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:50:20 INFO - MultiFileLogger online at 20161123 18:50:20 in /builds/slave/test 18:50:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:50:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:50:20 INFO - {'append_to_log': False, 18:50:20 INFO - 'base_work_dir': '/builds/slave/test', 18:50:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:50:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:50:20 INFO - 'branch': 'Mozilla-Aurora', 18:50:20 INFO - 'buildbot_json_path': 'buildprops.json', 18:50:20 INFO - 'config_files': ('talos/mac_config.py',), 18:50:20 INFO - 'default_actions': ('clobber', 18:50:20 INFO - 'read-buildbot-config', 18:50:20 INFO - 'download-and-extract', 18:50:20 INFO - 'populate-webroot', 18:50:20 INFO - 'create-virtualenv', 18:50:20 INFO - 'install', 18:50:20 INFO - 'run-tests'), 18:50:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:50:20 INFO - 'download_minidump_stackwalk': True, 18:50:20 INFO - 'download_symbols': 'ondemand', 18:50:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:50:20 INFO - 'tooltool.py': '/tools/tooltool.py', 18:50:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:50:20 INFO - '/tools/misc-python/virtualenv.py')}, 18:50:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:50:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:50:20 INFO - 'installer_path': 'installer.exe', 18:50:20 INFO - 'log_level': 'info', 18:50:20 INFO - 'log_name': 'talos', 18:50:20 INFO - 'log_to_console': True, 18:50:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:50:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:50:20 INFO - 'opt_config_files': (), 18:50:20 INFO - 'pip_index': False, 18:50:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:20 INFO - 'cmd': ('bash', 18:50:20 INFO - '-c', 18:50:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:20 INFO - 'enabled': True, 18:50:20 INFO - 'halt_on_failure': False, 18:50:20 INFO - 'name': 'check_screen_resolution'},), 18:50:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:20 INFO - 'cmd': ('bash', 18:50:20 INFO - '-c', 18:50:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:20 INFO - 'enabled': True, 18:50:20 INFO - 'halt_on_failure': False, 18:50:20 INFO - 'name': 'check_screen_resolution'},), 18:50:20 INFO - 'run_cmd_checks_enabled': True, 18:50:20 INFO - 'sps_profile': False, 18:50:20 INFO - 'sps_profile_interval': 0, 18:50:20 INFO - 'suite': 'dromaeojs', 18:50:20 INFO - 'system_bits': '32', 18:50:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:50:20 INFO - 'title': 't-yosemite-r7-0133', 18:50:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:50:20 INFO - 'use_talos_json': True, 18:50:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:50:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:50:20 INFO - 'work_dir': 'build'} 18:50:20 INFO - [mozharness: 2016-11-24 02:50:20.627350Z] Running clobber step. 18:50:20 INFO - Running pre-action listener: _resource_record_pre_action 18:50:20 INFO - Running main action method: clobber 18:50:20 INFO - rmtree: /builds/slave/test/build 18:50:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:50:22 INFO - Running post-action listener: _resource_record_post_action 18:50:22 INFO - [mozharness: 2016-11-24 02:50:22.958551Z] Finished clobber step (success) 18:50:22 INFO - [mozharness: 2016-11-24 02:50:22.958675Z] Running read-buildbot-config step. 18:50:22 INFO - Running pre-action listener: _resource_record_pre_action 18:50:22 INFO - Running main action method: read_buildbot_config 18:50:22 INFO - Using buildbot properties: 18:50:22 INFO - { 18:50:22 INFO - "project": "", 18:50:22 INFO - "product": "firefox", 18:50:22 INFO - "script_repo_revision": "production", 18:50:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:50:22 INFO - "repository": "", 18:50:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:50:22 INFO - "buildid": "20161123172836", 18:50:22 INFO - "pgo_build": "False", 18:50:22 INFO - "basedir": "/builds/slave/test", 18:50:22 INFO - "buildnumber": 39, 18:50:22 INFO - "slavename": "t-yosemite-r7-0133", 18:50:22 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:50:22 INFO - "platform": "yosemite_r7", 18:50:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:50:22 INFO - "branch": "mozilla-aurora", 18:50:22 INFO - "repo_path": "releases/mozilla-aurora", 18:50:22 INFO - "revision": "08b67f1167751fd9cde52cd0f3da8da46e65426e", 18:50:22 INFO - "stage_platform": "macosx64", 18:50:22 INFO - "builduid": "201ab6d47fba471c92850b76f917e570", 18:50:22 INFO - "slavebuilddir": "test" 18:50:22 INFO - } 18:50:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 18:50:22 INFO - Running post-action listener: _resource_record_post_action 18:50:22 INFO - [mozharness: 2016-11-24 02:50:22.962038Z] Finished read-buildbot-config step (success) 18:50:22 INFO - [mozharness: 2016-11-24 02:50:22.962140Z] Running download-and-extract step. 18:50:22 INFO - Running pre-action listener: _resource_record_pre_action 18:50:22 INFO - Running main action method: download_and_extract 18:50:22 INFO - mkdir: /builds/slave/test/build/tests 18:50:22 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')]} 18:50:22 INFO - trying https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 18:50:22 INFO - Downloading https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:50:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 18:50:24 INFO - Downloaded 1199 bytes. 18:50:24 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:50:24 INFO - Using the following test package requirements: 18:50:24 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:50:24 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 18:50:24 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 18:50:24 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'jsshell-mac.zip'], 18:50:24 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 18:50:24 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:50:24 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 18:50:24 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 18:50:24 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 18:50:24 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:50:24 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 18:50:24 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:50:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:50:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:50:24 INFO - Fetch https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:50:24 INFO - Http code: 200 18:50:24 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 18:50:24 INFO - x-amz-cf-id: 9bfiFLHyuc4cojWwYeddg9BwgTFdX1mEdxEA60NQKkgTsSBCpOQaAQ== 18:50:24 INFO - x-amz-version-id: GT9VnpllLq1V3zERb4gqLI3Xk2pzYsJw 18:50:25 INFO - Expected file size: 16278222 18:50:25 INFO - Obtained file size: 16278222 18:50:31 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:50:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:50:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:50:31 INFO - Fetch https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:50:32 INFO - Http code: 200 18:50:32 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 18:50:32 INFO - x-amz-cf-id: liaDpGCW18wc-EVXoe7oM9Niw-Km8gKCsQiBAOFmptw__XAUfMgYUQ== 18:50:32 INFO - x-amz-version-id: GT9VnpllLq1V3zERb4gqLI3Xk2pzYsJw 18:50:32 INFO - Expected file size: 16278222 18:50:32 INFO - Obtained file size: 16278222 18:50:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 18:50:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:50:39 INFO - Fetch https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 18:50:39 INFO - Http code: 200 18:50:39 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 18:50:39 INFO - x-amz-cf-id: pnT_s-2sLcriu_8w1g5k0gWRPlH8aY2rkQHsI8sPWV_Oh5uQmf-CNg== 18:50:39 INFO - x-amz-version-id: jcTLXVySg8l0qreUpXxSrcHjKGychCdj 18:50:40 INFO - Expected file size: 14409038 18:50:40 INFO - Obtained file size: 14409038 18:50:40 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')]} 18:50:40 INFO - trying https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:50:40 INFO - Downloading https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 18:50:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 18:50:43 INFO - Downloaded 109691542 bytes. 18:50:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:50:43 INFO - mkdir: /builds/slave/test/properties 18:50:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:50:43 INFO - Writing to file /builds/slave/test/properties/build_url 18:50:43 INFO - Contents: 18:50:43 INFO - build_url:https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:50:43 INFO - Running post-action listener: _resource_record_post_action 18:50:43 INFO - Running post-action listener: set_extra_try_arguments 18:50:43 INFO - [mozharness: 2016-11-24 02:50:43.953936Z] Finished download-and-extract step (success) 18:50:43 INFO - [mozharness: 2016-11-24 02:50:43.954047Z] Running populate-webroot step. 18:50:43 INFO - Running pre-action listener: _resource_record_pre_action 18:50:43 INFO - Running main action method: populate_webroot 18:50:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:50:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:50:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:50:43 INFO - '--tppagecycles', 18:50:43 INFO - '7'], 18:50:43 INFO - 'tests': ['tsvgm']}}, 18:50:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:50:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:50:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:50:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:50:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['damp', 'tps']}, 18:50:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'tests': ['damp', 'tps']}, 18:50:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['dromaeo_dom']}, 18:50:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:50:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['basic_compositor_video']}, 18:50:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:50:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['a11yr', 18:50:43 INFO - 'ts_paint', 18:50:43 INFO - 'tpaint', 18:50:43 INFO - 'sessionrestore', 18:50:43 INFO - 'sessionrestore_no_auto_restore', 18:50:43 INFO - 'tabpaint']}, 18:50:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:50:43 INFO - 'ts_paint', 18:50:43 INFO - 'tpaint', 18:50:43 INFO - 'sessionrestore', 18:50:43 INFO - 'sessionrestore_no_auto_restore', 18:50:43 INFO - 'tabpaint']}, 18:50:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:50:43 INFO - 'ts_paint', 18:50:43 INFO - 'tpaint', 18:50:43 INFO - 'sessionrestore', 18:50:43 INFO - 'sessionrestore_no_auto_restore', 18:50:43 INFO - 'tabpaint']}, 18:50:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:50:43 INFO - 'ts_paint', 18:50:43 INFO - 'tpaint', 18:50:43 INFO - 'sessionrestore', 18:50:43 INFO - 'sessionrestore_no_auto_restore', 18:50:43 INFO - 'tabpaint']}, 18:50:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['a11yr', 18:50:43 INFO - 'ts_paint', 18:50:43 INFO - 'tpaint', 18:50:43 INFO - 'sessionrestore', 18:50:43 INFO - 'sessionrestore_no_auto_restore', 18:50:43 INFO - 'tabpaint']}, 18:50:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['a11yr', 18:50:43 INFO - 'ts_paint', 18:50:43 INFO - 'tpaint', 18:50:43 INFO - 'sessionrestore', 18:50:43 INFO - 'sessionrestore_no_auto_restore', 18:50:43 INFO - 'tabpaint']}, 18:50:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['tsvgx', 18:50:43 INFO - 'tsvgr_opacity', 18:50:43 INFO - 'tart', 18:50:43 INFO - 'tscrollx', 18:50:43 INFO - 'cart']}, 18:50:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:50:43 INFO - 'tsvgr_opacity', 18:50:43 INFO - 'tart', 18:50:43 INFO - 'tscrollx', 18:50:43 INFO - 'cart']}, 18:50:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'talos_options': ['--disable-e10s'], 18:50:43 INFO - 'tests': ['tp5o']}, 18:50:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'tests': ['tp5o']}, 18:50:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'talos_options': ['--disable-e10s', 18:50:43 INFO - '--xperf_path', 18:50:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:43 INFO - 'tests': ['tp5n']}, 18:50:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:43 INFO - 'talos_options': ['--xperf_path', 18:50:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:43 INFO - 'tests': ['tp5n']}}, 18:50:43 INFO - 'talos.zip': {'path': '', 18:50:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:50:43 INFO - Running post-action listener: _resource_record_post_action 18:50:43 INFO - [mozharness: 2016-11-24 02:50:43.973119Z] Finished populate-webroot step (success) 18:50:43 INFO - [mozharness: 2016-11-24 02:50:43.973235Z] Running create-virtualenv step. 18:50:43 INFO - Running pre-action listener: _resource_record_pre_action 18:50:43 INFO - Running main action method: create_virtualenv 18:50:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:50:43 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 18:50:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:50:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:50:44 INFO - Using real prefix '/tools/python27' 18:50:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:50:44 INFO - Installing distribute.............................................................................................................................................................................................done. 18:50:47 INFO - Installing pip.................done. 18:50:47 INFO - Return code: 0 18:50:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:50:47 INFO - Reading from file tmpfile_stdout 18:50:47 INFO - Output received: 18:50:47 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) 18:50:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:50:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:50:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:50:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:50:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:47 INFO - 'HOME': '/Users/cltbld', 18:50:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:47 INFO - 'LOGNAME': 'cltbld', 18:50:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:47 INFO - 'MOZ_NO_REMOTE': '1', 18:50:47 INFO - 'NO_EM_RESTART': '1', 18:50:47 INFO - 'PAGER': '/bin/cat', 18:50:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:47 INFO - 'PWD': '/builds/slave/test', 18:50:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:47 INFO - 'SHELL': '/bin/bash', 18:50:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:50:47 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:50:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:47 INFO - 'USER': 'cltbld', 18:50:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:47 INFO - 'XPC_FLAGS': '0x0', 18:50:47 INFO - 'XPC_SERVICE_NAME': '0', 18:50:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:50:48 INFO - Cleaning up... 18:50:48 INFO - Return code: 0 18:50:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:50:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:50:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:50:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:48 INFO - 'HOME': '/Users/cltbld', 18:50:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:48 INFO - 'LOGNAME': 'cltbld', 18:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:48 INFO - 'MOZ_NO_REMOTE': '1', 18:50:48 INFO - 'NO_EM_RESTART': '1', 18:50:48 INFO - 'PAGER': '/bin/cat', 18:50:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:48 INFO - 'PWD': '/builds/slave/test', 18:50:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:48 INFO - 'SHELL': '/bin/bash', 18:50:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:50:48 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:50:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:48 INFO - 'USER': 'cltbld', 18:50:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:48 INFO - 'XPC_FLAGS': '0x0', 18:50:48 INFO - 'XPC_SERVICE_NAME': '0', 18:50:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:48 INFO - Downloading/unpacking psutil>=3.1.1 18:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:50:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:51 INFO - Installing collected packages: psutil 18:50:51 INFO - Running setup.py install for psutil 18:50:52 INFO - building 'psutil._psutil_osx' extension 18:50:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:50:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:50:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:50:52 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 18:50:52 INFO - building 'psutil._psutil_posix' extension 18:50:52 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 18:50:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:50:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:50:52 INFO - ^ 18:50:52 INFO - 1 warning generated. 18:50:52 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 18:50:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:53 INFO - Successfully installed psutil 18:50:53 INFO - Cleaning up... 18:50:53 INFO - Return code: 0 18:50:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:50:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:50:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:50:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:53 INFO - 'HOME': '/Users/cltbld', 18:50:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:53 INFO - 'LOGNAME': 'cltbld', 18:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:53 INFO - 'MOZ_NO_REMOTE': '1', 18:50:53 INFO - 'NO_EM_RESTART': '1', 18:50:53 INFO - 'PAGER': '/bin/cat', 18:50:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:53 INFO - 'PWD': '/builds/slave/test', 18:50:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:53 INFO - 'SHELL': '/bin/bash', 18:50:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:50:53 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:50:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:53 INFO - 'USER': 'cltbld', 18:50:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:53 INFO - 'XPC_FLAGS': '0x0', 18:50:53 INFO - 'XPC_SERVICE_NAME': '0', 18:50:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:50:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:50:56 INFO - Installing collected packages: mozsystemmonitor 18:50:56 INFO - Running setup.py install for mozsystemmonitor 18:50:56 INFO - Successfully installed mozsystemmonitor 18:50:56 INFO - Cleaning up... 18:50:56 INFO - Return code: 0 18:50:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:50:56 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')]} 18:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:56 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')]} 18:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:56 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 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:50:56 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 18:50:56 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 18:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:56 INFO - 'HOME': '/Users/cltbld', 18:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:56 INFO - 'LOGNAME': 'cltbld', 18:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:56 INFO - 'MOZ_NO_REMOTE': '1', 18:50:56 INFO - 'NO_EM_RESTART': '1', 18:50:56 INFO - 'PAGER': '/bin/cat', 18:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:56 INFO - 'PWD': '/builds/slave/test', 18:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:56 INFO - 'SHELL': '/bin/bash', 18:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:50:56 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:56 INFO - 'USER': 'cltbld', 18:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:56 INFO - 'XPC_FLAGS': '0x0', 18:50:56 INFO - 'XPC_SERVICE_NAME': '0', 18:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:56 INFO - Downloading/unpacking jsonschema==2.5.1 18:50:56 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 18:50:56 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 18:50:56 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 18:50:56 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 18:50:56 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 18:50:56 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 18:50:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:50:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:59 INFO - Downloading functools32-3.2.3-2.tar.gz 18:50:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:50:59 INFO - warning: no files found matching '*.txt' 18:50:59 INFO - no previously-included directories found matching 'build' 18:50:59 INFO - no previously-included directories found matching 'dist' 18:50:59 INFO - no previously-included directories found matching '.git*' 18:50:59 INFO - Installing collected packages: jsonschema, functools32 18:50:59 INFO - Running setup.py install for functools32 18:50:59 INFO - warning: no files found matching '*.txt' 18:50:59 INFO - no previously-included directories found matching 'build' 18:50:59 INFO - no previously-included directories found matching 'dist' 18:50:59 INFO - no previously-included directories found matching '.git*' 18:50:59 INFO - Successfully installed jsonschema functools32 18:50:59 INFO - Cleaning up... 18:50:59 INFO - Return code: 0 18:50:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:50:59 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')]} 18:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:59 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')]} 18:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:59 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 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:50:59 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 18:50:59 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 18:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:59 INFO - 'HOME': '/Users/cltbld', 18:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:59 INFO - 'LOGNAME': 'cltbld', 18:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:59 INFO - 'MOZ_NO_REMOTE': '1', 18:50:59 INFO - 'NO_EM_RESTART': '1', 18:50:59 INFO - 'PAGER': '/bin/cat', 18:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:59 INFO - 'PWD': '/builds/slave/test', 18:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:59 INFO - 'SHELL': '/bin/bash', 18:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:50:59 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:59 INFO - 'USER': 'cltbld', 18:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:59 INFO - 'XPC_FLAGS': '0x0', 18:50:59 INFO - 'XPC_SERVICE_NAME': '0', 18:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:51:00 INFO - Cleaning up... 18:51:00 INFO - Return code: 0 18:51:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:51:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:51:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:00 INFO - 'HOME': '/Users/cltbld', 18:51:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:00 INFO - 'LOGNAME': 'cltbld', 18:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:00 INFO - 'MOZ_NO_REMOTE': '1', 18:51:00 INFO - 'NO_EM_RESTART': '1', 18:51:00 INFO - 'PAGER': '/bin/cat', 18:51:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:00 INFO - 'PWD': '/builds/slave/test', 18:51:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:00 INFO - 'SHELL': '/bin/bash', 18:51:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:51:00 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:51:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:00 INFO - 'USER': 'cltbld', 18:51:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:00 INFO - 'XPC_FLAGS': '0x0', 18:51:00 INFO - 'XPC_SERVICE_NAME': '0', 18:51:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:00 INFO - Downloading/unpacking blobuploader==1.2.4 18:51:00 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 18:51:00 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 18:51:00 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 18:51:00 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 18:51:00 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 18:51:00 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 18:51:02 INFO - Downloading blobuploader-1.2.4.tar.gz 18:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:51:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:51:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:04 INFO - Downloading docopt-0.6.1.tar.gz 18:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:51:04 INFO - Installing collected packages: blobuploader, requests, docopt 18:51:04 INFO - Running setup.py install for blobuploader 18:51:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:51:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:51:04 INFO - Running setup.py install for requests 18:51:04 INFO - Running setup.py install for docopt 18:51:05 INFO - Successfully installed blobuploader requests docopt 18:51:05 INFO - Cleaning up... 18:51:05 INFO - Return code: 0 18:51:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:05 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 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:51:05 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 18:51:05 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 18:51:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:51:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:05 INFO - 'HOME': '/Users/cltbld', 18:51:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:05 INFO - 'LOGNAME': 'cltbld', 18:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:05 INFO - 'MOZ_NO_REMOTE': '1', 18:51:05 INFO - 'NO_EM_RESTART': '1', 18:51:05 INFO - 'PAGER': '/bin/cat', 18:51:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:05 INFO - 'PWD': '/builds/slave/test', 18:51:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:05 INFO - 'SHELL': '/bin/bash', 18:51:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:51:05 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:51:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:05 INFO - 'USER': 'cltbld', 18:51:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:05 INFO - 'XPC_FLAGS': '0x0', 18:51:05 INFO - 'XPC_SERVICE_NAME': '0', 18:51:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:51:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-gVR6LT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:51:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-gxV9FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:51:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rOt9Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:51:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Z71Uj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:51:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-NMHK8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:51:05 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-eeHiHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-afmMoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-K0xhpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-0QWSpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ixgZSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ziTdBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-b0W7Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-6FkDvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-djTlM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:06 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rabDXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:51:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Xap0v7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:51:07 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-adY4Ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:51:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:51:07 INFO - Running setup.py install for manifestparser 18:51:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:51:07 INFO - Running setup.py install for mozcrash 18:51:07 INFO - Running setup.py install for mozdebug 18:51:07 INFO - Running setup.py install for mozdevice 18:51:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:51:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:51:07 INFO - Running setup.py install for mozfile 18:51:07 INFO - Running setup.py install for mozhttpd 18:51:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozinfo 18:51:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozInstall 18:51:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozleak 18:51:08 INFO - Running setup.py install for mozlog 18:51:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for moznetwork 18:51:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozprocess 18:51:08 INFO - Running setup.py install for mozprofile 18:51:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:51:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:51:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:51:09 INFO - Running setup.py install for mozrunner 18:51:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:51:09 INFO - Running setup.py install for mozscreenshot 18:51:09 INFO - Running setup.py install for moztest 18:51:09 INFO - Running setup.py install for mozversion 18:51:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:51:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:51:09 INFO - Cleaning up... 18:51:09 INFO - Return code: 0 18:51:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51: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')]} 18:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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')]} 18:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:09 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 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:51:09 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 18:51:09 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 18:51:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:51:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:09 INFO - 'HOME': '/Users/cltbld', 18:51:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:09 INFO - 'LOGNAME': 'cltbld', 18:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:09 INFO - 'MOZ_NO_REMOTE': '1', 18:51:09 INFO - 'NO_EM_RESTART': '1', 18:51:09 INFO - 'PAGER': '/bin/cat', 18:51:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:09 INFO - 'PWD': '/builds/slave/test', 18:51:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:09 INFO - 'SHELL': '/bin/bash', 18:51:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:51:09 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:51:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:09 INFO - 'USER': 'cltbld', 18:51:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:09 INFO - 'XPC_FLAGS': '0x0', 18:51:09 INFO - 'XPC_SERVICE_NAME': '0', 18:51:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-c5nl_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:51:10 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)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-zdLQG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-UxAOL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:51:10 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)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-WN6lsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:51:10 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)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-TSmdGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:51:10 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)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-L73IGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:51:10 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)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-mgB4ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:51:10 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)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-4lrVJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:51:10 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)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:51:10 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-tROwwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-2PEvxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:51:11 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)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-YY3wzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:51:11 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)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-QyhbKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:51:11 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)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-afIAHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:51:11 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)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-GqDz6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-fEnEvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:11 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)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-iJjBIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:51:11 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)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:51:11 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-JVGiMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:51:11 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)) 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:51:11 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)) 18:51:11 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)) 18:51:11 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)) 18:51:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:14 INFO - Downloading blessings-1.6.tar.gz 18:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:51:14 INFO - Installing collected packages: blessings 18:51:14 INFO - Running setup.py install for blessings 18:51:14 INFO - Successfully installed blessings 18:51:14 INFO - Cleaning up... 18:51:14 INFO - Return code: 0 18:51:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:51:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:51:15 INFO - Reading from file tmpfile_stdout 18:51:15 INFO - Current package versions: 18:51:15 INFO - blessings == 1.6 18:51:15 INFO - blobuploader == 1.2.4 18:51:15 INFO - docopt == 0.6.1 18:51:15 INFO - functools32 == 3.2.3-2 18:51:15 INFO - jsonschema == 2.5.1 18:51:15 INFO - manifestparser == 1.1 18:51:15 INFO - mozInstall == 1.12 18:51:15 INFO - mozcrash == 1.0 18:51:15 INFO - mozdebug == 0.1 18:51:15 INFO - mozdevice == 0.48 18:51:15 INFO - mozfile == 1.2 18:51:15 INFO - mozhttpd == 0.7 18:51:15 INFO - mozinfo == 0.9 18:51:15 INFO - mozleak == 0.1 18:51:15 INFO - mozlog == 3.4 18:51:15 INFO - moznetwork == 0.27 18:51:15 INFO - mozprocess == 0.23 18:51:15 INFO - mozprofile == 0.28 18:51:15 INFO - mozrunner == 6.13 18:51:15 INFO - mozscreenshot == 0.1 18:51:15 INFO - mozsystemmonitor == 0.3 18:51:15 INFO - moztest == 0.7 18:51:15 INFO - mozversion == 1.4 18:51:15 INFO - psutil == 3.1.1 18:51:15 INFO - requests == 1.2.3 18:51:15 INFO - wsgiref == 0.1.2 18:51:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:15 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 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:51:15 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 18:51:15 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 18:51:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:51:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:15 INFO - 'HOME': '/Users/cltbld', 18:51:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:15 INFO - 'LOGNAME': 'cltbld', 18:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:15 INFO - 'MOZ_NO_REMOTE': '1', 18:51:15 INFO - 'NO_EM_RESTART': '1', 18:51:15 INFO - 'PAGER': '/bin/cat', 18:51:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:15 INFO - 'PWD': '/builds/slave/test', 18:51:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:15 INFO - 'SHELL': '/bin/bash', 18:51:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:51:15 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:51:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:15 INFO - 'USER': 'cltbld', 18:51:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:15 INFO - 'XPC_FLAGS': '0x0', 18:51:15 INFO - 'XPC_SERVICE_NAME': '0', 18:51:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 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)) 18:51:16 INFO - Cleaning up... 18:51:16 INFO - Return code: 0 18:51:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:16 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 0x10e1c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e13a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff95bd6c690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, '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.UfZjBshVk4/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.oXnQVoY3T8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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 18:51:16 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 18:51:16 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 18:51:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:51:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:16 INFO - 'HOME': '/Users/cltbld', 18:51:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:16 INFO - 'LOGNAME': 'cltbld', 18:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:16 INFO - 'MOZ_NO_REMOTE': '1', 18:51:16 INFO - 'NO_EM_RESTART': '1', 18:51:16 INFO - 'PAGER': '/bin/cat', 18:51:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:16 INFO - 'PWD': '/builds/slave/test', 18:51:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:16 INFO - 'SHELL': '/bin/bash', 18:51:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:51:16 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:51:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:16 INFO - 'USER': 'cltbld', 18:51:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:16 INFO - 'XPC_FLAGS': '0x0', 18:51:16 INFO - 'XPC_SERVICE_NAME': '0', 18:51:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:51:16 INFO - Cleaning up... 18:51:16 INFO - Return code: 0 18:51:16 INFO - Running post-action listener: _resource_record_post_action 18:51:16 INFO - Running post-action listener: _start_resource_monitoring 18:51:16 INFO - Starting resource monitoring. 18:51:16 INFO - [mozharness: 2016-11-24 02:51:16.798229Z] Finished create-virtualenv step (success) 18:51:16 INFO - [mozharness: 2016-11-24 02:51:16.799151Z] Running install step. 18:51:16 INFO - Running pre-action listener: _resource_record_pre_action 18:51:16 INFO - Running main action method: install 18:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:51:17 INFO - Reading from file tmpfile_stdout 18:51:17 INFO - Detecting whether we're running mozinstall >=1.0... 18:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:51:17 INFO - Reading from file tmpfile_stdout 18:51:17 INFO - Output received: 18:51:17 INFO - Usage: mozinstall [options] installer 18:51:17 INFO - Options: 18:51:17 INFO - -h, --help show this help message and exit 18:51:17 INFO - -d DEST, --destination=DEST 18:51:17 INFO - Directory to install application into. [default: 18:51:17 INFO - "/builds/slave/test"] 18:51:17 INFO - --app=APP Application being installed. [default: firefox] 18:51:17 INFO - mkdir: /builds/slave/test/build/application 18:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:51:48 INFO - Reading from file tmpfile_stdout 18:51:48 INFO - Output received: 18:51:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:51:48 INFO - Running post-action listener: _resource_record_post_action 18:51:48 INFO - [mozharness: 2016-11-24 02:51:48.021642Z] Finished install step (success) 18:51:48 INFO - [mozharness: 2016-11-24 02:51:48.021817Z] Running run-tests step. 18:51:48 INFO - Running pre-action listener: _resource_record_pre_action 18:51:48 INFO - Running main action method: run_tests 18:51:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:48 INFO - 2016-11-23 18:51:48.036 screenresolution[1621:7410] starting screenresolution argv=screenresolution get 18:51:48 INFO - 2016-11-23 18:51:48.038 screenresolution[1621:7410] Display 0: 1600x1200x32@60 18:51:48 INFO - 2016-11-23 18:51:48.044 screenresolution[1622:7413] starting screenresolution argv=screenresolution list 18:51:48 INFO - Available Modes on Display 0 18:51:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:48 INFO - Intel Iris: 18:51:48 INFO - Chipset Model: Intel Iris 18:51:48 INFO - Type: GPU 18:51:48 INFO - Bus: Built-In 18:51:48 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:48 INFO - Vendor: Intel (0x8086) 18:51:48 INFO - Device ID: 0x0a2e 18:51:48 INFO - Revision ID: 0x0009 18:51:48 INFO - Displays: 18:51:48 INFO - Display: 18:51:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:48 INFO - Display Serial Number: 1600x1200 60 18:51:48 INFO - Main Display: Yes 18:51:48 INFO - Mirror: Off 18:51:48 INFO - Online: Yes 18:51:48 INFO - Rotation: Supported 18:51:48 INFO - Return code: 0 18:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:51:48 INFO - Python 2.7.3 18:51:48 INFO - Return code: 0 18:51:48 INFO - grabbing minidump binary from tooltool 18:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:48 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 0x10dfd8d30>, '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 0x10de3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dfd0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:51:48 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 18:51:48 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 18:51:48 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 18:51:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:51:48 INFO - Return code: 0 18:51:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:51:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:51:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:51:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:51:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 18:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --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 18:51:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UfZjBshVk4/Render', 18:51:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:48 INFO - 'HOME': '/Users/cltbld', 18:51:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:48 INFO - 'LOGNAME': 'cltbld', 18:51:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:51:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:48 INFO - 'MOZ_NO_REMOTE': '1', 18:51:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:51:48 INFO - 'NO_EM_RESTART': '1', 18:51:48 INFO - 'PAGER': '/bin/cat', 18:51:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:48 INFO - 'PWD': '/builds/slave/test', 18:51:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:51:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:48 INFO - 'SHELL': '/bin/bash', 18:51:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oXnQVoY3T8/Listeners', 18:51:48 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 18:51:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:48 INFO - 'USER': 'cltbld', 18:51:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:48 INFO - 'XPC_FLAGS': '0x0', 18:51:48 INFO - 'XPC_SERVICE_NAME': '0', 18:51:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--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 18:51:48 INFO - mozversion application_buildid: 20161123172836 18:51:48 INFO - mozversion application_changeset: 08b67f1167751fd9cde52cd0f3da8da46e65426e 18:51:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:51:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:51:48 INFO - mozversion application_name: Firefox 18:51:48 INFO - mozversion application_remotingname: firefox-dev 18:51:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:51:48 INFO - mozversion application_vendor: Mozilla 18:51:48 INFO - mozversion application_version: 52.0a2 18:51:48 INFO - mozversion platform_buildid: 20161123172836 18:51:48 INFO - mozversion platform_changeset: 08b67f1167751fd9cde52cd0f3da8da46e65426e 18:51:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:51:48 INFO - mozversion platform_version: 52.0a2 18:51:48 INFO - using testdate: 1479955908 18:51:48 INFO - actual date: 1479955908 18:51:48 INFO - starting webserver on 'localhost:49235' 18:51:48 INFO - SUITE-START | Running 2 tests 18:51:48 INFO - TEST-START | dromaeo_css 18:51:48 INFO - operating with platform_type : mac_ 18:51:48 INFO - Initialising browser for dromaeo_css test... 18:51:48 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpqkdpBb/profile http://localhost:49235/getInfo.html) 18:51:50 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 18:51:51 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 18:51:51 INFO - PROCESS | 1632 | colorDepth:24 18:51:51 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 18:51:51 INFO - PROCESS | 1632 | __metrics 18:51:51 INFO - PROCESS | 1632 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:51:51 INFO - PROCESS | 1632 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:51:52 INFO - TEST-INFO | 1632: exit 0 18:51:52 INFO - Browser initialized. 18:51:52 INFO - Running cycle 1/1 for dromaeo_css test... 18:51:52 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpqkdpBb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:52:04 INFO - PROCESS | 1634 | RSS: Main: 222617600 18:52:04 INFO - PROCESS | 1634 | 18:52:55 INFO - PROCESS | 1634 | 1479955975992 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] 18:55:08 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 18:55:10 INFO - PROCESS | 1634 | RSS: Main: 348377088 18:55:10 INFO - PROCESS | 1634 | 18:58:13 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 18:58:13 INFO - PROCESS | 1634 | RSS: Main: 345026560 18:58:13 INFO - PROCESS | 1634 | 19:01:16 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 19:01:16 INFO - PROCESS | 1634 | RSS: Main: 322105344 19:01:16 INFO - PROCESS | 1634 | 19:04:19 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 19:04:19 INFO - PROCESS | 1634 | RSS: Main: 325345280 19:04:19 INFO - PROCESS | 1634 | 19:07:23 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 19:07:24 INFO - PROCESS | 1634 | RSS: Main: 314331136 19:07:24 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 19:10:27 INFO - PROCESS | 1634 | RSS: Main: 502964224 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | __start_tp_report 19:10:27 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 19:10:27 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 19:10:27 INFO - PROCESS | 1634 | |i|pagename|runs| 19:10:27 INFO - PROCESS | 1634 | |0;dojo.html;2982.8806847726087;3299.2246821996832;3312.8840490582834;3312.9171770705716;3369.578802649669;5161.561267292284;5459.235707001018;5505.201745746865;5522.171674248864;5561.026820306441;4416.955830441702;5455.1271796512565;5482.150266708657;5519.668819870801;5528.9723551382285;5010.323606313146;5059.4181669107975;5100.617453690969;5103.719295438749;5124.436312005695;5011.824586139486;5029.220470827027;5045.495450454961;5081.568066714333;5160.974195129038;5052.671576347544;5094.032133894567;5112.309838171855;5115.820946266881;5118.488151184889;5117.66735162217;5166;5168.870778230536;5170.043541944752;5215.348081489814;4942.382202224722;4966.677165984235;5092.108880945857;5099.59203263738;5122.641415100945;4038.9192216155807;5402.51377376038;5425.810096646598;5445.918311225335;5464.808731694372;5926.614770039977;5984;6008.4291992261105;6012.639241645472;6017.879642407127;5877.206577112078;5966.403359664042;6098.207233059718;6123.142760013601;6130.754769809202;5523.7790488380815;5543.861403464905;5569.637973531708;5577.107662773937;5598.356189038232;6163.613187032882;6197.25632924043;6208.192934918432;6213.37866213375;6215.96892015537;5740.081586946152;5853.912191317134;5854.4730974212525;5883.941160588425;5888.646681199141;5927;5943.613665111754;6002.519798416116;6004.789832355847;6015.849603759853;5890.793822216202;5900.616459930091;5907.822765317047;5908.586398952118;5909.409059094056;5909.5863289569315;5915.497182739429;5995.640261584319;6042.848928776728;6078.300995385499;4740.19416699162;4775.832845850378;4782.880427989258;4788.257820037899;4801.207800712913;4202.222588821078;4282.914341713148;4295.871123866289;4301.720388174758;4318.892027699333;4681.391419115494;5541.030319694038;5569.582281328917;5603.3836278009385;5644.604877658525;6011.579189456696;6075.5747097703625;6111.98679349419;6115.541334399938;6141.324454310023;5603.943960560342;5666.9716651416475;5676.978143934049;5686.175504551817;5701.344345400249;5111.539961403491;5159.071367153873;5160.380754309507;5205.167173252262;5210.218467229945;6191.597546159485;6195.597286176384;6225.221847269091;6240.157578726814;6250.468710159687;4492.77536123186;5554.1946417770005;6095.420935011167;6116.755329786759;6123.877522449437;6191.442770150708;6195.81412557624;6223.222097237845;6241.188645476241;6247.219097612799;4686.8125274988615;5852.151438041546;5887.116932460165;5890.705464726832;5996.37038110992;5875.676837773793;6064.029755239317;6079.544034197541;6079.726412311545;6127.172831667846;5826.126081087871;5840.532757379485;5869.706514674334;5882.49998750111;5887.234659494238;5707.429257074259;5763.625364351304;5770.884582308414;5821.82534523948;5894.498967587803;5622.9156562652415;5659.971700141473;5661.971690141523;5681.0058239808695;5710.086386178192;5804.390538993345;5807.361190269152;5808.21589085485;5810.331811841692;5816.360200377871;5646.011947909182;5664.688442135722;5683.403242659627;5688.886222275614;5695.686737229326 19:10:27 INFO - PROCESS | 1634 | |1;ext.html;496.8434942992959;503.16725818769936;515.4433212130899;515.8676704633331;531.1554628141255;29454.852725736368;31408.52887206691;31462.52806207903;31464.685353146462;31500.527492087607;31821.84089079557;32255.83872080636;39867.800660996654;40661.59338406615;40687.18625627493;9111.45331280122;9262.490563019031;9263.490508022056;9266.98063213046;9274.907250927505;9214.907850921505;9312.534373281344;9317.534123293808;9328.81342373152;9338.299627527962;9331.440113593173;9376.062393760603;9377.1091746284;9378.906210937872;9390.624375025025;9289.767755806126;9343.486108264044;9344.112309330603;9366.672166474209;9379.484128372937;8567.871481927776;9161.587728552231;9249.67626133089;9255.629774809;9266.629334826599;34906.65093349059;38517;38526;38614.61385386138;38677;3132.9843350783326;3290.2596915694016;3303.355845610107;3371.2402594488817;3599.4420864766003;24112.51774964491;28605.856970715013;29141.83432662691;30144.69855301441;30697.386052279053;3159.8420078996187;3171.587693599817;3207.5990501187352;3209.7753157278767;3292.901212963615;461441;466452;466674;467471.66264168464;467956;32677.18307042343;33066;33419.997400078035;34890;60584.394156057875;29354.26614334616;34951;35089.649103509146;35096.64903350985;35143.824280878434;14195.574132776033;14345.139291642326;14369;14390.208538530273;14407.207603581903;12029.398530073462;12107.939460302641;12110.878891211152;12122.69693257658;12292.631221063382;7662.731804386931;7670.7698769037015;7746.961265193638;7772;7784.571848548271;3157.1475701560908;3196.536502207167;3202.807831530069;3204.8718051278156;3236.465983112807;868.201254845531;913.1097180249204;993.3890657245742;1023.6877752285476;1050.5850189175385;28181.295467613065;29192.708072919;29316.267093322836;29751.40497190044;29906.953256635912;716.8566286742672;1061.9256652034437;1072.742541789965;1129.2603344809163;1193.6240084373394;14830.110193388215;15163.393464261526;15401.537953861402;15420.656446835246;15487.380504779683;680523;688404;690310;690612;691416;56723.149152762744;57000.42999569951;57192.42807571871;57225.71387143037;57267.71366143143;1219.9024078073733;1267.0687045021903;1277.023077345831;1321.2402868350737;1394.9930250348684;29314.413711726073;30027.69972300249;30298.545521816748;30516.237094072825;30576.96043514822;48161;48547.75726121488;48643.75678121587;48671;48783.75608122079;453977;455802;456102;456997;457317;20791.688124678443;26092.217233483025;26610.802513886534;26835.731642683324;27188.1843544694;45087.32369014533;45283.86790330268;45327.09345813131;45552.772236138604;46243.537564623926;35990;37387.6261237395;37919;37933.430998535594;38318.42522362111;30210.69789302167;35147.29705405929;37651.435228472146;37742.62257377391;40485.59514404818;37345.4398184022;37380.439293410076;37538.436923446716;38230.808845955595;38393.61606384012;32091.839540802146;33807.49288760621;34003.319933601684;34546.82726586351;34710.30579388449;23448.296551103493;23711.881440592686;25271.74728252694;25674;25786.484270314864 19:10:27 INFO - PROCESS | 1634 | |2;jquery.html;353.4397979202963;359.2545468153582;381.9637134472225;390.9198614284071;411.9835206591736;14566;14681;16096.114713690742;19648.705269420934;20443.875586842718;18681.813181868176;19213.32753353633;19736.013199340046;19901.40295791125;20066.398008059747;5807.41925807418;6038.112558562489;6107.603005804608;6111.205543279368;6112.938870611304;5730.742116604742;5911.438413350725;5970.373110823366;5983.9102413463615;5987.311459182184;5698.943010569903;5856.677882716449;5916.171735956969;5924.08176732605;5930.169776231331;5116.667416617908;5859.0625499920025;6046.3349031606485;6053;6067.211262535886;5608.327000759935;5688.089905615123;5784.652920824722;5820.5634577406445;5881.617694849821;13104.951603871667;13338.933305333509;13361.795303774865;13868.653283667989;24679.5064098717;2187.3109970359446;2291.51878105598;2297.574948634508;2420.310211589706;2535.632333311678;9867.50662466881;9874.9506252469;9930.851037234448;10341.53463094155;10574.629887953884;1489.448903905551;1838.531174550481;1842.447265820262;1880.2948894164688;1928.845692344609;105203;113431;113672;113681;114780.42609786981;9341.252699784001;11353.148513861493;11476.885231147582;11595.420228988516;11760.529578816922;14607;14618.853811461962;14642.267886605627;14646.414143434144;14655.413783448543;6968.930310696828;8382.036065852504;8488.660453581788;8492.830143397097;8619.138086191331;6788.676208139365;8338.666453341919;8364.121767214476;8397.70608028716;8503.61733721984;4383.890402739893;4543.295789152687;4557.647397152921;4565.657575681837;4567;1845.6216475622466;1908.2462427341122;1914.8946807925697;1917.9712304315447;1920.817522335376;706.2795948132979;736.694271877165;755.4032314471616;840.1640367834044;923.7182659288982;19719.704204436945;20060.498487537636;20981.370558883256;21385.572288554144;21434.79466159739;932.2681694869631;935.5883411299006;948.3503799897093;958.769895225141;976.3312131190157;8476.830463390697;10287.43419111956;11113.66659000215;11121.610743624096;11167.497462614201;578511;591096;592509;601750;602734;75283.24716752872;108784;117659.41170294094;119784;128395.35802321116;926.2127191886842;944.9149576538144;971.2909576009553;972.6304004478253;973.7955029443896;18343.724844127082;18756.812431876053;19028.524286892938;19066.33267835619;19089.90455047716;53560.19659705186;54685.45314546803;55167.72416137894;56289.02988395387;56613.433865660816;76529.23470765221;78982.6050869742;79515.20484795078;80392.59803700945;84040.15959840323;8046;8159.510429374256;8186.959065204635;8271.13153118938;8441.78895527592;11624.593139240424;12783.23300601967;12844.293563854078;13283.070185086952;13296.601101966957;10800.08199303068;10983.945080274547;11150.275232109887;11377.54489820398;11424.600138995096;10826.89173108259;10874.293170943864;11036.392998385167;11167.553297867995;11352.262102963281;10851.131909447067;11001.724956876142;11296.43517824122;11302.265352752045;11951.880481195076;7032.929670703227;7141.714331426892;7523.3229009389415;7750.612469376621;8543.10297418787;9681.225501959967;11114.05530529914;11300.321980681185;11617;11692.298462092302 19:10:27 INFO - PROCESS | 1634 | |3;mootools.html;1473.0719646622626;1503.812023497063;1678.2280151130478;1780.786305643323;1853.3976457651268;7274.27257274273;8385.66457341707;8392;8396.99236091669;8402.915970840297;7025.26234745352;7295.124585049788;8201.7129400471;8455.069942306342;8463.746087617366;2328.2898715891647;2354.376090336062;2358.3396648938324;2359.1389142962867;2360.1975328388344;2045.9795402045938;2337.298810356886;2367.419982104378;2377.061060880951;2381.345130089222;2136.8504204705678;2156.137544982004;2272.488690044735;2355.187460326185;2361.2089949866813;2312.225404489498;2330.7436182019965;2341.040173528862;2343.1798870395396;2345.6012477878717;2306.031466783955;2366.1127077345996;2369.324742448411;2369.6445533170163;2374.4063984004;8497.107803680587;8547.230749232598;8564.571771411405;8567.443116197428;8571.571421428904;5152.458991805881;5325.573954083664;5331.840044798662;5332.520073193431;5334.759935802898;3095.8452077396264;3361.680640339164;3522.806245656488;3722.4230244312175;3729.291434627412;896.914793094655;1014.660088870222;1022.7443139215196;1080.508368692243;1113.991837387166;11261.43692815356;11357;11368.94315528417;11380.943095284554;11437.942810285978;4103.138340948434;4369.47566292047;4569.702969306984;4712.010477799632;4757.643176761757;9541.427514349023;9672.177864881423;9683.709488715349;9686.225101991824;9690.854637180448;5277.393099793496;5291.417944026154;5291.682499050069;5291.947080529222;5295.602829787705;4765.380500534909;4800.759962001886;4853.538913803183;4865.854024379274;4891.633127515451;3779.6787273081545;3827.8468861245797;3847.961050516401;3898.3957486589625;3929.115948911472;991.6414512118444;1269.9619011429672;1271.3007845685022;1276.7701813673627;1285.2866659004342;773.6209257463802;859.282499113235;867.6052396159732;888.7422647432302;955.7945041816043;5471.534919531805;5525.861853453695;5534.142207957773;5803.7968671096305;5895.587308888423;948.6774496671165;951.2437612098398;965.2663975378622;965.4014511003082;969.7963427680126;3801.562820275704;3897.395903634941;3953.327934251127;4003.619656132663;4012.7391719538136;10291.073803357735;10333.276670632984;10528.315659482108;10529;10630.681079567625;10094.444805535766;10281.434521101412;10491.580336786596;10563.94718026405;10566.735831604117;7881.369490440751;7921.287084162453;8148.348132149414;8160.5103693778565;8160.591970401456;5802.158686990447;5834.18321435008;5885.440883116097;5889.38161493037;5897.7935772247765;9299.116583924788;9464.526773661428;9591.520423978634;9770.56032478526;9800.264980126376;9799.85300220483;9864.704058878246;9918.305718599917;9945.104940555384;9946.552405141641;6787.321267873173;6899.896501552581;7257.201707812242;7372.520786149098;7426.5915374654915;7805.804854878674;8030.43786934909;8036.316913062221;8044.115147333671;8051.396145289244;6428.903566446601;7070.68181931804;7119.8932016020835;7254.782356529313;7394.815129621587;7085.39774119185;7126.429885609243;7141.321574450419;7179.425645948416;7186.209516953236;6680.565763225375;6824.112865327675;6884.552504087218;6890.965545172242;6898.8620227596175;4872.634552408513;4888.266759986031;4891.828785992473;4970.130227210296;4992.051510213048;4898.951010489849;4902.313676085422;4932.161532539405;5039.193729003342;5051.141305978066 19:10:27 INFO - PROCESS | 1634 | |4;prototype.html;255.3705116886874;303.7403020417543;311.0745532042174;314.7843727046973;319.4904127915973;4556.817727290912;4609.700369475986;5124.615653825964;5176.715280659567;5222.373315202181;4104.548499665035;4966.57784088352;4991.700497970115;5012.195609580548;5178.152016390979;3940.3104456720102;3949.723519353646;3958.742681725686;3960.2079584083153;3974.3839704845795;3385.933430969249;3890.8443662253476;3917.3536366499493;3938.735227244332;3940.586238444965;3430.7922092843146;3816.8816306949634;3859.902045975191;3895.961040389602;3946.9737868154198;3656.957767036395;3872.7869967151796;3890.6692931100893;3925.6064097245353;3933.9213215735667;3405.880794172192;3411.5906091268957;3412.6246112927556;3430.9828450857835;3431.957984998922;4082.7958602070075;4226.774235471709;4945.134601444769;4954.628402869763;5051.898962020739;4020.618041286073;4360.062586543908;4365.956340436618;4453.755043472608;4474.798634061475;4682.648801339913;5167.741612919376;5405.324334458192;5429.56596433224;5435.728213589305;3255.7395408367274;3256.5833862269988;3331.9666803332143;3750.8874733757757;3753.305638456894;4902.499725140168;4954.504549545053;5115.2071428928575;5358.54452371545;5701.543876489871;3187.3784612000436;3695.2655035273706;3766.9452553285123;3770.9434358484095;3802.752821066622;3292.7859689120132;3359.732180777965;3751.6248375162268;3842.2507611015585;4013.9197216055513;3724.4413337999517;4018.3168861293652;4108.67130629549;4235.733882372916;4397.428334316518;5073.847784566469;5276.680829792552;5325.733713314319;5336.744585802658;5341.225522299244;1207.3782002268838;1227.8403807504967;1241.5840693367822;1250.6435665835374;1251.7559075980096;784.1022029775875;793.2464159048926;814.543855440955;835.7910522369408;854.769212312672;4309.892252693708;4563.041761230179;4840.83057093;4847.830325938575;4868.537488938545;4875.928599619287;5147.760724085035;5259.684418934876;5268.207849839591;5292.676830792302;3978.3742729798196;4399.912001759947;4743.857684269477;4817.807287708522;4960.950390496121;2389.880505974729;2462.7660372264604;2516.5973444248834;2679.3837417394107;2686.301561594;4960.511846446124;5374.5700343972385;5410.835665733659;5417.51242388187;5429.294191755047;4502.527234640381;4994.950050499521;5205.6356055075785;5224.477552244745;5297;4521.728696278234;4903.681260718042;5360.142377219626;5502.81689436781;5554.527865131509;4703.905921881612;4895.877603059952;5123.948760512347;5134;5203.479652034766;4592.058627981189;4726.905461890674;5103.540681338697;5486.683196033003;5561.193626923992;4711.8350857719815;5583.022970980143;5602.943970560243;5938;5958.433948774859;3508.7017603503987;3688.9077773055888;3759.567649720317;3786.5456145262747;3817.809109544456;4117.649999750055;4823.781060712112;4915.827946021872;5058.898822023612;5547.421912866098;4305.354196870495;4916.434610019751;4965.9571489986065;5474.781008759605;5475.6181061238485;4208.452901122834;4984.22744474607;5108.66793658411;5137.071869034615;5457.69982650958;4254.914901702011;4678.76606169697;5274.762635681327;5292.417834038176;5348.358197016383;4429.247028005182;4781.211100168433;5098.286239926488;5203.869903252449;5270.446603106619;4463.540755914192;5008.373953255843;5207;5313.787448502171;5369.3556773187465 19:10:27 INFO - PROCESS | 1634 | |5;yui.html;706.7208452661199;749.1833901047856;761.029687148885;767.5394763142116;791.244361634639;13566.118202316842;13685.3157342133;13918.373673184706;13951.302434878266;13966.441342346294;12744.617661470147;12899.742005159915;12905.935470322635;12906.483740650363;12912.225266484018;652.7454292825802;672.2672287206943;676.5669971218424;676.5805200775526;822.4818364430415;668.4752469311592;674.4503229867667;806.6450761664878;841.3479553346144;845.1464021338462;874.5321253129576;881.8236352729448;882.5763633455945;884.5577211394303;884.7345796261128;878.0297770963058;881.0396667632248;882.0870399136894;882.7837179890936;883.284539522982;511.70065511675716;527.0171130840993;529.4414392815578;655.2268323378412;658.2200092889941;12090.516379344903;12146.96750776185;12905.67735806603;13418.194908305435;13434.395452204673;13259.3370331484;13667.384967676478;13673.31633418335;13718.45126194951;13742.93128534361;11193.944030279796;11224.270422422516;11226;11245.212835101545;11329.886701132966;3445.0698311455767;3511.6488351164935;3560.359135355661;3766.773993560395;3790.431435284702;83175.58412207961;89618.5519072407;89900.55049724775;90050;90271;10072.586703548837;10406.219533534864;10431.68704938853;10832.404217768099;10924.071453926505;20363.287284944956;20389.388318350473;20565.48586285325;20670.896645516677;20805.687914681293;7515.436342274351;7532.661030253652;7558.735444259424;7652.311291983749;7845.215478452224;4176.77027763476;4180.268453020709;4232.767197804089;4391.538888416734;4424.159409712144;2888.306806366499;3241.546183534307;3349.815760133216;3352.02791190552;3361.7478689098416;609.8810731907236;613.6379536073738;619.5353484886335;632.4213344789558;634.5653227539151;498.7930009046205;554.3818642213903;594.2173479561316;660.4775991340008;675.9087523184405;8697.608607612672;9002.639894404281;9182.63269469227;9227.169554740105;9230.49232292217;618.3445547719432;638.958467699598;653.7842511971038;655.7180412422608;660.2011566005187;3768.2840260350995;4427.977860110679;4481.731096134243;4531.682782205214;4545.749983750926;59850.401495984486;81245.59377203195;110016;115954;119961.40019299848;60519.39480605226;67214.66392668104;67736.32263677398;77235.84146237811;77510;62617;69897.30102698908;71156.28843711497;71312.5737485237;84589.15410845936;7925.502855723864;9226.677066302647;9496.47769372691;9554.140127388291;9633.951830241085;39662;46556.30165547452;47501.52498474971;54250.45749542454;56327.71836140793;72840.27159728335;76388.61805690936;76486;78765;79708;11752.94123529411;12068.939655302018;12143.514259429523;12341.69145771327;12712.555060572835;12792;12799.168054076456;13386.19682819034;13702.38339274755;13969.231692257023;11975;12212;12590.874091258971;12782.552610658582;13574.117682350616;10345;13260.80108798388;13271.66820829487;13363.53227637028;13561.186328820302;10404;12065.155439119528;12446.193580125446;13288.867111328764;13297.66755831112;9603.183729383081;10664.306820056672;11187.664370068911;11297;11535.423228838692;9084.682036128705;9125.543722813965;10137.391756494633;11036.785953544942;11319.603813866475 19:10:27 INFO - PROCESS | 1634 | __end_tp_report 19:10:27 INFO - PROCESS | 1634 | __start_cc_report 19:10:27 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,1446 19:10:27 INFO - PROCESS | 1634 | __end_cc_report 19:10:27 INFO - PROCESS | 1634 | __startTimestamp1479957027429__endTimestamp 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | ------- Summary: start ------- 19:10:27 INFO - PROCESS | 1634 | Number of tests: 6 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5518.77 Median:5683.40 stddev:640.22 (11.3%) stddev-sans-first:610.49 19:10:27 INFO - PROCESS | 1634 | Values: 2982.9 3299.2 3312.9 3312.9 3369.6 5161.6 5459.2 5505.2 5522.2 5561.0 4417.0 5455.1 5482.2 5519.7 5529.0 5010.3 5059.4 5100.6 5103.7 5124.4 5011.8 5029.2 5045.5 5081.6 5161.0 5052.7 5094.0 5112.3 5115.8 5118.5 5117.7 5166.0 5168.9 5170.0 5215.3 4942.4 4966.7 5092.1 5099.6 5122.6 4038.9 5402.5 5425.8 5445.9 5464.8 5926.6 5984.0 6008.4 6012.6 6017.9 5877.2 5966.4 6098.2 6123.1 6130.8 5523.8 5543.9 5569.6 5577.1 5598.4 6163.6 6197.3 6208.2 6213.4 6216.0 5740.1 5853.9 5854.5 5883.9 5888.6 5927.0 5943.6 6002.5 6004.8 6015.8 5890.8 5900.6 5907.8 5908.6 5909.4 5909.6 5915.5 5995.6 6042.8 6078.3 4740.2 4775.8 4782.9 4788.3 4801.2 4202.2 4282.9 4295.9 4301.7 4318.9 4681.4 5541.0 5569.6 5603.4 5644.6 6011.6 6075.6 6112.0 6115.5 6141.3 5603.9 5667.0 5677.0 5686.2 5701.3 5111.5 5159.1 5160.4 5205.2 5210.2 6191.6 6195.6 6225.2 6240.2 6250.5 4492.8 5554.2 6095.4 6116.8 6123.9 6191.4 6195.8 6223.2 6241.2 6247.2 4686.8 5852.2 5887.1 5890.7 5996.4 5875.7 6064.0 6079.5 6079.7 6127.2 5826.1 5840.5 5869.7 5882.5 5887.2 5707.4 5763.6 5770.9 5821.8 5894.5 5622.9 5660.0 5662.0 5681.0 5710.1 5804.4 5807.4 5808.2 5810.3 5816.4 5646.0 5664.7 5683.4 5688.9 5695.7 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:64812.11 Median:36667.72 stddev:146862.99 (400.5%) stddev-sans-first:147195.59 19:10:27 INFO - PROCESS | 1634 | Values: 496.8 503.2 515.4 515.9 531.2 29454.9 31408.5 31462.5 31464.7 31500.5 31821.8 32255.8 39867.8 40661.6 40687.2 9111.5 9262.5 9263.5 9267.0 9274.9 9214.9 9312.5 9317.5 9328.8 9338.3 9331.4 9376.1 9377.1 9378.9 9390.6 9289.8 9343.5 9344.1 9366.7 9379.5 8567.9 9161.6 9249.7 9255.6 9266.6 34906.7 38517.0 38526.0 38614.6 38677.0 3133.0 3290.3 3303.4 3371.2 3599.4 24112.5 28605.9 29141.8 30144.7 30697.4 3159.8 3171.6 3207.6 3209.8 3292.9 461441.0 466452.0 466674.0 467471.7 467956.0 32677.2 33066.0 33420.0 34890.0 60584.4 29354.3 34951.0 35089.6 35096.6 35143.8 14195.6 14345.1 14369.0 14390.2 14407.2 12029.4 12107.9 12110.9 12122.7 12292.6 7662.7 7670.8 7747.0 7772.0 7784.6 3157.1 3196.5 3202.8 3204.9 3236.5 868.2 913.1 993.4 1023.7 1050.6 28181.3 29192.7 29316.3 29751.4 29907.0 716.9 1061.9 1072.7 1129.3 1193.6 14830.1 15163.4 15401.5 15420.7 15487.4 680523.0 688404.0 690310.0 690612.0 691416.0 56723.1 57000.4 57192.4 57225.7 57267.7 1219.9 1267.1 1277.0 1321.2 1395.0 29314.4 30027.7 30298.5 30516.2 30577.0 48161.0 48547.8 48643.8 48671.0 48783.8 453977.0 455802.0 456102.0 456997.0 457317.0 20791.7 26092.2 26610.8 26835.7 27188.2 45087.3 45283.9 45327.1 45552.8 46243.5 35990.0 37387.6 37919.0 37933.4 38318.4 30210.7 35147.3 37651.4 37742.6 40485.6 37345.4 37380.4 37538.4 38230.8 38393.6 32091.8 33807.5 34003.3 34546.8 34710.3 23448.3 23711.9 25271.7 25674.0 25786.5 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:33922.69 Median:386.44 stddev:98672.51 (25533.6%) stddev-sans-first:98917.11 19:10:27 INFO - PROCESS | 1634 | Values: 353.4 359.3 382.0 390.9 412.0 14566.0 14681.0 16096.1 19648.7 20443.9 18681.8 19213.3 19736.0 19901.4 20066.4 5807.4 6038.1 6107.6 6111.2 6112.9 5730.7 5911.4 5970.4 5983.9 5987.3 5698.9 5856.7 5916.2 5924.1 5930.2 5116.7 5859.1 6046.3 6053.0 6067.2 5608.3 5688.1 5784.7 5820.6 5881.6 13105.0 13338.9 13361.8 13868.7 24679.5 2187.3 2291.5 2297.6 2420.3 2535.6 9867.5 9875.0 9930.9 10341.5 10574.6 1489.4 1838.5 1842.4 1880.3 1928.8 105203.0 113431.0 113672.0 113681.0 114780.4 9341.3 11353.1 11476.9 11595.4 11760.5 14607.0 14618.9 14642.3 14646.4 14655.4 6968.9 8382.0 8488.7 8492.8 8619.1 6788.7 8338.7 8364.1 8397.7 8503.6 4383.9 4543.3 4557.6 4565.7 4567.0 1845.6 1908.2 1914.9 1918.0 1920.8 706.3 736.7 755.4 840.2 923.7 19719.7 20060.5 20981.4 21385.6 21434.8 932.3 935.6 948.4 958.8 976.3 8476.8 10287.4 11113.7 11121.6 11167.5 578511.0 591096.0 592509.0 601750.0 602734.0 75283.2 108784.0 117659.4 119784.0 128395.4 926.2 944.9 971.3 972.6 973.8 18343.7 18756.8 19028.5 19066.3 19089.9 53560.2 54685.5 55167.7 56289.0 56613.4 76529.2 78982.6 79515.2 80392.6 84040.2 8046.0 8159.5 8187.0 8271.1 8441.8 11624.6 12783.2 12844.3 13283.1 13296.6 10800.1 10983.9 11150.3 11377.5 11424.6 10826.9 10874.3 11036.4 11167.6 11352.3 10851.1 11001.7 11296.4 11302.3 11951.9 7032.9 7141.7 7523.3 7750.6 8543.1 9681.2 11114.1 11300.3 11617.0 11692.3 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5559.04 Median:5291.55 stddev:3077.06 (58.2%) stddev-sans-first:3070.37 19:10:27 INFO - PROCESS | 1634 | Values: 1473.1 1503.8 1678.2 1780.8 1853.4 7274.3 8385.7 8392.0 8397.0 8402.9 7025.3 7295.1 8201.7 8455.1 8463.7 2328.3 2354.4 2358.3 2359.1 2360.2 2046.0 2337.3 2367.4 2377.1 2381.3 2136.9 2156.1 2272.5 2355.2 2361.2 2312.2 2330.7 2341.0 2343.2 2345.6 2306.0 2366.1 2369.3 2369.6 2374.4 8497.1 8547.2 8564.6 8567.4 8571.6 5152.5 5325.6 5331.8 5332.5 5334.8 3095.8 3361.7 3522.8 3722.4 3729.3 896.9 1014.7 1022.7 1080.5 1114.0 11261.4 11357.0 11368.9 11380.9 11437.9 4103.1 4369.5 4569.7 4712.0 4757.6 9541.4 9672.2 9683.7 9686.2 9690.9 5277.4 5291.4 5291.7 5291.9 5295.6 4765.4 4800.8 4853.5 4865.9 4891.6 3779.7 3827.8 3848.0 3898.4 3929.1 991.6 1270.0 1271.3 1276.8 1285.3 773.6 859.3 867.6 888.7 955.8 5471.5 5525.9 5534.1 5803.8 5895.6 948.7 951.2 965.3 965.4 969.8 3801.6 3897.4 3953.3 4003.6 4012.7 10291.1 10333.3 10528.3 10529.0 10630.7 10094.4 10281.4 10491.6 10563.9 10566.7 7881.4 7921.3 8148.3 8160.5 8160.6 5802.2 5834.2 5885.4 5889.4 5897.8 9299.1 9464.5 9591.5 9770.6 9800.3 9799.9 9864.7 9918.3 9945.1 9946.6 6787.3 6899.9 7257.2 7372.5 7426.6 7805.8 8030.4 8036.3 8044.1 8051.4 6428.9 7070.7 7119.9 7254.8 7394.8 7085.4 7126.4 7141.3 7179.4 7186.2 6680.6 6824.1 6884.6 6891.0 6898.9 4872.6 4888.3 4891.8 4970.1 4992.1 4899.0 4902.3 4932.2 5039.2 5051.1 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4231.11 Median:4707.87 stddev:1284.23 (27.3%) stddev-sans-first:1252.68 19:10:27 INFO - PROCESS | 1634 | Values: 255.4 303.7 311.1 314.8 319.5 4556.8 4609.7 5124.6 5176.7 5222.4 4104.5 4966.6 4991.7 5012.2 5178.2 3940.3 3949.7 3958.7 3960.2 3974.4 3385.9 3890.8 3917.4 3938.7 3940.6 3430.8 3816.9 3859.9 3896.0 3947.0 3657.0 3872.8 3890.7 3925.6 3933.9 3405.9 3411.6 3412.6 3431.0 3432.0 4082.8 4226.8 4945.1 4954.6 5051.9 4020.6 4360.1 4366.0 4453.8 4474.8 4682.6 5167.7 5405.3 5429.6 5435.7 3255.7 3256.6 3332.0 3750.9 3753.3 4902.5 4954.5 5115.2 5358.5 5701.5 3187.4 3695.3 3766.9 3770.9 3802.8 3292.8 3359.7 3751.6 3842.3 4013.9 3724.4 4018.3 4108.7 4235.7 4397.4 5073.8 5276.7 5325.7 5336.7 5341.2 1207.4 1227.8 1241.6 1250.6 1251.8 784.1 793.2 814.5 835.8 854.8 4309.9 4563.0 4840.8 4847.8 4868.5 4875.9 5147.8 5259.7 5268.2 5292.7 3978.4 4399.9 4743.9 4817.8 4961.0 2389.9 2462.8 2516.6 2679.4 2686.3 4960.5 5374.6 5410.8 5417.5 5429.3 4502.5 4995.0 5205.6 5224.5 5297.0 4521.7 4903.7 5360.1 5502.8 5554.5 4703.9 4895.9 5123.9 5134.0 5203.5 4592.1 4726.9 5103.5 5486.7 5561.2 4711.8 5583.0 5602.9 5938.0 5958.4 3508.7 3688.9 3759.6 3786.5 3817.8 4117.6 4823.8 4915.8 5058.9 5547.4 4305.4 4916.4 4966.0 5474.8 5475.6 4208.5 4984.2 5108.7 5137.1 5457.7 4254.9 4678.8 5274.8 5292.4 5348.4 4429.2 4781.2 5098.3 5203.9 5270.4 4463.5 5008.4 5207.0 5313.8 5369.4 19:10:27 INFO - PROCESS | 1634 | 19:10:27 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:18919.18 Median:25551.03 stddev:27043.20 (105.8%) stddev-sans-first:27084.49 19:10:27 INFO - PROCESS | 1634 | Values: 706.7 749.2 761.0 767.5 791.2 13566.1 13685.3 13918.4 13951.3 13966.4 12744.6 12899.7 12905.9 12906.5 12912.2 652.7 672.3 676.6 676.6 822.5 668.5 674.5 806.6 841.3 845.1 874.5 881.8 882.6 884.6 884.7 878.0 881.0 882.1 882.8 883.3 511.7 527.0 529.4 655.2 658.2 12090.5 12147.0 12905.7 13418.2 13434.4 13259.3 13667.4 13673.3 13718.5 13742.9 11193.9 11224.3 11226.0 11245.2 11329.9 3445.1 3511.6 3560.4 3766.8 3790.4 83175.6 89618.6 89900.6 90050.0 90271.0 10072.6 10406.2 10431.7 10832.4 10924.1 20363.3 20389.4 20565.5 20670.9 20805.7 7515.4 7532.7 7558.7 7652.3 7845.2 4176.8 4180.3 4232.8 4391.5 4424.2 2888.3 3241.5 3349.8 3352.0 3361.7 609.9 613.6 619.5 632.4 634.6 498.8 554.4 594.2 660.5 675.9 8697.6 9002.6 9182.6 9227.2 9230.5 618.3 639.0 653.8 655.7 660.2 3768.3 4428.0 4481.7 4531.7 4545.7 59850.4 81245.6 110016.0 115954.0 119961.4 60519.4 67214.7 67736.3 77235.8 77510.0 62617.0 69897.3 71156.3 71312.6 84589.2 7925.5 9226.7 9496.5 9554.1 9634.0 39662.0 46556.3 47501.5 54250.5 56327.7 72840.3 76388.6 76486.0 78765.0 79708.0 11752.9 12068.9 12143.5 12341.7 12712.6 12792.0 12799.2 13386.2 13702.4 13969.2 11975.0 12212.0 12590.9 12782.6 13574.1 10345.0 13260.8 13271.7 13363.5 13561.2 10404.0 12065.2 12446.2 13288.9 13297.7 9603.2 10664.3 11187.7 11297.0 11535.4 9084.7 9125.5 10137.4 11036.8 11319.6 19:10:27 INFO - PROCESS | 1634 | -------- Summary: end -------- 19:10:27 INFO - PROCESS | 1634 | 19:10:28 INFO - TEST-INFO | 1634: exit 0 19:10:28 INFO - TEST-OK | dromaeo_css | took 1119475ms 19:10:28 INFO - TEST-START | kraken 19:10:28 INFO - operating with platform_type : mac_ 19:10:28 INFO - Initialising browser for kraken test... 19:10:28 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmplDGBqJ/profile http://localhost:49235/getInfo.html) 19:10:29 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 19:10:30 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 19:10:30 INFO - PROCESS | 1643 | colorDepth:24 19:10:30 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 19:10:30 INFO - PROCESS | 1643 | __metrics 19:10:30 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:10:30 INFO - PROCESS | 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:10:31 INFO - TEST-INFO | 1643: exit 0 19:10:31 INFO - Browser initialized. 19:10:31 INFO - Running cycle 1/1 for kraken test... 19:10:31 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmplDGBqJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:43 INFO - PROCESS | 1644 | RSS: Main: 223043584 19:10:43 INFO - PROCESS | 1644 | 19:10:46 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 19:10:47 INFO - PROCESS | 1644 | RSS: Main: 274173952 19:10:47 INFO - PROCESS | 1644 | 19:10:50 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 19:10:50 INFO - PROCESS | 1644 | RSS: Main: 283713536 19:10:50 INFO - PROCESS | 1644 | 19:10:53 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 19:10:54 INFO - PROCESS | 1644 | RSS: Main: 380194816 19:10:54 INFO - PROCESS | 1644 | 19:10:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 19:10:57 INFO - PROCESS | 1644 | RSS: Main: 283627520 19:10:57 INFO - PROCESS | 1644 | 19:10:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:11:00 INFO - PROCESS | 1644 | RSS: Main: 284790784 19:11:00 INFO - PROCESS | 1644 | 19:11:02 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 19:11:03 INFO - PROCESS | 1644 | RSS: Main: 324943872 19:11:03 INFO - PROCESS | 1644 | 19:11:06 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 19:11:06 INFO - PROCESS | 1644 | RSS: Main: 324124672 19:11:06 INFO - PROCESS | 1644 | 19:11:09 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 19:11:10 INFO - PROCESS | 1644 | RSS: Main: 321376256 19:11:10 INFO - PROCESS | 1644 | 19:11:11 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:11:12 INFO - PROCESS | 1644 | RSS: Main: 301330432 19:11:12 INFO - PROCESS | 1644 | 19:11:14 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:11:14 INFO - PROCESS | 1644 | RSS: Main: 300228608 19:11:14 INFO - PROCESS | 1644 | 19:11:17 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:11:17 INFO - PROCESS | 1644 | RSS: Main: 303828992 19:11:17 INFO - PROCESS | 1644 | 19:11:20 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:11:21 INFO - PROCESS | 1644 | RSS: Main: 321794048 19:11:21 INFO - PROCESS | 1644 | 19:11:24 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:11:24 INFO - PROCESS | 1644 | RSS: Main: 315273216 19:11:24 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 19:11:26 INFO - PROCESS | 1644 | RSS: Main: 359153664 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | __start_tp_report 19:11:26 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 19:11:26 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 19:11:26 INFO - PROCESS | 1644 | |i|pagename|runs| 19:11:26 INFO - PROCESS | 1644 | |0;ai-astar;115;114;113;113;115;113;114;113;113;112 19:11:26 INFO - PROCESS | 1644 | |1;audio-beat-detection;147;113;111;145;118;142;140;111;143;112 19:11:26 INFO - PROCESS | 1644 | |2;audio-dft;186;186;184;185;183;182;184;184;181;184 19:11:26 INFO - PROCESS | 1644 | |3;audio-fft;78;58;60;95;86;49;58;73;109;83 19:11:26 INFO - PROCESS | 1644 | |4;audio-oscillator;90;70;94;69;67;72;114;97;99;91 19:11:26 INFO - PROCESS | 1644 | |5;imaging-gaussian-blur;87;83;86;83;84;84;86;84;87;85 19:11:26 INFO - PROCESS | 1644 | |6;imaging-darkroom;93;93;94;94;94;93;93;94;93;93 19:11:26 INFO - PROCESS | 1644 | |7;imaging-desaturate;85;83;82;82;84;81;82;85;80;84 19:11:26 INFO - PROCESS | 1644 | |8;json-parse-financial;55;54;52;54;56;68;58;50;56;56 19:11:26 INFO - PROCESS | 1644 | |9;json-stringify-tinderbox;64;72;79;75;72;70;71;69;71;71 19:11:26 INFO - PROCESS | 1644 | |10;stanford-crypto-aes;85;85;91;100;105;105;85;86;83;85 19:11:26 INFO - PROCESS | 1644 | |11;stanford-crypto-ccm;147;146;146;143;146;148;157;160;191;157 19:11:26 INFO - PROCESS | 1644 | |12;stanford-crypto-pbkdf2;167;167;167;168;169;166;166;166;167;165 19:11:26 INFO - PROCESS | 1644 | |13;stanford-crypto-sha256-iterative;66;66;66;65;66;66;66;65;65;65 19:11:26 INFO - PROCESS | 1644 | __end_tp_report 19:11:26 INFO - PROCESS | 1644 | __start_cc_report 19:11:26 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1961 19:11:26 INFO - PROCESS | 1644 | __end_cc_report 19:11:26 INFO - PROCESS | 1644 | __startTimestamp1479957086755__endTimestamp 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | ------- Summary: start ------- 19:11:26 INFO - PROCESS | 1644 | Number of tests: 14 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#0] ai-astar Cycles:10 Average:113.50 Median:113.50 stddev:0.97 (0.9%) stddev-sans-first:0.87 19:11:26 INFO - PROCESS | 1644 | Values: 115.0 114.0 113.0 113.0 115.0 113.0 114.0 113.0 113.0 112.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:141.00 stddev:16.24 (11.5%) stddev-sans-first:15.74 19:11:26 INFO - PROCESS | 1644 | Values: 147.0 113.0 111.0 145.0 118.0 142.0 140.0 111.0 143.0 112.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.60 (0.9%) stddev-sans-first:1.50 19:11:26 INFO - PROCESS | 1644 | Values: 186.0 186.0 184.0 185.0 183.0 182.0 184.0 184.0 181.0 184.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#3] audio-fft Cycles:10 Average:74.90 Median:75.50 stddev:18.95 (25.1%) stddev-sans-first:20.07 19:11:26 INFO - PROCESS | 1644 | Values: 78.0 58.0 60.0 95.0 86.0 49.0 58.0 73.0 109.0 83.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#4] audio-oscillator Cycles:10 Average:86.30 Median:90.50 stddev:15.92 (17.6%) stddev-sans-first:16.83 19:11:26 INFO - PROCESS | 1644 | Values: 90.0 70.0 94.0 69.0 67.0 72.0 114.0 97.0 99.0 91.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:85.50 stddev:1.52 (1.8%) stddev-sans-first:1.41 19:11:26 INFO - PROCESS | 1644 | Values: 87.0 83.0 86.0 83.0 84.0 84.0 86.0 84.0 87.0 85.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 19:11:26 INFO - PROCESS | 1644 | Values: 93.0 93.0 94.0 94.0 94.0 93.0 93.0 94.0 93.0 93.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:83.50 stddev:1.69 (2.0%) stddev-sans-first:1.59 19:11:26 INFO - PROCESS | 1644 | Values: 85.0 83.0 82.0 82.0 84.0 81.0 82.0 85.0 80.0 84.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:4.82 (8.6%) stddev-sans-first:5.10 19:11:26 INFO - PROCESS | 1644 | Values: 55.0 54.0 52.0 54.0 56.0 68.0 58.0 50.0 56.0 56.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:71.50 stddev:3.86 (5.4%) stddev-sans-first:3.03 19:11:26 INFO - PROCESS | 1644 | Values: 64.0 72.0 79.0 75.0 72.0 70.0 71.0 69.0 71.0 71.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:85.00 stddev:8.86 (10.4%) stddev-sans-first:9.12 19:11:26 INFO - PROCESS | 1644 | Values: 85.0 85.0 91.0 100.0 105.0 105.0 85.0 86.0 83.0 85.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:152.50 stddev:14.22 (9.3%) stddev-sans-first:14.85 19:11:26 INFO - PROCESS | 1644 | Values: 147.0 146.0 146.0 143.0 146.0 148.0 157.0 160.0 191.0 157.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 19:11:26 INFO - PROCESS | 1644 | Values: 167.0 167.0 167.0 168.0 169.0 166.0 166.0 166.0 167.0 165.0 19:11:26 INFO - PROCESS | 1644 | 19:11:26 INFO - PROCESS | 1644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 19:11:26 INFO - PROCESS | 1644 | Values: 66.0 66.0 66.0 65.0 66.0 66.0 66.0 65.0 65.0 65.0 19:11:26 INFO - PROCESS | 1644 | -------- Summary: end -------- 19:11:26 INFO - PROCESS | 1644 | 19:11:27 INFO - TEST-INFO | 1644: exit 0 19:11:27 INFO - TEST-OK | kraken | took 59720ms 19:11:27 INFO - SUITE-END | took 1179s 19:11:27 INFO - Completed test suite (00:19:39) 19:11:27 INFO - javascript benchmark 19:11:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2982.8806847726087, 3299.2246821996832, 3312.8840490582834, 3312.9171770705716, 3369.578802649669, 5161.561267292284, 5459.235707001018, 5505.201745746865, 5522.171674248864, 5561.026820306441, 4416.955830441702, 5455.1271796512565, 5482.150266708657, 5519.668819870801, 5528.9723551382285, 5010.323606313146, 5059.4181669107975, 5100.617453690969, 5103.719295438749, 5124.436312005695, 5011.824586139486, 5029.220470827027, 5045.495450454961, 5081.568066714333, 5160.974195129038, 5052.671576347544, 5094.032133894567, 5112.309838171855, 5115.820946266881, 5118.488151184889, 5117.66735162217, 5166.0, 5168.870778230536, 5170.043541944752, 5215.348081489814, 4942.382202224722, 4966.677165984235, 5092.108880945857, 5099.59203263738, 5122.641415100945, 4038.9192216155807, 5402.51377376038, 5425.810096646598, 5445.918311225335, 5464.808731694372, 5926.614770039977, 5984.0, 6008.4291992261105, 6012.639241645472, 6017.879642407127, 5877.206577112078, 5966.403359664042, 6098.207233059718, 6123.142760013601, 6130.754769809202, 5523.7790488380815, 5543.861403464905, 5569.637973531708, 5577.107662773937, 5598.356189038232, 6163.613187032882, 6197.25632924043, 6208.192934918432, 6213.37866213375, 6215.96892015537, 5740.081586946152, 5853.912191317134, 5854.4730974212525, 5883.941160588425, 5888.646681199141, 5927.0, 5943.613665111754, 6002.519798416116, 6004.789832355847, 6015.849603759853, 5890.793822216202, 5900.616459930091, 5907.822765317047, 5908.586398952118, 5909.409059094056, 5909.5863289569315, 5915.497182739429, 5995.640261584319, 6042.848928776728, 6078.300995385499, 4740.19416699162, 4775.832845850378, 4782.880427989258, 4788.257820037899, 4801.207800712913, 4202.222588821078, 4282.914341713148, 4295.871123866289, 4301.720388174758, 4318.892027699333, 4681.391419115494, 5541.030319694038, 5569.582281328917, 5603.3836278009385, 5644.604877658525, 6011.579189456696, 6075.5747097703625, 6111.98679349419, 6115.541334399938, 6141.324454310023, 5603.943960560342, 5666.9716651416475, 5676.978143934049, 5686.175504551817, 5701.344345400249, 5111.539961403491, 5159.071367153873, 5160.380754309507, 5205.167173252262, 5210.218467229945, 6191.597546159485, 6195.597286176384, 6225.221847269091, 6240.157578726814, 6250.468710159687, 4492.77536123186, 5554.1946417770005, 6095.420935011167, 6116.755329786759, 6123.877522449437, 6191.442770150708, 6195.81412557624, 6223.222097237845, 6241.188645476241, 6247.219097612799, 4686.8125274988615, 5852.151438041546, 5887.116932460165, 5890.705464726832, 5996.37038110992, 5875.676837773793, 6064.029755239317, 6079.544034197541, 6079.726412311545, 6127.172831667846, 5826.126081087871, 5840.532757379485, 5869.706514674334, 5882.49998750111, 5887.234659494238, 5707.429257074259, 5763.625364351304, 5770.884582308414, 5821.82534523948, 5894.498967587803, 5622.9156562652415, 5659.971700141473, 5661.971690141523, 5681.0058239808695, 5710.086386178192, 5804.390538993345, 5807.361190269152, 5808.21589085485, 5810.331811841692, 5816.360200377871, 5646.011947909182, 5664.688442135722, 5683.403242659627, 5688.886222275614, 5695.686737229326], "value": 5479.6555072063775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.8434942992959, 503.16725818769936, 515.4433212130899, 515.8676704633331, 531.1554628141255, 29454.852725736368, 31408.52887206691, 31462.52806207903, 31464.685353146462, 31500.527492087607, 31821.84089079557, 32255.83872080636, 39867.800660996654, 40661.59338406615, 40687.18625627493, 9111.45331280122, 9262.490563019031, 9263.490508022056, 9266.98063213046, 9274.907250927505, 9214.907850921505, 9312.534373281344, 9317.534123293808, 9328.81342373152, 9338.299627527962, 9331.440113593173, 9376.062393760603, 9377.1091746284, 9378.906210937872, 9390.624375025025, 9289.767755806126, 9343.486108264044, 9344.112309330603, 9366.672166474209, 9379.484128372937, 8567.871481927776, 9161.587728552231, 9249.67626133089, 9255.629774809, 9266.629334826599, 34906.65093349059, 38517.0, 38526.0, 38614.61385386138, 38677.0, 3132.9843350783326, 3290.2596915694016, 3303.355845610107, 3371.2402594488817, 3599.4420864766003, 24112.51774964491, 28605.856970715013, 29141.83432662691, 30144.69855301441, 30697.386052279053, 3159.8420078996187, 3171.587693599817, 3207.5990501187352, 3209.7753157278767, 3292.901212963615, 461441.0, 466452.0, 466674.0, 467471.66264168464, 467956.0, 32677.18307042343, 33066.0, 33419.997400078035, 34890.0, 60584.394156057875, 29354.26614334616, 34951.0, 35089.649103509146, 35096.64903350985, 35143.824280878434, 14195.574132776033, 14345.139291642326, 14369.0, 14390.208538530273, 14407.207603581903, 12029.398530073462, 12107.939460302641, 12110.878891211152, 12122.69693257658, 12292.631221063382, 7662.731804386931, 7670.7698769037015, 7746.961265193638, 7772.0, 7784.571848548271, 3157.1475701560908, 3196.536502207167, 3202.807831530069, 3204.8718051278156, 3236.465983112807, 868.201254845531, 913.1097180249204, 993.3890657245742, 1023.6877752285476, 1050.5850189175385, 28181.295467613065, 29192.708072919, 29316.267093322836, 29751.40497190044, 29906.953256635912, 716.8566286742672, 1061.9256652034437, 1072.742541789965, 1129.2603344809163, 1193.6240084373394, 14830.110193388215, 15163.393464261526, 15401.537953861402, 15420.656446835246, 15487.380504779683, 680523.0, 688404.0, 690310.0, 690612.0, 691416.0, 56723.149152762744, 57000.42999569951, 57192.42807571871, 57225.71387143037, 57267.71366143143, 1219.9024078073733, 1267.0687045021903, 1277.023077345831, 1321.2402868350737, 1394.9930250348684, 29314.413711726073, 30027.69972300249, 30298.545521816748, 30516.237094072825, 30576.96043514822, 48161.0, 48547.75726121488, 48643.75678121587, 48671.0, 48783.75608122079, 453977.0, 455802.0, 456102.0, 456997.0, 457317.0, 20791.688124678443, 26092.217233483025, 26610.802513886534, 26835.731642683324, 27188.1843544694, 45087.32369014533, 45283.86790330268, 45327.09345813131, 45552.772236138604, 46243.537564623926, 35990.0, 37387.6261237395, 37919.0, 37933.430998535594, 38318.42522362111, 30210.69789302167, 35147.29705405929, 37651.435228472146, 37742.62257377391, 40485.59514404818, 37345.4398184022, 37380.439293410076, 37538.436923446716, 38230.808845955595, 38393.61606384012, 32091.839540802146, 33807.49288760621, 34003.319933601684, 34546.82726586351, 34710.30579388449, 23448.296551103493, 23711.881440592686, 25271.74728252694, 25674.0, 25786.484270314864], "value": 17755.91956402391, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4397979202963, 359.2545468153582, 381.9637134472225, 390.9198614284071, 411.9835206591736, 14566.0, 14681.0, 16096.114713690742, 19648.705269420934, 20443.875586842718, 18681.813181868176, 19213.32753353633, 19736.013199340046, 19901.40295791125, 20066.398008059747, 5807.41925807418, 6038.112558562489, 6107.603005804608, 6111.205543279368, 6112.938870611304, 5730.742116604742, 5911.438413350725, 5970.373110823366, 5983.9102413463615, 5987.311459182184, 5698.943010569903, 5856.677882716449, 5916.171735956969, 5924.08176732605, 5930.169776231331, 5116.667416617908, 5859.0625499920025, 6046.3349031606485, 6053.0, 6067.211262535886, 5608.327000759935, 5688.089905615123, 5784.652920824722, 5820.5634577406445, 5881.617694849821, 13104.951603871667, 13338.933305333509, 13361.795303774865, 13868.653283667989, 24679.5064098717, 2187.3109970359446, 2291.51878105598, 2297.574948634508, 2420.310211589706, 2535.632333311678, 9867.50662466881, 9874.9506252469, 9930.851037234448, 10341.53463094155, 10574.629887953884, 1489.448903905551, 1838.531174550481, 1842.447265820262, 1880.2948894164688, 1928.845692344609, 105203.0, 113431.0, 113672.0, 113681.0, 114780.42609786981, 9341.252699784001, 11353.148513861493, 11476.885231147582, 11595.420228988516, 11760.529578816922, 14607.0, 14618.853811461962, 14642.267886605627, 14646.414143434144, 14655.413783448543, 6968.930310696828, 8382.036065852504, 8488.660453581788, 8492.830143397097, 8619.138086191331, 6788.676208139365, 8338.666453341919, 8364.121767214476, 8397.70608028716, 8503.61733721984, 4383.890402739893, 4543.295789152687, 4557.647397152921, 4565.657575681837, 4567.0, 1845.6216475622466, 1908.2462427341122, 1914.8946807925697, 1917.9712304315447, 1920.817522335376, 706.2795948132979, 736.694271877165, 755.4032314471616, 840.1640367834044, 923.7182659288982, 19719.704204436945, 20060.498487537636, 20981.370558883256, 21385.572288554144, 21434.79466159739, 932.2681694869631, 935.5883411299006, 948.3503799897093, 958.769895225141, 976.3312131190157, 8476.830463390697, 10287.43419111956, 11113.66659000215, 11121.610743624096, 11167.497462614201, 578511.0, 591096.0, 592509.0, 601750.0, 602734.0, 75283.24716752872, 108784.0, 117659.41170294094, 119784.0, 128395.35802321116, 926.2127191886842, 944.9149576538144, 971.2909576009553, 972.6304004478253, 973.7955029443896, 18343.724844127082, 18756.812431876053, 19028.524286892938, 19066.33267835619, 19089.90455047716, 53560.19659705186, 54685.45314546803, 55167.72416137894, 56289.02988395387, 56613.433865660816, 76529.23470765221, 78982.6050869742, 79515.20484795078, 80392.59803700945, 84040.15959840323, 8046.0, 8159.510429374256, 8186.959065204635, 8271.13153118938, 8441.78895527592, 11624.593139240424, 12783.23300601967, 12844.293563854078, 13283.070185086952, 13296.601101966957, 10800.08199303068, 10983.945080274547, 11150.275232109887, 11377.54489820398, 11424.600138995096, 10826.89173108259, 10874.293170943864, 11036.392998385167, 11167.553297867995, 11352.262102963281, 10851.131909447067, 11001.724956876142, 11296.43517824122, 11302.265352752045, 11951.880481195076, 7032.929670703227, 7141.714331426892, 7523.3229009389415, 7750.612469376621, 8543.10297418787, 9681.225501959967, 11114.05530529914, 11300.321980681185, 11617.0, 11692.298462092302], "value": 9266.971135803382, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.0719646622626, 1503.812023497063, 1678.2280151130478, 1780.786305643323, 1853.3976457651268, 7274.27257274273, 8385.66457341707, 8392.0, 8396.99236091669, 8402.915970840297, 7025.26234745352, 7295.124585049788, 8201.7129400471, 8455.069942306342, 8463.746087617366, 2328.2898715891647, 2354.376090336062, 2358.3396648938324, 2359.1389142962867, 2360.1975328388344, 2045.9795402045938, 2337.298810356886, 2367.419982104378, 2377.061060880951, 2381.345130089222, 2136.8504204705678, 2156.137544982004, 2272.488690044735, 2355.187460326185, 2361.2089949866813, 2312.225404489498, 2330.7436182019965, 2341.040173528862, 2343.1798870395396, 2345.6012477878717, 2306.031466783955, 2366.1127077345996, 2369.324742448411, 2369.6445533170163, 2374.4063984004, 8497.107803680587, 8547.230749232598, 8564.571771411405, 8567.443116197428, 8571.571421428904, 5152.458991805881, 5325.573954083664, 5331.840044798662, 5332.520073193431, 5334.759935802898, 3095.8452077396264, 3361.680640339164, 3522.806245656488, 3722.4230244312175, 3729.291434627412, 896.914793094655, 1014.660088870222, 1022.7443139215196, 1080.508368692243, 1113.991837387166, 11261.43692815356, 11357.0, 11368.94315528417, 11380.943095284554, 11437.942810285978, 4103.138340948434, 4369.47566292047, 4569.702969306984, 4712.010477799632, 4757.643176761757, 9541.427514349023, 9672.177864881423, 9683.709488715349, 9686.225101991824, 9690.854637180448, 5277.393099793496, 5291.417944026154, 5291.682499050069, 5291.947080529222, 5295.602829787705, 4765.380500534909, 4800.759962001886, 4853.538913803183, 4865.854024379274, 4891.633127515451, 3779.6787273081545, 3827.8468861245797, 3847.961050516401, 3898.3957486589625, 3929.115948911472, 991.6414512118444, 1269.9619011429672, 1271.3007845685022, 1276.7701813673627, 1285.2866659004342, 773.6209257463802, 859.282499113235, 867.6052396159732, 888.7422647432302, 955.7945041816043, 5471.534919531805, 5525.861853453695, 5534.142207957773, 5803.7968671096305, 5895.587308888423, 948.6774496671165, 951.2437612098398, 965.2663975378622, 965.4014511003082, 969.7963427680126, 3801.562820275704, 3897.395903634941, 3953.327934251127, 4003.619656132663, 4012.7391719538136, 10291.073803357735, 10333.276670632984, 10528.315659482108, 10529.0, 10630.681079567625, 10094.444805535766, 10281.434521101412, 10491.580336786596, 10563.94718026405, 10566.735831604117, 7881.369490440751, 7921.287084162453, 8148.348132149414, 8160.5103693778565, 8160.591970401456, 5802.158686990447, 5834.18321435008, 5885.440883116097, 5889.38161493037, 5897.7935772247765, 9299.116583924788, 9464.526773661428, 9591.520423978634, 9770.56032478526, 9800.264980126376, 9799.85300220483, 9864.704058878246, 9918.305718599917, 9945.104940555384, 9946.552405141641, 6787.321267873173, 6899.896501552581, 7257.201707812242, 7372.520786149098, 7426.5915374654915, 7805.804854878674, 8030.43786934909, 8036.316913062221, 8044.115147333671, 8051.396145289244, 6428.903566446601, 7070.68181931804, 7119.8932016020835, 7254.782356529313, 7394.815129621587, 7085.39774119185, 7126.429885609243, 7141.321574450419, 7179.425645948416, 7186.209516953236, 6680.565763225375, 6824.112865327675, 6884.552504087218, 6890.965545172242, 6898.8620227596175, 4872.634552408513, 4888.266759986031, 4891.828785992473, 4970.130227210296, 4992.051510213048, 4898.951010489849, 4902.313676085422, 4932.161532539405, 5039.193729003342, 5051.141305978066], "value": 4489.271878146762, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.3705116886874, 303.7403020417543, 311.0745532042174, 314.7843727046973, 319.4904127915973, 4556.817727290912, 4609.700369475986, 5124.615653825964, 5176.715280659567, 5222.373315202181, 4104.548499665035, 4966.57784088352, 4991.700497970115, 5012.195609580548, 5178.152016390979, 3940.3104456720102, 3949.723519353646, 3958.742681725686, 3960.2079584083153, 3974.3839704845795, 3385.933430969249, 3890.8443662253476, 3917.3536366499493, 3938.735227244332, 3940.586238444965, 3430.7922092843146, 3816.8816306949634, 3859.902045975191, 3895.961040389602, 3946.9737868154198, 3656.957767036395, 3872.7869967151796, 3890.6692931100893, 3925.6064097245353, 3933.9213215735667, 3405.880794172192, 3411.5906091268957, 3412.6246112927556, 3430.9828450857835, 3431.957984998922, 4082.7958602070075, 4226.774235471709, 4945.134601444769, 4954.628402869763, 5051.898962020739, 4020.618041286073, 4360.062586543908, 4365.956340436618, 4453.755043472608, 4474.798634061475, 4682.648801339913, 5167.741612919376, 5405.324334458192, 5429.56596433224, 5435.728213589305, 3255.7395408367274, 3256.5833862269988, 3331.9666803332143, 3750.8874733757757, 3753.305638456894, 4902.499725140168, 4954.504549545053, 5115.2071428928575, 5358.54452371545, 5701.543876489871, 3187.3784612000436, 3695.2655035273706, 3766.9452553285123, 3770.9434358484095, 3802.752821066622, 3292.7859689120132, 3359.732180777965, 3751.6248375162268, 3842.2507611015585, 4013.9197216055513, 3724.4413337999517, 4018.3168861293652, 4108.67130629549, 4235.733882372916, 4397.428334316518, 5073.847784566469, 5276.680829792552, 5325.733713314319, 5336.744585802658, 5341.225522299244, 1207.3782002268838, 1227.8403807504967, 1241.5840693367822, 1250.6435665835374, 1251.7559075980096, 784.1022029775875, 793.2464159048926, 814.543855440955, 835.7910522369408, 854.769212312672, 4309.892252693708, 4563.041761230179, 4840.83057093, 4847.830325938575, 4868.537488938545, 4875.928599619287, 5147.760724085035, 5259.684418934876, 5268.207849839591, 5292.676830792302, 3978.3742729798196, 4399.912001759947, 4743.857684269477, 4817.807287708522, 4960.950390496121, 2389.880505974729, 2462.7660372264604, 2516.5973444248834, 2679.3837417394107, 2686.301561594, 4960.511846446124, 5374.5700343972385, 5410.835665733659, 5417.51242388187, 5429.294191755047, 4502.527234640381, 4994.950050499521, 5205.6356055075785, 5224.477552244745, 5297.0, 4521.728696278234, 4903.681260718042, 5360.142377219626, 5502.81689436781, 5554.527865131509, 4703.905921881612, 4895.877603059952, 5123.948760512347, 5134.0, 5203.479652034766, 4592.058627981189, 4726.905461890674, 5103.540681338697, 5486.683196033003, 5561.193626923992, 4711.8350857719815, 5583.022970980143, 5602.943970560243, 5938.0, 5958.433948774859, 3508.7017603503987, 3688.9077773055888, 3759.567649720317, 3786.5456145262747, 3817.809109544456, 4117.649999750055, 4823.781060712112, 4915.827946021872, 5058.898822023612, 5547.421912866098, 4305.354196870495, 4916.434610019751, 4965.9571489986065, 5474.781008759605, 5475.6181061238485, 4208.452901122834, 4984.22744474607, 5108.66793658411, 5137.071869034615, 5457.69982650958, 4254.914901702011, 4678.76606169697, 5274.762635681327, 5292.417834038176, 5348.358197016383, 4429.247028005182, 4781.211100168433, 5098.286239926488, 5203.869903252449, 5270.446603106619, 4463.540755914192, 5008.373953255843, 5207.0, 5313.787448502171, 5369.3556773187465], "value": 3825.8737609269083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.7208452661199, 749.1833901047856, 761.029687148885, 767.5394763142116, 791.244361634639, 13566.118202316842, 13685.3157342133, 13918.373673184706, 13951.302434878266, 13966.441342346294, 12744.617661470147, 12899.742005159915, 12905.935470322635, 12906.483740650363, 12912.225266484018, 652.7454292825802, 672.2672287206943, 676.5669971218424, 676.5805200775526, 822.4818364430415, 668.4752469311592, 674.4503229867667, 806.6450761664878, 841.3479553346144, 845.1464021338462, 874.5321253129576, 881.8236352729448, 882.5763633455945, 884.5577211394303, 884.7345796261128, 878.0297770963058, 881.0396667632248, 882.0870399136894, 882.7837179890936, 883.284539522982, 511.70065511675716, 527.0171130840993, 529.4414392815578, 655.2268323378412, 658.2200092889941, 12090.516379344903, 12146.96750776185, 12905.67735806603, 13418.194908305435, 13434.395452204673, 13259.3370331484, 13667.384967676478, 13673.31633418335, 13718.45126194951, 13742.93128534361, 11193.944030279796, 11224.270422422516, 11226.0, 11245.212835101545, 11329.886701132966, 3445.0698311455767, 3511.6488351164935, 3560.359135355661, 3766.773993560395, 3790.431435284702, 83175.58412207961, 89618.5519072407, 89900.55049724775, 90050.0, 90271.0, 10072.586703548837, 10406.219533534864, 10431.68704938853, 10832.404217768099, 10924.071453926505, 20363.287284944956, 20389.388318350473, 20565.48586285325, 20670.896645516677, 20805.687914681293, 7515.436342274351, 7532.661030253652, 7558.735444259424, 7652.311291983749, 7845.215478452224, 4176.77027763476, 4180.268453020709, 4232.767197804089, 4391.538888416734, 4424.159409712144, 2888.306806366499, 3241.546183534307, 3349.815760133216, 3352.02791190552, 3361.7478689098416, 609.8810731907236, 613.6379536073738, 619.5353484886335, 632.4213344789558, 634.5653227539151, 498.7930009046205, 554.3818642213903, 594.2173479561316, 660.4775991340008, 675.9087523184405, 8697.608607612672, 9002.639894404281, 9182.63269469227, 9227.169554740105, 9230.49232292217, 618.3445547719432, 638.958467699598, 653.7842511971038, 655.7180412422608, 660.2011566005187, 3768.2840260350995, 4427.977860110679, 4481.731096134243, 4531.682782205214, 4545.749983750926, 59850.401495984486, 81245.59377203195, 110016.0, 115954.0, 119961.40019299848, 60519.39480605226, 67214.66392668104, 67736.32263677398, 77235.84146237811, 77510.0, 62617.0, 69897.30102698908, 71156.28843711497, 71312.5737485237, 84589.15410845936, 7925.502855723864, 9226.677066302647, 9496.47769372691, 9554.140127388291, 9633.951830241085, 39662.0, 46556.30165547452, 47501.52498474971, 54250.45749542454, 56327.71836140793, 72840.27159728335, 76388.61805690936, 76486.0, 78765.0, 79708.0, 11752.94123529411, 12068.939655302018, 12143.514259429523, 12341.69145771327, 12712.555060572835, 12792.0, 12799.168054076456, 13386.19682819034, 13702.38339274755, 13969.231692257023, 11975.0, 12212.0, 12590.874091258971, 12782.552610658582, 13574.117682350616, 10345.0, 13260.80108798388, 13271.66820829487, 13363.53227637028, 13561.186328820302, 10404.0, 12065.155439119528, 12446.193580125446, 13288.867111328764, 13297.66755831112, 9603.183729383081, 10664.306820056672, 11187.664370068911, 11297.0, 11535.423228838692, 9084.682036128705, 9125.543722813965, 10137.391756494633, 11036.785953544942, 11319.603813866475], "value": 7000.322222391436, "unit": "score"}], "name": "dromaeo_css", "value": 6905.323871680075, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 113.0, 115.0, 113.0, 114.0, 113.0, 113.0, 112.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 111.0, 145.0, 118.0, 142.0, 140.0, 111.0, 143.0, 112.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 185.0, 183.0, 182.0, 184.0, 184.0, 181.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 60.0, 95.0, 86.0, 49.0, 58.0, 73.0, 109.0, 83.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 94.0, 69.0, 67.0, 72.0, 114.0, 97.0, 99.0, 91.0], "value": 86.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 83.0, 84.0, 84.0, 86.0, 84.0, 87.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 82.0, 84.0, 81.0, 82.0, 85.0, 80.0, 84.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 52.0, 54.0, 56.0, 68.0, 58.0, 50.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 79.0, 75.0, 72.0, 70.0, 71.0, 69.0, 71.0, 71.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 91.0, 100.0, 105.0, 105.0, 85.0, 86.0, 83.0, 85.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 143.0, 146.0, 148.0, 157.0, 160.0, 191.0, 157.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 167.0, 168.0, 169.0, 166.0, 166.0, 166.0, 167.0, 165.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1452.6999999999996, "alertThreshold": 2.0}]} 19:11:28 INFO - Return code: 0 19:11:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:11:28 ERROR - # TBPL SUCCESS # 19:11:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:11:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:11:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:11:28 INFO - 2016-11-23 19:11:28.084 screenresolution[1648:10007] starting screenresolution argv=screenresolution get 19:11:28 INFO - 2016-11-23 19:11:28.087 screenresolution[1648:10007] Display 0: 1600x1200x32@60 19:11:28 INFO - 2016-11-23 19:11:28.093 screenresolution[1649:10010] starting screenresolution argv=screenresolution list 19:11:28 INFO - Available Modes on Display 0 19:11:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:11:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:11:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:11:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:11:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:11:28 INFO - Intel Iris: 19:11:28 INFO - Chipset Model: Intel Iris 19:11:28 INFO - Type: GPU 19:11:28 INFO - Bus: Built-In 19:11:28 INFO - VRAM (Dynamic, Max): 1536 MB 19:11:28 INFO - Vendor: Intel (0x8086) 19:11:28 INFO - Device ID: 0x0a2e 19:11:28 INFO - Revision ID: 0x0009 19:11:28 INFO - Displays: 19:11:28 INFO - Display: 19:11:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:11:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:11:28 INFO - Display Serial Number: 1600x1200 60 19:11:28 INFO - Main Display: Yes 19:11:28 INFO - Mirror: Off 19:11:28 INFO - Online: Yes 19:11:28 INFO - Rotation: Supported 19:11:28 INFO - Return code: 0 19:11:28 INFO - Running post-action listener: _resource_record_post_action 19:11:28 INFO - [mozharness: 2016-11-24 03:11:28.332588Z] Finished run-tests step (success) 19:11:28 INFO - Running post-run listener: _resource_record_post_run 19:11:28 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 39158784; Write bytes: 657005056; Read time: 381; Write time: 1730 19:11:28 INFO - TinderboxPrint: CPU usage
53.4% 19:11:28 INFO - TinderboxPrint: I/O read bytes / time
39,158,784 / 381 19:11:28 INFO - TinderboxPrint: I/O write bytes / time
657,005,056 / 1,730 19:11:28 INFO - TinderboxPrint: CPU idle
2,259.1 (46.6%) 19:11:28 INFO - TinderboxPrint: CPU system
967.3 (20.0%) 19:11:28 INFO - TinderboxPrint: CPU user
1,619.2 (33.4%) 19:11:28 INFO - TinderboxPrint: Swap in / out
386,719,744 / 0 19:11:28 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347660800; Write bytes: 344180736; Read time: 26905; Write time: 568 19:11:28 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 34903040; Write bytes: 305500672; Read time: 352; Write time: 1132 19:11:28 INFO - Running post-run listener: _upload_blobber_files 19:11:28 INFO - Blob upload gear active. 19:11:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:11:28 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 19:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:11:29 INFO - (blobuploader) - INFO - Open directory for files ... 19:11:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:11:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:11:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:29 INFO - (blobuploader) - INFO - Done attempting. 19:11:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:11:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:11:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:30 INFO - (blobuploader) - INFO - Done attempting. 19:11:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:11:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:11:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:33 INFO - (blobuploader) - INFO - Done attempting. 19:11:33 INFO - (blobuploader) - INFO - Iteration through files over. 19:11:33 INFO - Return code: 0 19:11:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:11:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:11:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c07ff6c44d2ce029fce6208b43250b9b2d537f0c772364121c2962677ac08f019bb3756f00e91609eabe8b45eb8005bc4ea525fd130b7ed3856feb56f676e587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9932f0c8ce74ff2cc88023d87da4a4208ecea58ddc991a46d216bbb0311f5636ca835d78b0803cbe57dc65e58f3a0dbd62a66891e493189fd8c0bca296f51e13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15e4a5736bdc2265803b299c8fe4ece0efcd57f96cb337124e06eed1b1dca3c27fc789c9ec0cb260040aff44e89e5a2864d0118741bdffc257b25a7e84e3139"} 19:11:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:11:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:11:33 INFO - Contents: 19:11:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c07ff6c44d2ce029fce6208b43250b9b2d537f0c772364121c2962677ac08f019bb3756f00e91609eabe8b45eb8005bc4ea525fd130b7ed3856feb56f676e587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9932f0c8ce74ff2cc88023d87da4a4208ecea58ddc991a46d216bbb0311f5636ca835d78b0803cbe57dc65e58f3a0dbd62a66891e493189fd8c0bca296f51e13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15e4a5736bdc2265803b299c8fe4ece0efcd57f96cb337124e06eed1b1dca3c27fc789c9ec0cb260040aff44e89e5a2864d0118741bdffc257b25a7e84e3139"} 19:11:33 INFO - Running post-run listener: copy_logs_to_upload_dir 19:11:33 INFO - Copying logs to upload dir... 19:11:33 INFO - mkdir: /builds/slave/test/build/upload/logs 19:11:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.910495 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-23 19:11:33.391755) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 19:11:33.395640) ========= 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.UfZjBshVk4/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.oXnQVoY3T8/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c07ff6c44d2ce029fce6208b43250b9b2d537f0c772364121c2962677ac08f019bb3756f00e91609eabe8b45eb8005bc4ea525fd130b7ed3856feb56f676e587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9932f0c8ce74ff2cc88023d87da4a4208ecea58ddc991a46d216bbb0311f5636ca835d78b0803cbe57dc65e58f3a0dbd62a66891e493189fd8c0bca296f51e13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15e4a5736bdc2265803b299c8fe4ece0efcd57f96cb337124e06eed1b1dca3c27fc789c9ec0cb260040aff44e89e5a2864d0118741bdffc257b25a7e84e3139"} build_url:https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/UiQbbcrCR3a8Q8W9DOiw-g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c07ff6c44d2ce029fce6208b43250b9b2d537f0c772364121c2962677ac08f019bb3756f00e91609eabe8b45eb8005bc4ea525fd130b7ed3856feb56f676e587", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9932f0c8ce74ff2cc88023d87da4a4208ecea58ddc991a46d216bbb0311f5636ca835d78b0803cbe57dc65e58f3a0dbd62a66891e493189fd8c0bca296f51e13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15e4a5736bdc2265803b299c8fe4ece0efcd57f96cb337124e06eed1b1dca3c27fc789c9ec0cb260040aff44e89e5a2864d0118741bdffc257b25a7e84e3139"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 19:11:33.432683) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:11:33.433241) ========= 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.UfZjBshVk4/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.oXnQVoY3T8/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005312 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:11:33.484823) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 19:11:33.485264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 19:11:33.485936) ========= ========= Total master_lag: 0.20 =========