builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0214 starttime: 1472660780.97 results: success (0) buildid: 20160831065320 builduid: 62b9b9144133481597d5c5ec067dfedf revision: b38f935eb81130e8a1bb3b559efd8e30af3895a3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:20.974798) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:20.975213) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:20.975517) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:20.999061) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:20.999453) ========= 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.jcex9KQEyN/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.7XkELpSRlZ/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-08-31 09:26:21-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.1M=0.001s 2016-08-31 09:26:21 (13.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:21.103707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:21.104018) ========= 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.jcex9KQEyN/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.7XkELpSRlZ/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.020254 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:21.152514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 09:26:21.152918) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b38f935eb81130e8a1bb3b559efd8e30af3895a3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b38f935eb81130e8a1bb3b559efd8e30af3895a3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jcex9KQEyN/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.7XkELpSRlZ/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-08-31 09:26:21,234 truncating revision to first 12 chars 2016-08-31 09:26:21,234 Setting DEBUG logging. 2016-08-31 09:26:21,234 attempt 1/10 2016-08-31 09:26:21,234 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b38f935eb811?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 09:26:22,095 unpacking tar archive at: mozilla-central-b38f935eb811/testing/mozharness/ program finished with exit code 0 elapsedTime=1.119455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 09:26:22.294601) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:22.294921) ========= script_repo_revision: b38f935eb81130e8a1bb3b559efd8e30af3895a3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:22.295296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:22.295575) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 09:26:22.314268) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-31 09:26:22.314683) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jcex9KQEyN/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.7XkELpSRlZ/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:26:22 INFO - MultiFileLogger online at 20160831 09:26:22 in /builds/slave/test 09:26:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:26:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:22 INFO - {'append_to_log': False, 09:26:22 INFO - 'base_work_dir': '/builds/slave/test', 09:26:22 INFO - 'blob_upload_branch': 'Firefox', 09:26:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:22 INFO - 'branch': 'Firefox', 09:26:22 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:22 INFO - 'config_files': ('talos/mac_config.py',), 09:26:22 INFO - 'default_actions': ('clobber', 09:26:22 INFO - 'read-buildbot-config', 09:26:22 INFO - 'download-and-extract', 09:26:22 INFO - 'populate-webroot', 09:26:22 INFO - 'create-virtualenv', 09:26:22 INFO - 'install', 09:26:22 INFO - 'run-tests'), 09:26:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:22 INFO - 'download_minidump_stackwalk': True, 09:26:22 INFO - 'download_symbols': 'ondemand', 09:26:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:22 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:22 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:22 INFO - 'installer_path': 'installer.exe', 09:26:22 INFO - 'log_level': 'info', 09:26:22 INFO - 'log_name': 'talos', 09:26:22 INFO - 'log_to_console': True, 09:26:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:22 INFO - 'opt_config_files': (), 09:26:22 INFO - 'pip_index': False, 09:26:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:22 INFO - 'cmd': ('bash', 09:26:22 INFO - '-c', 09:26:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:22 INFO - 'enabled': True, 09:26:22 INFO - 'halt_on_failure': False, 09:26:22 INFO - 'name': 'check_screen_resolution'},), 09:26:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:22 INFO - 'cmd': ('bash', 09:26:22 INFO - '-c', 09:26:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:22 INFO - 'enabled': True, 09:26:22 INFO - 'halt_on_failure': False, 09:26:22 INFO - 'name': 'check_screen_resolution'},), 09:26:22 INFO - 'run_cmd_checks_enabled': True, 09:26:22 INFO - 'sps_profile': False, 09:26:22 INFO - 'sps_profile_interval': 0, 09:26:22 INFO - 'suite': 'dromaeojs-e10s', 09:26:22 INFO - 'system_bits': '32', 09:26:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:22 INFO - 'title': 't-yosemite-r7-0214', 09:26:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:22 INFO - 'use_talos_json': True, 09:26:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:22 INFO - 'work_dir': 'build'} 09:26:22 INFO - [mozharness: 2016-08-31 16:26:22.503262Z] Running clobber step. 09:26:22 INFO - Running pre-action listener: _resource_record_pre_action 09:26:22 INFO - Running main action method: clobber 09:26:22 INFO - rmtree: /builds/slave/test/build 09:26:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:23 INFO - Running post-action listener: _resource_record_post_action 09:26:23 INFO - [mozharness: 2016-08-31 16:26:23.440694Z] Finished clobber step (success) 09:26:23 INFO - [mozharness: 2016-08-31 16:26:23.440965Z] Running read-buildbot-config step. 09:26:23 INFO - Running pre-action listener: _resource_record_pre_action 09:26:23 INFO - Running main action method: read_buildbot_config 09:26:23 INFO - Using buildbot properties: 09:26:23 INFO - { 09:26:23 INFO - "project": "", 09:26:23 INFO - "product": "firefox", 09:26:23 INFO - "script_repo_revision": "production", 09:26:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:26:23 INFO - "repository": "", 09:26:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:26:23 INFO - "buildid": "20160831065320", 09:26:23 INFO - "pgo_build": "False", 09:26:23 INFO - "basedir": "/builds/slave/test", 09:26:23 INFO - "buildnumber": 287, 09:26:23 INFO - "slavename": "t-yosemite-r7-0214", 09:26:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:26:23 INFO - "platform": "yosemite_r7", 09:26:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:26:23 INFO - "branch": "mozilla-central", 09:26:23 INFO - "repo_path": "mozilla-central", 09:26:23 INFO - "revision": "b38f935eb81130e8a1bb3b559efd8e30af3895a3", 09:26:23 INFO - "stage_platform": "macosx64", 09:26:23 INFO - "builduid": "62b9b9144133481597d5c5ec067dfedf", 09:26:23 INFO - "slavebuilddir": "test" 09:26:23 INFO - } 09:26:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:26:23 INFO - Running post-action listener: _resource_record_post_action 09:26:23 INFO - [mozharness: 2016-08-31 16:26:23.446831Z] Finished read-buildbot-config step (success) 09:26:23 INFO - [mozharness: 2016-08-31 16:26:23.446980Z] Running download-and-extract step. 09:26:23 INFO - Running pre-action listener: _resource_record_pre_action 09:26:23 INFO - Running main action method: download_and_extract 09:26:23 INFO - mkdir: /builds/slave/test/build/tests 09:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:23 INFO - trying https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:26:23 INFO - Downloading https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:26:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:26:24 INFO - Downloaded 1199 bytes. 09:26:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:26:24 INFO - Using the following test package requirements: 09:26:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:26:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:26:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:26:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'jsshell-mac.zip'], 09:26:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:26:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:26:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:26:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:26:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:26:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:26:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:26:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:24 INFO - trying https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:26:24 INFO - Downloading https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:26:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:25 INFO - Downloaded 16396887 bytes. 09:26:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:26:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:32 INFO - trying https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:26:32 INFO - Downloading https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:26:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:33 INFO - Downloaded 16396887 bytes. 09:26:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:39 INFO - trying https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:26:39 INFO - Downloading https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:26:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:26:40 INFO - Downloaded 14413998 bytes. 09:26:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:41 INFO - trying https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:26:41 INFO - Downloading https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:26:43 INFO - Downloaded 111580576 bytes. 09:26:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:26:43 INFO - mkdir: /builds/slave/test/properties 09:26:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:43 INFO - Contents: 09:26:43 INFO - build_url:https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:26:44 INFO - Running post-action listener: _resource_record_post_action 09:26:44 INFO - Running post-action listener: set_extra_try_arguments 09:26:44 INFO - [mozharness: 2016-08-31 16:26:44.576146Z] Finished download-and-extract step (success) 09:26:44 INFO - [mozharness: 2016-08-31 16:26:44.576263Z] Running populate-webroot step. 09:26:44 INFO - Running pre-action listener: _resource_record_pre_action 09:26:44 INFO - Running main action method: populate_webroot 09:26:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:44 INFO - '--tppagecycles', 09:26:44 INFO - '7'], 09:26:44 INFO - 'tests': ['tsvgm']}}, 09:26:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['damp', 'tps']}, 09:26:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'tests': ['damp', 'tps']}, 09:26:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['dromaeo_dom']}, 09:26:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['basic_compositor_video']}, 09:26:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:26:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['a11yr', 09:26:44 INFO - 'ts_paint', 09:26:44 INFO - 'tpaint', 09:26:44 INFO - 'sessionrestore', 09:26:44 INFO - 'sessionrestore_no_auto_restore', 09:26:44 INFO - 'tabpaint']}, 09:26:44 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:44 INFO - 'ts_paint', 09:26:44 INFO - 'tpaint', 09:26:44 INFO - 'sessionrestore', 09:26:44 INFO - 'sessionrestore_no_auto_restore', 09:26:44 INFO - 'tabpaint']}, 09:26:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:44 INFO - 'ts_paint', 09:26:44 INFO - 'tpaint', 09:26:44 INFO - 'sessionrestore', 09:26:44 INFO - 'sessionrestore_no_auto_restore', 09:26:44 INFO - 'tabpaint']}, 09:26:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:44 INFO - 'ts_paint', 09:26:44 INFO - 'tpaint', 09:26:44 INFO - 'sessionrestore', 09:26:44 INFO - 'sessionrestore_no_auto_restore', 09:26:44 INFO - 'tabpaint']}, 09:26:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['a11yr', 09:26:44 INFO - 'ts_paint', 09:26:44 INFO - 'tpaint', 09:26:44 INFO - 'sessionrestore', 09:26:44 INFO - 'sessionrestore_no_auto_restore', 09:26:44 INFO - 'tabpaint']}, 09:26:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['a11yr', 09:26:44 INFO - 'ts_paint', 09:26:44 INFO - 'tpaint', 09:26:44 INFO - 'sessionrestore', 09:26:44 INFO - 'sessionrestore_no_auto_restore', 09:26:44 INFO - 'tabpaint']}, 09:26:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['tsvgx', 09:26:44 INFO - 'tsvgr_opacity', 09:26:44 INFO - 'tart', 09:26:44 INFO - 'tscrollx', 09:26:44 INFO - 'cart']}, 09:26:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:44 INFO - 'tsvgr_opacity', 09:26:44 INFO - 'tart', 09:26:44 INFO - 'tscrollx', 09:26:44 INFO - 'cart']}, 09:26:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'talos_options': ['--disable-e10s'], 09:26:44 INFO - 'tests': ['tp5o']}, 09:26:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'tests': ['tp5o']}, 09:26:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'talos_options': ['--disable-e10s', 09:26:44 INFO - '--xperf_path', 09:26:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:44 INFO - 'tests': ['tp5n']}, 09:26:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:44 INFO - 'talos_options': ['--xperf_path', 09:26:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:44 INFO - 'tests': ['tp5n']}}, 09:26:44 INFO - 'talos.zip': {'path': '', 09:26:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:44 INFO - Running post-action listener: _resource_record_post_action 09:26:44 INFO - [mozharness: 2016-08-31 16:26:44.595383Z] Finished populate-webroot step (success) 09:26:44 INFO - [mozharness: 2016-08-31 16:26:44.595514Z] Running create-virtualenv step. 09:26:44 INFO - Running pre-action listener: _resource_record_pre_action 09:26:44 INFO - Running main action method: create_virtualenv 09:26:44 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:26:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:44 INFO - Using real prefix '/tools/python27' 09:26:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:48 INFO - Installing pip.................done. 09:26:48 INFO - Return code: 0 09:26:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:48 INFO - 'HOME': '/Users/cltbld', 09:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:48 INFO - 'LOGNAME': 'cltbld', 09:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:48 INFO - 'MOZ_NO_REMOTE': '1', 09:26:48 INFO - 'NO_EM_RESTART': '1', 09:26:48 INFO - 'PAGER': '/bin/cat', 09:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:48 INFO - 'PWD': '/builds/slave/test', 09:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:48 INFO - 'SHELL': '/bin/bash', 09:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:26:48 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:48 INFO - 'USER': 'cltbld', 09:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:48 INFO - 'XPC_FLAGS': '0x0', 09:26:48 INFO - 'XPC_SERVICE_NAME': '0', 09:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:26:48 INFO - Cleaning up... 09:26:48 INFO - Return code: 0 09:26:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:48 INFO - 'HOME': '/Users/cltbld', 09:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:48 INFO - 'LOGNAME': 'cltbld', 09:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:48 INFO - 'MOZ_NO_REMOTE': '1', 09:26:48 INFO - 'NO_EM_RESTART': '1', 09:26:48 INFO - 'PAGER': '/bin/cat', 09:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:48 INFO - 'PWD': '/builds/slave/test', 09:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:48 INFO - 'SHELL': '/bin/bash', 09:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:26:48 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:48 INFO - 'USER': 'cltbld', 09:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:48 INFO - 'XPC_FLAGS': '0x0', 09:26:48 INFO - 'XPC_SERVICE_NAME': '0', 09:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:48 INFO - Downloading/unpacking psutil>=3.1.1 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:52 INFO - Installing collected packages: psutil 09:26:52 INFO - Running setup.py install for psutil 09:26:52 INFO - building 'psutil._psutil_osx' extension 09:26:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:26: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 09:26: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 09:26: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 09:26:52 INFO - building 'psutil._psutil_posix' extension 09:26: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 09:26:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:52 INFO - ^ 09:26:52 INFO - 1 warning generated. 09:26: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 09:26:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:52 INFO - Successfully installed psutil 09:26:52 INFO - Cleaning up... 09:26:53 INFO - Return code: 0 09:26:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:26:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:26:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:26:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:53 INFO - 'HOME': '/Users/cltbld', 09:26:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:53 INFO - 'LOGNAME': 'cltbld', 09:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:53 INFO - 'MOZ_NO_REMOTE': '1', 09:26:53 INFO - 'NO_EM_RESTART': '1', 09:26:53 INFO - 'PAGER': '/bin/cat', 09:26:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:53 INFO - 'PWD': '/builds/slave/test', 09:26:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:53 INFO - 'SHELL': '/bin/bash', 09:26:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:26:53 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:26:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:53 INFO - 'USER': 'cltbld', 09:26:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:53 INFO - 'XPC_FLAGS': '0x0', 09:26:53 INFO - 'XPC_SERVICE_NAME': '0', 09:26:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:55 INFO - Installing collected packages: mozsystemmonitor 09:26:55 INFO - Running setup.py install for mozsystemmonitor 09:26:55 INFO - Successfully installed mozsystemmonitor 09:26:55 INFO - Cleaning up... 09:26:56 INFO - Return code: 0 09:26:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26: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 09:26: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 09:26:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:26:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:56 INFO - 'HOME': '/Users/cltbld', 09:26:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:56 INFO - 'LOGNAME': 'cltbld', 09:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:56 INFO - 'MOZ_NO_REMOTE': '1', 09:26:56 INFO - 'NO_EM_RESTART': '1', 09:26:56 INFO - 'PAGER': '/bin/cat', 09:26:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:56 INFO - 'PWD': '/builds/slave/test', 09:26:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:56 INFO - 'SHELL': '/bin/bash', 09:26:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:26:56 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:26:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:56 INFO - 'USER': 'cltbld', 09:26:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:56 INFO - 'XPC_FLAGS': '0x0', 09:26:56 INFO - 'XPC_SERVICE_NAME': '0', 09:26:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26: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 09:26:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:59 INFO - warning: no files found matching '*.txt' 09:26:59 INFO - no previously-included directories found matching 'build' 09:26:59 INFO - no previously-included directories found matching 'dist' 09:26:59 INFO - no previously-included directories found matching '.git*' 09:26:59 INFO - Installing collected packages: jsonschema, functools32 09:26:59 INFO - Running setup.py install for functools32 09:26:59 INFO - warning: no files found matching '*.txt' 09:26:59 INFO - no previously-included directories found matching 'build' 09:26:59 INFO - no previously-included directories found matching 'dist' 09:26:59 INFO - no previously-included directories found matching '.git*' 09:26:59 INFO - Successfully installed jsonschema functools32 09:26:59 INFO - Cleaning up... 09:26:59 INFO - Return code: 0 09:26:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26: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 09:26: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 09:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:59 INFO - 'HOME': '/Users/cltbld', 09:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:59 INFO - 'LOGNAME': 'cltbld', 09:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:59 INFO - 'MOZ_NO_REMOTE': '1', 09:26:59 INFO - 'NO_EM_RESTART': '1', 09:26:59 INFO - 'PAGER': '/bin/cat', 09:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:59 INFO - 'PWD': '/builds/slave/test', 09:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:59 INFO - 'SHELL': '/bin/bash', 09:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:26:59 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:59 INFO - 'USER': 'cltbld', 09:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:59 INFO - 'XPC_FLAGS': '0x0', 09:26:59 INFO - 'XPC_SERVICE_NAME': '0', 09:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:26:59 INFO - Cleaning up... 09:26:59 INFO - Return code: 0 09:26:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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')]} 09:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26: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', '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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:26: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:26: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 blobuploader==1.2.4 09:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:59 INFO - 'HOME': '/Users/cltbld', 09:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:59 INFO - 'LOGNAME': 'cltbld', 09:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:59 INFO - 'MOZ_NO_REMOTE': '1', 09:26:59 INFO - 'NO_EM_RESTART': '1', 09:26:59 INFO - 'PAGER': '/bin/cat', 09:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:59 INFO - 'PWD': '/builds/slave/test', 09:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:59 INFO - 'SHELL': '/bin/bash', 09:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:26:59 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:59 INFO - 'USER': 'cltbld', 09:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:59 INFO - 'XPC_FLAGS': '0x0', 09:26:59 INFO - 'XPC_SERVICE_NAME': '0', 09:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:03 INFO - Downloading docopt-0.6.1.tar.gz 09:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:03 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:03 INFO - Running setup.py install for blobuploader 09:27:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:04 INFO - Running setup.py install for requests 09:27:04 INFO - Running setup.py install for docopt 09:27:04 INFO - Successfully installed blobuploader requests docopt 09:27:04 INFO - Cleaning up... 09:27:04 INFO - Return code: 0 09:27:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:04 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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:04 INFO - 'HOME': '/Users/cltbld', 09:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:04 INFO - 'LOGNAME': 'cltbld', 09:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:04 INFO - 'MOZ_NO_REMOTE': '1', 09:27:04 INFO - 'NO_EM_RESTART': '1', 09:27:04 INFO - 'PAGER': '/bin/cat', 09:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:04 INFO - 'PWD': '/builds/slave/test', 09:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:04 INFO - 'SHELL': '/bin/bash', 09:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:27:04 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:04 INFO - 'USER': 'cltbld', 09:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:04 INFO - 'XPC_FLAGS': '0x0', 09:27:04 INFO - 'XPC_SERVICE_NAME': '0', 09:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-davmKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-w3u2UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-cvQVz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-RS1YxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-M3FL6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-ZkIW3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-BeEB9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-ZKxbes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-sy9uAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:05 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-vEZ2CQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:06 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-eyKoAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:06 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-HCZUPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:06 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-IKzudq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:06 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-8FmK1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:06 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-wEmWNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:06 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-EDqEii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:06 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-mXDyGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:06 INFO - Running setup.py install for manifestparser 09:27:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozcrash 09:27:07 INFO - Running setup.py install for mozdebug 09:27:07 INFO - Running setup.py install for mozdevice 09:27:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozfile 09:27:07 INFO - Running setup.py install for mozhttpd 09:27:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozinfo 09:27:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:07 INFO - Running setup.py install for mozInstall 09:27:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for mozleak 09:27:08 INFO - Running setup.py install for mozlog 09:27:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for moznetwork 09:27:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for mozprocess 09:27:08 INFO - Running setup.py install for mozprofile 09:27:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:08 INFO - Running setup.py install for mozrunner 09:27:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:09 INFO - Running setup.py install for mozscreenshot 09:27:09 INFO - Running setup.py install for moztest 09:27:09 INFO - Running setup.py install for mozversion 09:27:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:09 INFO - Cleaning up... 09:27:09 INFO - Return code: 0 09:27:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27: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 09:27: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 09:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:09 INFO - 'HOME': '/Users/cltbld', 09:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:09 INFO - 'LOGNAME': 'cltbld', 09:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:09 INFO - 'MOZ_NO_REMOTE': '1', 09:27:09 INFO - 'NO_EM_RESTART': '1', 09:27:09 INFO - 'PAGER': '/bin/cat', 09:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:09 INFO - 'PWD': '/builds/slave/test', 09:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:09 INFO - 'SHELL': '/bin/bash', 09:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:27:09 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:09 INFO - 'USER': 'cltbld', 09:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:09 INFO - 'XPC_FLAGS': '0x0', 09:27:09 INFO - 'XPC_SERVICE_NAME': '0', 09:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-zVDa9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-mb8PrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:09 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-oUtTRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27: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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-hbhHau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27: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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-mYWTCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27: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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-wDJMAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27: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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-NmSJtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27: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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-oVNXFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27: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)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-eFrD0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Esyn5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-AV7A0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:10 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-BNnp4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27: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)) 09:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:11 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-RcKAt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27: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)) 09:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:11 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-Ddl19J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:11 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-tG8Ppf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27: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)) 09:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:11 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-BlY7cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27: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)) 09:27:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:11 INFO - Running setup.py (path:/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/pip-bbrlyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27: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)) 09:27: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27: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)) 09:27: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)) 09:27: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)) 09:27:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:13 INFO - Downloading blessings-1.6.tar.gz 09:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:14 INFO - Installing collected packages: blessings 09:27:14 INFO - Running setup.py install for blessings 09:27:14 INFO - Successfully installed blessings 09:27:14 INFO - Cleaning up... 09:27:14 INFO - Return code: 0 09:27:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:14 INFO - Reading from file tmpfile_stdout 09:27:14 INFO - Current package versions: 09:27:14 INFO - blessings == 1.6 09:27:14 INFO - blobuploader == 1.2.4 09:27:14 INFO - docopt == 0.6.1 09:27:14 INFO - functools32 == 3.2.3-2 09:27:14 INFO - jsonschema == 2.5.1 09:27:14 INFO - manifestparser == 1.1 09:27:14 INFO - mozInstall == 1.12 09:27:14 INFO - mozcrash == 0.17 09:27:14 INFO - mozdebug == 0.1 09:27:14 INFO - mozdevice == 0.48 09:27:14 INFO - mozfile == 1.2 09:27:14 INFO - mozhttpd == 0.7 09:27:14 INFO - mozinfo == 0.9 09:27:14 INFO - mozleak == 0.1 09:27:14 INFO - mozlog == 3.3 09:27:14 INFO - moznetwork == 0.27 09:27:14 INFO - mozprocess == 0.23 09:27:14 INFO - mozprofile == 0.28 09:27:14 INFO - mozrunner == 6.12 09:27:14 INFO - mozscreenshot == 0.1 09:27:14 INFO - mozsystemmonitor == 0.3 09:27:14 INFO - moztest == 0.7 09:27:14 INFO - mozversion == 1.4 09:27:14 INFO - psutil == 3.1.1 09:27:14 INFO - requests == 1.2.3 09:27:14 INFO - wsgiref == 0.1.2 09:27:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:14 INFO - 'HOME': '/Users/cltbld', 09:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:14 INFO - 'LOGNAME': 'cltbld', 09:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:14 INFO - 'MOZ_NO_REMOTE': '1', 09:27:14 INFO - 'NO_EM_RESTART': '1', 09:27:14 INFO - 'PAGER': '/bin/cat', 09:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:14 INFO - 'PWD': '/builds/slave/test', 09:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:14 INFO - 'SHELL': '/bin/bash', 09:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:27:14 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:14 INFO - 'USER': 'cltbld', 09:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:14 INFO - 'XPC_FLAGS': '0x0', 09:27:14 INFO - 'XPC_SERVICE_NAME': '0', 09:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:27:14 INFO - Cleaning up... 09:27:14 INFO - Return code: 0 09:27:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:14 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 0x100829a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008be618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde0a49f8a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, '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.jcex9KQEyN/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.7XkELpSRlZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:27:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:14 INFO - 'HOME': '/Users/cltbld', 09:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:14 INFO - 'LOGNAME': 'cltbld', 09:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:14 INFO - 'MOZ_NO_REMOTE': '1', 09:27:14 INFO - 'NO_EM_RESTART': '1', 09:27:14 INFO - 'PAGER': '/bin/cat', 09:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:14 INFO - 'PWD': '/builds/slave/test', 09:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:14 INFO - 'SHELL': '/bin/bash', 09:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:27:14 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:14 INFO - 'USER': 'cltbld', 09:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:14 INFO - 'XPC_FLAGS': '0x0', 09:27:14 INFO - 'XPC_SERVICE_NAME': '0', 09:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:15 INFO - Cleaning up... 09:27:15 INFO - Return code: 0 09:27:15 INFO - Running post-action listener: _resource_record_post_action 09:27:15 INFO - Running post-action listener: _start_resource_monitoring 09:27:15 INFO - Starting resource monitoring. 09:27:15 INFO - [mozharness: 2016-08-31 16:27:15.222850Z] Finished create-virtualenv step (success) 09:27:15 INFO - [mozharness: 2016-08-31 16:27:15.223875Z] Running install step. 09:27:15 INFO - Running pre-action listener: _resource_record_pre_action 09:27:15 INFO - Running main action method: install 09:27:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:15 INFO - Reading from file tmpfile_stdout 09:27:15 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:15 INFO - Reading from file tmpfile_stdout 09:27:15 INFO - Output received: 09:27:15 INFO - Usage: mozinstall [options] installer 09:27:15 INFO - Options: 09:27:15 INFO - -h, --help show this help message and exit 09:27:15 INFO - -d DEST, --destination=DEST 09:27:15 INFO - Directory to install application into. [default: 09:27:15 INFO - "/builds/slave/test"] 09:27:15 INFO - --app=APP Application being installed. [default: firefox] 09:27:15 INFO - mkdir: /builds/slave/test/build/application 09:27:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:27:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:27:49 INFO - Reading from file tmpfile_stdout 09:27:49 INFO - Output received: 09:27:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:27:49 INFO - Running post-action listener: _resource_record_post_action 09:27:49 INFO - [mozharness: 2016-08-31 16:27:49.539266Z] Finished install step (success) 09:27:49 INFO - [mozharness: 2016-08-31 16:27:49.539448Z] Running run-tests step. 09:27:49 INFO - Running pre-action listener: _resource_record_pre_action 09:27:49 INFO - Running main action method: run_tests 09:27:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:49 INFO - 2016-08-31 09:27:49.554 screenresolution[1910:11174] starting screenresolution argv=screenresolution get 09:27:49 INFO - 2016-08-31 09:27:49.556 screenresolution[1910:11174] Display 0: 1600x1200x32@60 09:27:49 INFO - 2016-08-31 09:27:49.562 screenresolution[1911:11176] starting screenresolution argv=screenresolution list 09:27:49 INFO - Available Modes on Display 0 09:27:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:49 INFO - Intel Iris: 09:27:49 INFO - Chipset Model: Intel Iris 09:27:49 INFO - Type: GPU 09:27:49 INFO - Bus: Built-In 09:27:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:49 INFO - Vendor: Intel (0x8086) 09:27:49 INFO - Device ID: 0x0a2e 09:27:49 INFO - Revision ID: 0x0009 09:27:49 INFO - Displays: 09:27:49 INFO - Display: 09:27:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:49 INFO - Display Serial Number: 1600x1200 60 09:27:49 INFO - Main Display: Yes 09:27:49 INFO - Mirror: Off 09:27:49 INFO - Online: Yes 09:27:49 INFO - Rotation: Supported 09:27:49 INFO - Return code: 0 09:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:49 INFO - Python 2.7.3 09:27:49 INFO - Return code: 0 09:27:49 INFO - grabbing minidump binary from tooltool 09:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:27:49 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10078dd30>, '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 0x1004f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100785b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:27:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:27:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:27:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:49 INFO - Return code: 0 09:27:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:27:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:27:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0214 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jcex9KQEyN/Render', 09:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:49 INFO - 'HOME': '/Users/cltbld', 09:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:49 INFO - 'LOGNAME': 'cltbld', 09:27:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:27:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:49 INFO - 'MOZ_NO_REMOTE': '1', 09:27:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:27:49 INFO - 'NO_EM_RESTART': '1', 09:27:49 INFO - 'PAGER': '/bin/cat', 09:27:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:49 INFO - 'PWD': '/builds/slave/test', 09:27:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:49 INFO - 'SHELL': '/bin/bash', 09:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7XkELpSRlZ/Listeners', 09:27:49 INFO - 'TMPDIR': '/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/', 09:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:49 INFO - 'USER': 'cltbld', 09:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:49 INFO - 'XPC_FLAGS': '0x0', 09:27:49 INFO - 'XPC_SERVICE_NAME': '0', 09:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0214', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:27:50 INFO - mozversion application_buildid: 20160831065320 09:27:50 INFO - mozversion application_changeset: b38f935eb81130e8a1bb3b559efd8e30af3895a3 09:27:50 INFO - mozversion application_display_name: Nightly 09:27:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:27:50 INFO - mozversion application_name: Firefox 09:27:50 INFO - mozversion application_remotingname: firefox 09:27:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:27:50 INFO - mozversion application_vendor: Mozilla 09:27:50 INFO - mozversion application_version: 51.0a1 09:27:50 INFO - mozversion platform_buildid: 20160831065320 09:27:50 INFO - mozversion platform_changeset: b38f935eb81130e8a1bb3b559efd8e30af3895a3 09:27:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:27:50 INFO - mozversion platform_version: 51.0a1 09:27:50 INFO - using testdate: 1472660870 09:27:50 INFO - actual date: 1472660870 09:27:50 INFO - starting webserver on 'localhost:49352' 09:27:50 INFO - SUITE-START | Running 2 tests 09:27:50 INFO - TEST-START | dromaeo_css 09:27:50 INFO - operating with platform_type : mac_ 09:27:50 INFO - Initialising browser for dromaeo_css test... 09:27:50 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpDxvZKl/profile http://localhost:49352/getInfo.html) 09:27:53 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 09:27:53 INFO - PROCESS | 1921 | colorDepth:24 09:27:53 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 09:27:53 INFO - PROCESS | 1921 | __metrics 09:27:53 ERROR - PROCESS | 1921 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 09:27:53 INFO - TEST-INFO | 1921: exit 0 09:27:53 INFO - Browser initialized. 09:27:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:53 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpDxvZKl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:05 INFO - PROCESS | 1924 | RSS: Main: 244228096 09:28:05 INFO - PROCESS | 1924 | 09:28:55 INFO - PROCESS | 1924 | 1472660935303 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] 09:31:07 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49352/tests/dromaeo/cssquery-ext.html) 09:31:08 INFO - PROCESS | 1924 | RSS: Main: 265641984 09:31:08 INFO - PROCESS | 1924 | 09:34:11 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-ext.html (next: http://localhost:49352/tests/dromaeo/cssquery-jquery.html) 09:34:11 INFO - PROCESS | 1924 | RSS: Main: 270700544 09:34:11 INFO - PROCESS | 1924 | 09:37:13 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49352/tests/dromaeo/cssquery-mootools.html) 09:37:14 INFO - PROCESS | 1924 | RSS: Main: 275931136 09:37:14 INFO - PROCESS | 1924 | 09:40:16 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49352/tests/dromaeo/cssquery-prototype.html) 09:40:16 INFO - PROCESS | 1924 | RSS: Main: 271245312 09:40:16 INFO - PROCESS | 1924 | 09:43:19 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49352/tests/dromaeo/cssquery-yui.html) 09:43:20 INFO - PROCESS | 1924 | RSS: Main: 276570112 09:43:20 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49352/tests/dromaeo/cssquery-yui.html (next: http://localhost:49352/tests/dromaeo/cssquery-dojo.html) 09:46:23 INFO - PROCESS | 1924 | RSS: Main: 279359488 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | __start_tp_report 09:46:23 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 09:46:23 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 09:46:23 INFO - PROCESS | 1924 | |i|pagename|runs| 09:46:23 INFO - PROCESS | 1924 | |0;dojo.html;2963.362876981449;3373.9325213495745;3427.383071047212;3437.9484307735374;3441.724662027038;5674.517665998395;5786.623869448483;5790.478856902878;5821.185034095225;5824.271966004249;5774.364819869811;5790.247267855183;5794.362620111784;5804.622699524528;5946.672932988684;5210.322658054448;5302.946970530284;5334.5732341412595;5385.649932754378;5387.515123638872;5393.164059570773;5409;5417.756200970946;5473.452654734535;5481.451854814526;5161.793528258865;5183.2743415921805;5207.140821764404;5341.492558206984;5349.277847490597;5260.473952604709;5288.894222115575;5289.285946397225;5299.708516031616;5334.973325133349;5230.607704422146;5295.867603309909;5328.733563321818;5341.252224688546;5351.7056561889085;5695.572832037614;5778.248827652378;5790.7973220937065;5824.417558244184;5903.616264942808;6251.593646413014;6252.531060170505;6276.717547710364;6282.748690052393;6292.5595208335435;6249.812505624838;6265.812025639192;6268.373162683741;6286.968565157191;6300.747970081237;5652.943470565283;5814.563907706939;5844.327902291291;5876.294844618587;5944.256967879015;6277.96861015692;6291.339409362043;6294.2446906370615;6309.463695585835;6329.3987071229085;6205.03821907605;6221.160143380676;6224.856447493987;6229.345918678564;6242.188515492957;4857.562819346276;6140.596275277727;6169.96915015431;6216.378362163838;6275.529335299871;6161.815145545641;6172.660503672251;6226.813195604139;6267.6866156692895;6275.341089185661;6238;6287.088372186099;6288.52836037299;6305.747770089237;6310.905336419957;4823.938733478629;4928.186849169919;4961.181405068195;4976.850694479172;5008.973160502088;4448.288273876165;4455.598996090367;4463.553644635511;4503.797329120198;4531.252343363308;5739.7704091836695;5881.441263080001;5882.735276912549;5882.764689412375;5889.764409423575;6160.045192995093;6212.47193988516;6218.098375735493;6276.905846412307;6307.148534947815;5841.299044114733;5849.151872978395;5850.210221620112;5892.6759028253855;5917.260342457193;5418.837434876881;5450.073487507133;5451.373092094381;5458.863528411822;5467.261919640877;6223.657698826518;6267.059941008885;6284.905726414016;6349.206349206349;6349.873002539924;4666.906661866811;5221.399539053057;5334.039872822929;5823.912641310299;6113.838370709551;5639.984802735383;6219;6349.904751428639;6363.427291543783;6373.107764912823;5629.88740225185;6057.57596968208;6088.494172540314;6194.349593292639;6288.465480434214;5355.410904800547;5375.784968601212;5547.363527759401;6125.509959203164;6267.843303917256;6166.691665416801;6242.438180563771;6249.406306400884;6257.9687101564205;6262;5994.88010239784;6088.208532890873;6115.663638499747;6117.827404800828;6138.969305153446;4803.2314829628485;5950.166976623363;5986.610870293417;6096.329403765493;6104.550104788436;6191.318954915046;6225.221847269091;6225.968870155621;6228.003519436868;6251.4061164189325;5837.182794408871;6010.1585777991995;6057.151998720271;6084.421979911901;6100.481459076028 09:46:23 INFO - PROCESS | 1924 | |1;ext.html;463.66616036453746;480.971141731496;487.4248386903455;514.7683542405919;531.4021725558749;37113.886583402505;39285.21429571407;39296;39347;39459.80270098645;40047.99880002994;40564.39153412701;40595.797021014856;40640.39039414411;40726.79636601813;9537.141657250864;9700.660476883311;9742.610295588169;9773.902260977406;9797.216222702167;9694.854577181348;9793;9802.5588848502;9809.754756131118;9819.509024548744;7909.920900791005;8451.83096338073;9620.182284505825;9784.804303913917;9858.063483969012;8238.876416853722;9782.315237933348;9820.607175712963;9821.705348839547;9826.606935722562;9649.71050868468;9709.854352184722;9747.3664211826;9748.366356186825;9758.219342452587;38686.61313386858;38837;39013.634522791566;39169.41245881314;39653.80173099145;3357.8824741134185;3522.5068490411363;3523.066387407339;3715.777053376779;3831.099691572478;30054.84972575145;30170.69829301701;30249.790008399857;30703.2324191897;31230.37539249225;3211.614606247242;3235.433799085174;3241.675832416763;3248.2529018325918;3307.4046671599103;398532;478462;482098;484682.57658711483;486362;56482;56877.431225688044;57100.71449642725;57101;57175.14237286444;33671.831640841636;58684.706576466844;59021.40978590159;59326.70336648289;59405.70297148573;15583.688326233412;16190.028598284141;16256.349746010264;16276.674466510603;16306;12447.066470014604;12533;12538.122331436713;12555.87444125547;12560.99512039053;18385.63228735418;18763.530911727044;18786.342478013205;18839;18872.905635471736;6922.3732422880585;7423.962880185564;7979.042514898133;8012.3590112790835;8093.109757926623;857.2541888556996;919.9218066464292;1025.9384436933808;1053.4048262731533;1059.9470026498643;27063.86468067647;29746.553801692993;29913.953011644488;29914.102576922727;30020.399592008474;1038.8649475568127;1092.1044743310408;1134.3534185514343;1157.2188772578477;1161.5702190189515;14793.556193314218;15265.389384424721;15335.61660958485;15448.536543903701;15731.292091855894;605912;617787;618026;620607;620769;51276.23085653718;55650.72174639182;55680.72159639257;55948;56031;1370.41757253167;1385.3142694366354;1412.0962583946282;1412.427966673499;1412.4773833681604;29121.980730674324;29171.416571668873;29211;29886.551701724926;30036.399272014874;48396;48429.5157048425;48821.02357952892;48838.5116148834;48884.511154887994;565990;569143;571963;572877.4410303093;573209;25740.742592573835;25980;27030.459390812466;27036;27399.589006165323;45704.77147614241;46006;46470;46473.53526464692;47330.76334618305;27759.861200693867;32485.675143248263;38374;38568;43298.855688812255;37092.81453592715;37690.24619507539;38305.6169438302;38541;39063.609363906;32496;34895.778647747204;37776;37855.62144378521;38089.42865856959;31381.215469613442;33965.98102056942;34020.97937061892;34186.316273674885;34257.82871085629;23978.88010559936;25144.24567262985;25361.873190633927;25444.236672899842;25803.483930321665 09:46:23 INFO - PROCESS | 1924 | |2;jquery.html;365.1583100952304;373.1957631304539;380.3552977702794;383.20293788919054;395.37333326677214;19355.322563710273;20899.26852560161;21224.681629775565;21359.786402135975;21505.462363440904;19844.60310793787;20138.59722805543;20740.274090406838;20983;21072.473188170265;4074.3684728867074;4735.360726301939;5233.58131349493;5702.429757024295;6023.969880150593;3878.961210387888;5099.872503187431;5780.855478613026;5982.97008514959;6022.126791615213;4938.38270216223;5392.245085688007;6141.447269745722;6155.322914479404;6165.969170154143;6101.694915254219;6142.754289828402;6762.357576030269;6775;6815.693293801791;5032;5893.086571581412;5978.581499295051;6010.909836352458;6022.277326720778;23741.643875341884;24241.515169696682;24765.752342476524;25540.23379298624;26063.348416289555;2062.4740691123816;2136.102836808544;2144.624690679125;2175.9020844062056;2357.846739961897;9649.276304277208;10082.294239403245;10291.433971131584;10292.176625869912;15090;1580.316962435003;1632.877534184941;1872.8314451699412;1968.4685135013467;1973.2797528901847;97067;113477.4326128364;116338;118166.4091679536;120298.3985080069;9435.952820235854;10024.147947424404;11233.213675042669;11311.812259712777;11583.884161158281;11884.940575297242;13981.860181398055;14280.071795333477;14285.428582856777;14338.784918226027;6625.436837868739;7035.859282814315;8361.871147395024;8565.657373705106;8568.143185681382;8463.830723385498;8472.19514146155;8488.957555212184;8624.310055195569;8636.265917396966;3787.394016957274;4446.5331140230455;4506.3465797459185;4569.634429245652;4623.283391074388;1586.4288856012058;1908.9045547722558;1950.4636225038228;1957.6117137092724;1965.6068786242522;816.9264766171074;905.6060613633091;929.9070092990647;973.425678849468;988.8170688422521;19206.711899321523;20708.792912070687;20998.790012099988;21010.24127755592;21179;904.204300215819;904.895936967244;938.174406522256;959.6928982725461;970.335320305593;9212.263018958467;10714;10812.945935270272;11144.387058711687;11171.66485005451;551083;552769;553141;579500;584948;126769;127207.3639631796;127946.36026819992;127983;128070.35964820116;881.2289246908955;957.2389949989774;1029.763154474475;1035.9171266298679;1076.8115579773507;15564.92217538905;16873.578160546083;18908.527286817938;19224.65427420067;19531.921700357725;54025.18962215648;54778.904421912135;55174.724126380715;55429.722851385486;56378;74008;76045;78120.43759124899;81270.59364703139;82030.58984705278;8088;8132.552709601029;8314.293285070595;8336.04103035774;8466.407351485337;12706.745865082832;13142.014348923678;13226.4048117833;13439.395227214995;13480.258585777876;11363.375014373958;11458.885411145782;11562.248453850472;11802.704932376415;11871.881281187078;9276.304277179373;11202.047825934887;11271.943640281746;11306.265092768943;11604.88395116038;11298.60454884075;11492.195546311677;11758.647240582795;11830.940845295718;11855.703607409883;7617.27635874591;7643.5796031218815;8133.251350959572;8245.381596380175;8339.166083391856;10270.435126068138;10688.839667405151;10783.946080269548;11105.278156920098;11219.66341009771 09:46:23 INFO - PROCESS | 1924 | |3;mootools.html;1448.7609544425168;1534.2662791046116;1765.2762367429348;1881.5578339090303;1918.1080797429192;7931.881021784678;8455.450395724274;8616.396852220347;8955.731328060161;8978.102189781026;8657.004444488886;8836.11638836116;8840.38117331787;8841.05496790705;8860.379773415863;1795.5241860906806;1949.220311875254;2179.204590324528;2413.1312727418117;2413.7948274396654;2350.894209760565;2381.845180063299;2411.7347091819884;2413.239829453725;2421.491486787768;1871.429214089702;1902.809719028093;2260.4461906832767;2268.54629074185;2294.162630639821;2109.7362829646295;2372.0867466025534;2380.214529205358;2384.9999999999914;2390.8565486070893;2367.739548649647;2368.6565448010115;2372.6203807390907;2374.9406264843346;2381.5475059738596;8804.207621314112;8924.8661270081;8927.196552310323;8940.15068568485;8955.68655097075;5262.710550919698;5334.493223143795;5385.111456609655;5402.675839449606;5408.215808707715;3496.580410350749;3553.502509648651;3643.36241157799;3673.981630091859;3703.5926048134684;1070.4754670211619;1100.147385776022;1103.900648941599;1121.6410748560463;1127.0757978457668;11397.316161030365;11433.428328583537;11519.769604607945;11545.595904143314;11549.653510394617;4598.988222591024;4645.953540464552;4815.494373090845;4824.107540105092;4832.903341933212;9957.850632240523;9984.400935943866;9992.25058120644;10002.399856008662;10008.899911000797;5309.734513274321;5353.839384818461;5359.464053594687;5365.839024829262;5369.865753356197;4370.082282720601;4727.149113159664;4866.221404575251;4884.096442158212;4925.285833554115;3933.4296527003426;3996.200759848042;4006.7395619285235;4053.5338436079637;4112.280350938634;1300.336828217616;1304.373900527733;1307.405130665545;1316.3155159317103;1318.6505576022362;817.2726273616486;829.2702421868717;923.2982932970856;929.5445231836352;935.9812803743887;5907.379725128885;6001.729922153513;6015.789447369409;6226.688665566703;6325.399087086719;836.8410002099581;959.6593209410559;961.9422834629945;962.6919385796618;980.9362391444533;3900.473436086092;4020.5979402060143;4100.528439229467;4123.690723195772;4153.439285696392;10524.947375263228;10863.076638485818;10912.454377281105;10920.617778377877;10921.781564368668;10618.044376006197;10755.731106722395;10793.136549076055;10887.510062047228;10905.18211134168;7737.5744334062165;8155.796105097302;8208.138145494637;8360.83278334442;8392.118827523143;5966.134910438049;6031.366706495754;6043.09353596964;6063.696815159313;6217.689115544295;9943.204543636348;10014;10071.647492337734;10091.344062635904;10151.289409741246;9107.59015844302;9771.755706107404;10003.549840257352;10089.344192627455;10310.742231443974;6937.479689023445;7338.339549440576;7402.25977402277;7503.512271702322;7611.972383728347;8052.838943220986;8402.831943361221;8514.74455766328;8519.233269005572;8536.530490822815;6750.696218670073;7549.811254718676;7557.35762460175;7595.088589369311;7623.313901748869;7325.670344834389;7377.114746230487;7464.440166987606;7487.176410594721;7492.51298665585;6971.337722916315;6992.091028166509;7021.543599666005;7043.506954513135;7049.29507049291;4347.500037495728;4715.316279139574;4981.925271121008;5048.545630893237;5155.690658560498;5044.293798868088;5048.924266135938;5138.794448222029;5190.688558686491;5212.192110222922 09:46:23 INFO - PROCESS | 1924 | |4;prototype.html;298.2707280698693;298.3868150949798;310.9113902537776;313.6925812703549;316.928691044515;5353.3843607985;5455.563554915607;5484.314460692413;5618.662880227189;5674.7439420434675;4628.514006029369;5059.59523238141;5487.067198576242;5491.450854914507;5536.861578460541;4098.426220329149;4260.169266992938;4261.76560289184;4271.743695378272;4306.978465107686;4106.568810274923;4202.835539697058;4243.6605071594195;4282.015136518603;4293.785310734466;3461.3942560052014;3827.5646632512808;4160;4185.665146788265;4198.244316023115;3901.824417901187;4202.432671589327;4203.390508376268;4212.49450065994;4217.903345130258;3383.5770528683915;3552.2185119273718;3565.750397472178;3565.857365705395;3651.543557055368;4897.363342765453;4938.185234416384;5248.396434410053;5367.892642147135;5424.674519528841;4130.524989626201;4400.471943366784;4763.214069678499;4776.80892764289;4781.832635857728;4692.202325604655;5119.488051194888;5326.414094449608;5343.465653434625;5745.827625171251;3872.206197729459;3884.1066554692297;3891.455196272524;3924.3132451820816;3946.8026598669953;5452.100403433429;5901.8819623607715;6167.568270221132;6200.875982480326;6241.750329986795;3608.6391360863704;3684.281565094781;3964.1873415949663;3997.5802540732802;4105.69207309447;4155.2728272552185;4216.494020717533;4233.724807887477;4234.237837189274;4242.406063151161;4895.559399654048;4896.828610998669;4898.657094003457;4937.432195297515;4969.403671559436;5046.953046953047;5462.562994960473;5466.426025267369;5802.390748971382;6047.244094488189;1343.3820442596516;1394.9232792196324;1428.557147284345;1437.4034775568232;1439.1868594244427;822.576373167819;946.3612061858219;950.6530116507425;953.7997020625608;954.2842867849113;5232.450592687789;5346;5390.51485366319;5411.458854114557;5464.453554644504;5991.370906054889;6013.428724271187;6022.488088512438;6022.789202377896;6024.819255422344;4306.418633484503;4973.179425394769;5368.570514358842;5415.729213539307;5475.222096753228;2246.0903334149693;2288.691026711406;2616.0974463809957;2673.2381271337576;2673.25148958292;4296.37702533131;5087.287779710843;5243.10867152585;5279.392869819943;5288.153895376721;4258.594663760951;4871.343743127353;4874.8781280467565;4943.950560494349;5021.966103727996;4710.576048155683;4732.219183834629;4994.350734404504;5223.216517522402;5246.317978662826;4099.754014759124;4439.733615983051;4535.208592605968;5001.674221487026;5041.218611115283;3930.2117536521387;4294.76378799169;5568.526675232489;6175.660338681236;6198.01663467686;5778.942210578009;6283.397733577908;6361.809145725636;6363.90454143179;6370.872582548416;3441.5353927219503;3680.4847321374496;3735.3463143950244;3983.3029219885825;4066.9796651016554;4884.975575122102;4942.209246520683;5717.199592057199;5845.503132233808;6078.848028799315;4986.775595098156;5927.110933360031;5950.31571369298;6014.789482368096;6042.884990253376;4937.9259311109645;5492.423295553909;5899.262592175978;5918.319393269657;5925.140854576149;4531;5205.315216305205;5459.699716515471;5782.421757824184;5796.275750397306;5625.493705566519;5793.899159159915;5990.46085852258;6024.307204671519;6034.969825150846;4812.711237325771;5257;5361.51746342831;5522.86192845166;5844.736986835687 09:46:23 INFO - PROCESS | 1924 | |5;yui.html;721.7149226055708;751.8007727952097;788.7121200761724;800.1758189065263;847.9024912135104;13444.65553444655;15022.24888755562;15659.84340156598;15931.68136637268;16244.43144489943;12435.502579896816;14573.927130364333;14829.480968166117;14830;14856.18290993995;699.4823830365543;859.2095272349422;861.9008813986378;868.3443999780158;870.843248215321;871.1984973824098;881.2597418168737;889.3863234368265;891.073283784863;895.3687650206589;916.0656130746635;931.1479995803832;950.401247214254;951.8096380723848;952.2619969523605;933.7572231219864;941.256407438123;942.3309450290336;942.5522876633613;943.8725772020792;684.7842929477199;695.8747425463415;697.0554898113085;699.1784653032666;700.908881845362;15605.68788624221;15675.529734107995;15705.68588628221;15732.921335393365;15790.842091579052;14503.492377766834;14541.982061255716;14689.853101469062;14727.55817325482;14968;11881.881181188164;12019.819702704466;12222.083343749255;12231.388430578436;12538.749225015448;3767.4537192107264;3768.6042965488778;3959.5644479107277;4006.779627120507;4008.7594744315434;117037;118078.40960795227;118571;118795.4060229702;124411;11207.775844483227;11233.213675042833;11265.436728163722;11346.546138154381;11714.23857449263;23115.190968316027;23199.88400057989;23215.3035408938;23242.41893952631;23338.299851004496;8072.959635201903;8104.756857294291;8136.75589732309;8154.5514996674565;8353.37349698763;4414.1171765647;4428.202923473741;4441.444819397575;4508.81964721414;4613.146567884952;3574.8927532174075;3670.15586415126;3687.760295580779;3698.334299826003;3703.000189948699;668.1481111582674;668.85619591788;674.2919934069208;686.361683634225;699.9755008574675;531.2270646209755;588.9587728858938;613.8097189871154;664.6078813499962;671.3957438305466;9381.249500039981;9396.201322887495;9465.716028519018;9495.857562136573;9732.41605503672;556.5338161255953;596.5853731656457;615.7383112177396;646.8647523596059;697.1285892634207;3454.8790792322147;3795.4686343911876;4117.238310912491;4317.287647538184;4530.569595888452;113501;159359;163267;166263;167536.97389078347;135946.28107437797;137353.3132334332;141781;141941;146325.26837365958;67771.66114169397;77940;116295;118755.40622296833;120369.79630203586;8744.169303916118;8757.562121894007;8792.340574456814;8830.867536986822;8966.730998070068;61452.385476144664;62033.68983155055;65258.02112968404;66873.33126668671;68948;101478;102959;103191;103410.93178136245;104535;10784.676459706221;11124.443777811239;11407.828882566933;12080.154389192672;12414.131010829517;14342.067765595202;14924.552263432115;14942.925285373503;15071.773923391378;15750.606234843763;13274.336283185996;13690.726185476435;14022.228777416933;14054.297285135906;14361.85638143647;13285.513026569011;13591.252481113634;14225.64435889111;14229.50196743109;14255.786163207353;13209.669758256121;13326.93336533311;13734.725305494034;13875.653108672364;14034.719305613626;9237;11248.212625116163;11416.714582135513;11460.885391145981;12005.39973001364;8232.644882249091;11401.408695048578;11614.477348519506;11734.295942243492;11791.410429478663 09:46:23 INFO - PROCESS | 1924 | __end_tp_report 09:46:23 INFO - PROCESS | 1924 | __start_cc_report 09:46:23 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,387 09:46:23 INFO - PROCESS | 1924 | __end_cc_report 09:46:23 INFO - PROCESS | 1924 | __startTimestamp1472661983505__endTimestamp 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | ------- Summary: start ------- 09:46:23 INFO - PROCESS | 1924 | Number of tests: 6 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | [#0] dojo.html Cycles:165 Average:5744.43 Median:5892.68 stddev:642.62 (10.9%) stddev-sans-first:606.43 09:46:23 INFO - PROCESS | 1924 | Values: 2963.4 3373.9 3427.4 3437.9 3441.7 5674.5 5786.6 5790.5 5821.2 5824.3 5774.4 5790.2 5794.4 5804.6 5946.7 5210.3 5302.9 5334.6 5385.6 5387.5 5393.2 5409.0 5417.8 5473.5 5481.5 5161.8 5183.3 5207.1 5341.5 5349.3 5260.5 5288.9 5289.3 5299.7 5335.0 5230.6 5295.9 5328.7 5341.3 5351.7 5695.6 5778.2 5790.8 5824.4 5903.6 6251.6 6252.5 6276.7 6282.7 6292.6 6249.8 6265.8 6268.4 6287.0 6300.7 5652.9 5814.6 5844.3 5876.3 5944.3 6278.0 6291.3 6294.2 6309.5 6329.4 6205.0 6221.2 6224.9 6229.3 6242.2 4857.6 6140.6 6170.0 6216.4 6275.5 6161.8 6172.7 6226.8 6267.7 6275.3 6238.0 6287.1 6288.5 6305.7 6310.9 4823.9 4928.2 4961.2 4976.9 5009.0 4448.3 4455.6 4463.6 4503.8 4531.3 5739.8 5881.4 5882.7 5882.8 5889.8 6160.0 6212.5 6218.1 6276.9 6307.1 5841.3 5849.2 5850.2 5892.7 5917.3 5418.8 5450.1 5451.4 5458.9 5467.3 6223.7 6267.1 6284.9 6349.2 6349.9 4666.9 5221.4 5334.0 5823.9 6113.8 5640.0 6219.0 6349.9 6363.4 6373.1 5629.9 6057.6 6088.5 6194.3 6288.5 5355.4 5375.8 5547.4 6125.5 6267.8 6166.7 6242.4 6249.4 6258.0 6262.0 5994.9 6088.2 6115.7 6117.8 6139.0 4803.2 5950.2 5986.6 6096.3 6104.6 6191.3 6225.2 6226.0 6228.0 6251.4 5837.2 6010.2 6057.2 6084.4 6100.5 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | [#1] ext.html Cycles:180 Average:67969.06 Median:39038.62 stddev:148283.61 (379.8%) stddev-sans-first:148612.96 09:46:23 INFO - PROCESS | 1924 | Values: 463.7 481.0 487.4 514.8 531.4 37113.9 39285.2 39296.0 39347.0 39459.8 40048.0 40564.4 40595.8 40640.4 40726.8 9537.1 9700.7 9742.6 9773.9 9797.2 9694.9 9793.0 9802.6 9809.8 9819.5 7909.9 8451.8 9620.2 9784.8 9858.1 8238.9 9782.3 9820.6 9821.7 9826.6 9649.7 9709.9 9747.4 9748.4 9758.2 38686.6 38837.0 39013.6 39169.4 39653.8 3357.9 3522.5 3523.1 3715.8 3831.1 30054.8 30170.7 30249.8 30703.2 31230.4 3211.6 3235.4 3241.7 3248.3 3307.4 398532.0 478462.0 482098.0 484682.6 486362.0 56482.0 56877.4 57100.7 57101.0 57175.1 33671.8 58684.7 59021.4 59326.7 59405.7 15583.7 16190.0 16256.3 16276.7 16306.0 12447.1 12533.0 12538.1 12555.9 12561.0 18385.6 18763.5 18786.3 18839.0 18872.9 6922.4 7424.0 7979.0 8012.4 8093.1 857.3 919.9 1025.9 1053.4 1059.9 27063.9 29746.6 29914.0 29914.1 30020.4 1038.9 1092.1 1134.4 1157.2 1161.6 14793.6 15265.4 15335.6 15448.5 15731.3 605912.0 617787.0 618026.0 620607.0 620769.0 51276.2 55650.7 55680.7 55948.0 56031.0 1370.4 1385.3 1412.1 1412.4 1412.5 29122.0 29171.4 29211.0 29886.6 30036.4 48396.0 48429.5 48821.0 48838.5 48884.5 565990.0 569143.0 571963.0 572877.4 573209.0 25740.7 25980.0 27030.5 27036.0 27399.6 45704.8 46006.0 46470.0 46473.5 47330.8 27759.9 32485.7 38374.0 38568.0 43298.9 37092.8 37690.2 38305.6 38541.0 39063.6 32496.0 34895.8 37776.0 37855.6 38089.4 31381.2 33966.0 34021.0 34186.3 34257.8 23978.9 25144.2 25361.9 25444.2 25803.5 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | [#2] jquery.html Cycles:180 Average:33955.26 Median:25152.99 stddev:94451.55 (375.5%) stddev-sans-first:94682.84 09:46:23 INFO - PROCESS | 1924 | Values: 365.2 373.2 380.4 383.2 395.4 19355.3 20899.3 21224.7 21359.8 21505.5 19844.6 20138.6 20740.3 20983.0 21072.5 4074.4 4735.4 5233.6 5702.4 6024.0 3879.0 5099.9 5780.9 5983.0 6022.1 4938.4 5392.2 6141.4 6155.3 6166.0 6101.7 6142.8 6762.4 6775.0 6815.7 5032.0 5893.1 5978.6 6010.9 6022.3 23741.6 24241.5 24765.8 25540.2 26063.3 2062.5 2136.1 2144.6 2175.9 2357.8 9649.3 10082.3 10291.4 10292.2 15090.0 1580.3 1632.9 1872.8 1968.5 1973.3 97067.0 113477.4 116338.0 118166.4 120298.4 9436.0 10024.1 11233.2 11311.8 11583.9 11884.9 13981.9 14280.1 14285.4 14338.8 6625.4 7035.9 8361.9 8565.7 8568.1 8463.8 8472.2 8489.0 8624.3 8636.3 3787.4 4446.5 4506.3 4569.6 4623.3 1586.4 1908.9 1950.5 1957.6 1965.6 816.9 905.6 929.9 973.4 988.8 19206.7 20708.8 20998.8 21010.2 21179.0 904.2 904.9 938.2 959.7 970.3 9212.3 10714.0 10812.9 11144.4 11171.7 551083.0 552769.0 553141.0 579500.0 584948.0 126769.0 127207.4 127946.4 127983.0 128070.4 881.2 957.2 1029.8 1035.9 1076.8 15564.9 16873.6 18908.5 19224.7 19531.9 54025.2 54778.9 55174.7 55429.7 56378.0 74008.0 76045.0 78120.4 81270.6 82030.6 8088.0 8132.6 8314.3 8336.0 8466.4 12706.7 13142.0 13226.4 13439.4 13480.3 11363.4 11458.9 11562.2 11802.7 11871.9 9276.3 11202.0 11271.9 11306.3 11604.9 11298.6 11492.2 11758.6 11830.9 11855.7 7617.3 7643.6 8133.3 8245.4 8339.2 10270.4 10688.8 10783.9 11105.3 11219.7 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | [#3] mootools.html Cycles:180 Average:5735.18 Median:4953.61 stddev:3204.42 (64.7%) stddev-sans-first:3197.22 09:46:23 INFO - PROCESS | 1924 | Values: 1448.8 1534.3 1765.3 1881.6 1918.1 7931.9 8455.5 8616.4 8955.7 8978.1 8657.0 8836.1 8840.4 8841.1 8860.4 1795.5 1949.2 2179.2 2413.1 2413.8 2350.9 2381.8 2411.7 2413.2 2421.5 1871.4 1902.8 2260.4 2268.5 2294.2 2109.7 2372.1 2380.2 2385.0 2390.9 2367.7 2368.7 2372.6 2374.9 2381.5 8804.2 8924.9 8927.2 8940.2 8955.7 5262.7 5334.5 5385.1 5402.7 5408.2 3496.6 3553.5 3643.4 3674.0 3703.6 1070.5 1100.1 1103.9 1121.6 1127.1 11397.3 11433.4 11519.8 11545.6 11549.7 4599.0 4646.0 4815.5 4824.1 4832.9 9957.9 9984.4 9992.3 10002.4 10008.9 5309.7 5353.8 5359.5 5365.8 5369.9 4370.1 4727.1 4866.2 4884.1 4925.3 3933.4 3996.2 4006.7 4053.5 4112.3 1300.3 1304.4 1307.4 1316.3 1318.7 817.3 829.3 923.3 929.5 936.0 5907.4 6001.7 6015.8 6226.7 6325.4 836.8 959.7 961.9 962.7 980.9 3900.5 4020.6 4100.5 4123.7 4153.4 10524.9 10863.1 10912.5 10920.6 10921.8 10618.0 10755.7 10793.1 10887.5 10905.2 7737.6 8155.8 8208.1 8360.8 8392.1 5966.1 6031.4 6043.1 6063.7 6217.7 9943.2 10014.0 10071.6 10091.3 10151.3 9107.6 9771.8 10003.5 10089.3 10310.7 6937.5 7338.3 7402.3 7503.5 7612.0 8052.8 8402.8 8514.7 8519.2 8536.5 6750.7 7549.8 7557.4 7595.1 7623.3 7325.7 7377.1 7464.4 7487.2 7492.5 6971.3 6992.1 7021.5 7043.5 7049.3 4347.5 4715.3 4981.9 5048.5 5155.7 5044.3 5048.9 5138.8 5190.7 5212.2 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | [#4] prototype.html Cycles:180 Average:4560.60 Median:4956.68 stddev:1407.51 (28.4%) stddev-sans-first:1374.62 09:46:23 INFO - PROCESS | 1924 | Values: 298.3 298.4 310.9 313.7 316.9 5353.4 5455.6 5484.3 5618.7 5674.7 4628.5 5059.6 5487.1 5491.5 5536.9 4098.4 4260.2 4261.8 4271.7 4307.0 4106.6 4202.8 4243.7 4282.0 4293.8 3461.4 3827.6 4160.0 4185.7 4198.2 3901.8 4202.4 4203.4 4212.5 4217.9 3383.6 3552.2 3565.8 3565.9 3651.5 4897.4 4938.2 5248.4 5367.9 5424.7 4130.5 4400.5 4763.2 4776.8 4781.8 4692.2 5119.5 5326.4 5343.5 5745.8 3872.2 3884.1 3891.5 3924.3 3946.8 5452.1 5901.9 6167.6 6200.9 6241.8 3608.6 3684.3 3964.2 3997.6 4105.7 4155.3 4216.5 4233.7 4234.2 4242.4 4895.6 4896.8 4898.7 4937.4 4969.4 5047.0 5462.6 5466.4 5802.4 6047.2 1343.4 1394.9 1428.6 1437.4 1439.2 822.6 946.4 950.7 953.8 954.3 5232.5 5346.0 5390.5 5411.5 5464.5 5991.4 6013.4 6022.5 6022.8 6024.8 4306.4 4973.2 5368.6 5415.7 5475.2 2246.1 2288.7 2616.1 2673.2 2673.3 4296.4 5087.3 5243.1 5279.4 5288.2 4258.6 4871.3 4874.9 4944.0 5022.0 4710.6 4732.2 4994.4 5223.2 5246.3 4099.8 4439.7 4535.2 5001.7 5041.2 3930.2 4294.8 5568.5 6175.7 6198.0 5778.9 6283.4 6361.8 6363.9 6370.9 3441.5 3680.5 3735.3 3983.3 4067.0 4885.0 4942.2 5717.2 5845.5 6078.8 4986.8 5927.1 5950.3 6014.8 6042.9 4937.9 5492.4 5899.3 5918.3 5925.1 4531.0 5205.3 5459.7 5782.4 5796.3 5625.5 5793.9 5990.5 6024.3 6035.0 4812.7 5257.0 5361.5 5522.9 5844.7 09:46:23 INFO - PROCESS | 1924 | 09:46:23 INFO - PROCESS | 1924 | [#5] yui.html Cycles:180 Average:25794.18 Median:13396.59 stddev:41899.68 (312.8%) stddev-sans-first:41974.93 09:46:23 INFO - PROCESS | 1924 | Values: 721.7 751.8 788.7 800.2 847.9 13444.7 15022.2 15659.8 15931.7 16244.4 12435.5 14573.9 14829.5 14830.0 14856.2 699.5 859.2 861.9 868.3 870.8 871.2 881.3 889.4 891.1 895.4 916.1 931.1 950.4 951.8 952.3 933.8 941.3 942.3 942.6 943.9 684.8 695.9 697.1 699.2 700.9 15605.7 15675.5 15705.7 15732.9 15790.8 14503.5 14542.0 14689.9 14727.6 14968.0 11881.9 12019.8 12222.1 12231.4 12538.7 3767.5 3768.6 3959.6 4006.8 4008.8 117037.0 118078.4 118571.0 118795.4 124411.0 11207.8 11233.2 11265.4 11346.5 11714.2 23115.2 23199.9 23215.3 23242.4 23338.3 8073.0 8104.8 8136.8 8154.6 8353.4 4414.1 4428.2 4441.4 4508.8 4613.1 3574.9 3670.2 3687.8 3698.3 3703.0 668.1 668.9 674.3 686.4 700.0 531.2 589.0 613.8 664.6 671.4 9381.2 9396.2 9465.7 9495.9 9732.4 556.5 596.6 615.7 646.9 697.1 3454.9 3795.5 4117.2 4317.3 4530.6 113501.0 159359.0 163267.0 166263.0 167537.0 135946.3 137353.3 141781.0 141941.0 146325.3 67771.7 77940.0 116295.0 118755.4 120369.8 8744.2 8757.6 8792.3 8830.9 8966.7 61452.4 62033.7 65258.0 66873.3 68948.0 101478.0 102959.0 103191.0 103410.9 104535.0 10784.7 11124.4 11407.8 12080.2 12414.1 14342.1 14924.6 14942.9 15071.8 15750.6 13274.3 13690.7 14022.2 14054.3 14361.9 13285.5 13591.3 14225.6 14229.5 14255.8 13209.7 13326.9 13734.7 13875.7 14034.7 9237.0 11248.2 11416.7 11460.9 12005.4 8232.6 11401.4 11614.5 11734.3 11791.4 09:46:23 INFO - PROCESS | 1924 | -------- Summary: end -------- 09:46:23 INFO - PROCESS | 1924 | 09:46:24 INFO - TEST-INFO | 1924: exit 0 09:46:24 INFO - TEST-OK | dromaeo_css | took 1113962ms 09:46:24 INFO - TEST-START | kraken 09:46:24 INFO - operating with platform_type : mac_ 09:46:24 INFO - Initialising browser for kraken test... 09:46:24 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpeJPfL4/profile http://localhost:49352/getInfo.html) 09:46:26 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 09:46:26 INFO - PROCESS | 1930 | colorDepth:24 09:46:26 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 09:46:26 INFO - PROCESS | 1930 | __metrics 09:46:26 ERROR - PROCESS | 1930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 09:46:27 INFO - TEST-INFO | 1930: exit 0 09:46:27 INFO - Browser initialized. 09:46:27 INFO - Running cycle 1/1 for kraken test... 09:46:27 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/T/tmpeJPfL4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:39 INFO - PROCESS | 1932 | RSS: Main: 244375552 09:46:39 INFO - PROCESS | 1932 | 09:46:42 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:43 INFO - PROCESS | 1932 | RSS: Main: 256823296 09:46:43 INFO - PROCESS | 1932 | 09:46:46 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-dft) 09:46:46 INFO - PROCESS | 1932 | RSS: Main: 265576448 09:46:46 INFO - PROCESS | 1932 | 09:46:50 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-fft) 09:46:50 INFO - PROCESS | 1932 | RSS: Main: 263299072 09:46:50 INFO - PROCESS | 1932 | 09:46:52 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49352/tests/kraken/driver.html?testName=audio-oscillator) 09:46:53 INFO - PROCESS | 1932 | RSS: Main: 264646656 09:46:53 INFO - PROCESS | 1932 | 09:46:55 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49352/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:56 INFO - PROCESS | 1932 | RSS: Main: 264937472 09:46:56 INFO - PROCESS | 1932 | 09:46:59 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49352/tests/kraken/driver.html?testName=imaging-darkroom) 09:46:59 INFO - PROCESS | 1932 | RSS: Main: 265035776 09:46:59 INFO - PROCESS | 1932 | 09:47:02 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49352/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:03 INFO - PROCESS | 1932 | RSS: Main: 265252864 09:47:03 INFO - PROCESS | 1932 | 09:47:06 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49352/tests/kraken/driver.html?testName=json-parse-financial) 09:47:07 INFO - PROCESS | 1932 | RSS: Main: 265605120 09:47:07 INFO - PROCESS | 1932 | 09:47:08 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49352/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:09 INFO - PROCESS | 1932 | RSS: Main: 266530816 09:47:09 INFO - PROCESS | 1932 | 09:47:11 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:11 INFO - PROCESS | 1932 | RSS: Main: 266534912 09:47:11 INFO - PROCESS | 1932 | 09:47:14 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:15 INFO - PROCESS | 1932 | RSS: Main: 266530816 09:47:15 INFO - PROCESS | 1932 | 09:47:17 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:18 INFO - PROCESS | 1932 | RSS: Main: 266661888 09:47:18 INFO - PROCESS | 1932 | 09:47:21 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:22 INFO - PROCESS | 1932 | RSS: Main: 266739712 09:47:22 INFO - PROCESS | 1932 | 09:47:23 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49352/tests/kraken/driver.html?testName=ai-astar) 09:47:24 INFO - PROCESS | 1932 | RSS: Main: 268582912 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | __start_tp_report 09:47:24 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 09:47:24 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 09:47:24 INFO - PROCESS | 1932 | |i|pagename|runs| 09:47:24 INFO - PROCESS | 1932 | |0;ai-astar;156;172;132;115;117;117;116;120;115;115 09:47:24 INFO - PROCESS | 1932 | |1;audio-beat-detection;153;113;138;183;144;165;121;141;197;140 09:47:24 INFO - PROCESS | 1932 | |2;audio-dft;200;216;232;199;196;198;187;274;250;191 09:47:24 INFO - PROCESS | 1932 | |3;audio-fft;73;88;77;140;65;85;80;92;147;58 09:47:24 INFO - PROCESS | 1932 | |4;audio-oscillator;69;73;118;71;83;111;145;101;93;115 09:47:24 INFO - PROCESS | 1932 | |5;imaging-gaussian-blur;90;130;104;131;115;85;89;87;77;74 09:47:24 INFO - PROCESS | 1932 | |6;imaging-darkroom;95;96;96;104;98;151;155;95;95;94 09:47:24 INFO - PROCESS | 1932 | |7;imaging-desaturate;96;94;92;92;94;97;146;121;93;92 09:47:24 INFO - PROCESS | 1932 | |8;json-parse-financial;52;81;86;85;86;63;59;59;59;59 09:47:24 INFO - PROCESS | 1932 | |9;json-stringify-tinderbox;70;70;69;76;73;77;75;69;72;74 09:47:24 INFO - PROCESS | 1932 | |10;stanford-crypto-aes;115;80;93;94;110;112;88;89;112;115 09:47:24 INFO - PROCESS | 1932 | |11;stanford-crypto-ccm;138;139;136;173;145;127;174;136;192;162 09:47:24 INFO - PROCESS | 1932 | |12;stanford-crypto-pbkdf2;174;177;174;175;178;241;221;172;174;177 09:47:24 INFO - PROCESS | 1932 | |13;stanford-crypto-sha256-iterative;70;68;68;68;71;68;68;68;68;68 09:47:24 INFO - PROCESS | 1932 | __end_tp_report 09:47:24 INFO - PROCESS | 1932 | __start_cc_report 09:47:24 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,963 09:47:24 INFO - PROCESS | 1932 | __end_cc_report 09:47:24 INFO - PROCESS | 1932 | __startTimestamp1472662044242__endTimestamp 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | ------- Summary: start ------- 09:47:24 INFO - PROCESS | 1932 | Number of tests: 14 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#0] ai-astar Cycles:10 Average:127.50 Median:118.50 stddev:20.25 (17.1%) stddev-sans-first:18.67 09:47:24 INFO - PROCESS | 1932 | Values: 156.0 172.0 132.0 115.0 117.0 117.0 116.0 120.0 115.0 115.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:148.50 stddev:26.04 (17.5%) stddev-sans-first:27.58 09:47:24 INFO - PROCESS | 1932 | Values: 153.0 113.0 138.0 183.0 144.0 165.0 121.0 141.0 197.0 140.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#2] audio-dft Cycles:10 Average:214.30 Median:208.00 stddev:28.83 (13.9%) stddev-sans-first:30.11 09:47:24 INFO - PROCESS | 1932 | Values: 200.0 216.0 232.0 199.0 196.0 198.0 187.0 274.0 250.0 191.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#3] audio-fft Cycles:10 Average:90.50 Median:78.50 stddev:29.79 (37.9%) stddev-sans-first:30.92 09:47:24 INFO - PROCESS | 1932 | Values: 73.0 88.0 77.0 140.0 65.0 85.0 80.0 92.0 147.0 58.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:70.00 stddev:24.77 (35.4%) stddev-sans-first:23.96 09:47:24 INFO - PROCESS | 1932 | Values: 69.0 73.0 118.0 71.0 83.0 111.0 145.0 101.0 93.0 115.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#5] imaging-gaussian-blur Cycles:10 Average:98.20 Median:81.00 stddev:20.79 (25.7%) stddev-sans-first:21.84 09:47:24 INFO - PROCESS | 1932 | Values: 90.0 130.0 104.0 131.0 115.0 85.0 89.0 87.0 77.0 74.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:95.00 stddev:23.96 (25.2%) stddev-sans-first:24.95 09:47:24 INFO - PROCESS | 1932 | Values: 95.0 96.0 96.0 104.0 98.0 151.0 155.0 95.0 95.0 94.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:93.50 stddev:17.85 (19.1%) stddev-sans-first:18.81 09:47:24 INFO - PROCESS | 1932 | Values: 96.0 94.0 92.0 92.0 94.0 97.0 146.0 121.0 93.0 92.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:72.00 stddev:13.76 (19.1%) stddev-sans-first:13.16 09:47:24 INFO - PROCESS | 1932 | Values: 52.0 81.0 86.0 85.0 86.0 63.0 59.0 59.0 59.0 59.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:73.50 stddev:2.95 (4.0%) stddev-sans-first:2.99 09:47:24 INFO - PROCESS | 1932 | Values: 70.0 70.0 69.0 76.0 73.0 77.0 75.0 69.0 72.0 74.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#10] stanford-crypto-aes Cycles:10 Average:100.80 Median:84.00 stddev:13.26 (15.8%) stddev-sans-first:13.03 09:47:24 INFO - PROCESS | 1932 | Values: 115.0 80.0 93.0 94.0 110.0 112.0 88.0 89.0 112.0 115.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:153.50 stddev:21.54 (14.0%) stddev-sans-first:22.22 09:47:24 INFO - PROCESS | 1932 | Values: 138.0 139.0 136.0 173.0 145.0 127.0 174.0 136.0 192.0 162.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.30 Median:177.00 stddev:24.09 (13.6%) stddev-sans-first:25.14 09:47:24 INFO - PROCESS | 1932 | Values: 174.0 177.0 174.0 175.0 178.0 241.0 221.0 172.0 174.0 177.0 09:47:24 INFO - PROCESS | 1932 | 09:47:24 INFO - PROCESS | 1932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:1.08 (1.6%) stddev-sans-first:1.00 09:47:24 INFO - PROCESS | 1932 | Values: 70.0 68.0 68.0 68.0 71.0 68.0 68.0 68.0 68.0 68.0 09:47:24 INFO - PROCESS | 1932 | -------- Summary: end -------- 09:47:24 INFO - PROCESS | 1932 | 09:47:25 INFO - TEST-INFO | 1932: exit 0 09:47:25 INFO - TEST-OK | kraken | took 61090ms 09:47:25 INFO - SUITE-END | took 1175s 09:47:25 INFO - Completed test suite (00:19:35) 09:47:25 INFO - javascript benchmark 09:47:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7369.356671251894, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2963.362876981449, 3373.9325213495745, 3427.383071047212, 3437.9484307735374, 3441.724662027038, 5674.517665998395, 5786.623869448483, 5790.478856902878, 5821.185034095225, 5824.271966004249, 5774.364819869811, 5790.247267855183, 5794.362620111784, 5804.622699524528, 5946.672932988684, 5210.322658054448, 5302.946970530284, 5334.5732341412595, 5385.649932754378, 5387.515123638872, 5393.164059570773, 5409.0, 5417.756200970946, 5473.452654734535, 5481.451854814526, 5161.793528258865, 5183.2743415921805, 5207.140821764404, 5341.492558206984, 5349.277847490597, 5260.473952604709, 5288.894222115575, 5289.285946397225, 5299.708516031616, 5334.973325133349, 5230.607704422146, 5295.867603309909, 5328.733563321818, 5341.252224688546, 5351.7056561889085, 5695.572832037614, 5778.248827652378, 5790.7973220937065, 5824.417558244184, 5903.616264942808, 6251.593646413014, 6252.531060170505, 6276.717547710364, 6282.748690052393, 6292.5595208335435, 6249.812505624838, 6265.812025639192, 6268.373162683741, 6286.968565157191, 6300.747970081237, 5652.943470565283, 5814.563907706939, 5844.327902291291, 5876.294844618587, 5944.256967879015, 6277.96861015692, 6291.339409362043, 6294.2446906370615, 6309.463695585835, 6329.3987071229085, 6205.03821907605, 6221.160143380676, 6224.856447493987, 6229.345918678564, 6242.188515492957, 4857.562819346276, 6140.596275277727, 6169.96915015431, 6216.378362163838, 6275.529335299871, 6161.815145545641, 6172.660503672251, 6226.813195604139, 6267.6866156692895, 6275.341089185661, 6238.0, 6287.088372186099, 6288.52836037299, 6305.747770089237, 6310.905336419957, 4823.938733478629, 4928.186849169919, 4961.181405068195, 4976.850694479172, 5008.973160502088, 4448.288273876165, 4455.598996090367, 4463.553644635511, 4503.797329120198, 4531.252343363308, 5739.7704091836695, 5881.441263080001, 5882.735276912549, 5882.764689412375, 5889.764409423575, 6160.045192995093, 6212.47193988516, 6218.098375735493, 6276.905846412307, 6307.148534947815, 5841.299044114733, 5849.151872978395, 5850.210221620112, 5892.6759028253855, 5917.260342457193, 5418.837434876881, 5450.073487507133, 5451.373092094381, 5458.863528411822, 5467.261919640877, 6223.657698826518, 6267.059941008885, 6284.905726414016, 6349.206349206349, 6349.873002539924, 4666.906661866811, 5221.399539053057, 5334.039872822929, 5823.912641310299, 6113.838370709551, 5639.984802735383, 6219.0, 6349.904751428639, 6363.427291543783, 6373.107764912823, 5629.88740225185, 6057.57596968208, 6088.494172540314, 6194.349593292639, 6288.465480434214, 5355.410904800547, 5375.784968601212, 5547.363527759401, 6125.509959203164, 6267.843303917256, 6166.691665416801, 6242.438180563771, 6249.406306400884, 6257.9687101564205, 6262.0, 5994.88010239784, 6088.208532890873, 6115.663638499747, 6117.827404800828, 6138.969305153446, 4803.2314829628485, 5950.166976623363, 5986.610870293417, 6096.329403765493, 6104.550104788436, 6191.318954915046, 6225.221847269091, 6225.968870155621, 6228.003519436868, 6251.4061164189325, 5837.182794408871, 6010.1585777991995, 6057.151998720271, 6084.421979911901, 6100.481459076028], "value": 5704.924549315741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.66616036453746, 480.971141731496, 487.4248386903455, 514.7683542405919, 531.4021725558749, 37113.886583402505, 39285.21429571407, 39296.0, 39347.0, 39459.80270098645, 40047.99880002994, 40564.39153412701, 40595.797021014856, 40640.39039414411, 40726.79636601813, 9537.141657250864, 9700.660476883311, 9742.610295588169, 9773.902260977406, 9797.216222702167, 9694.854577181348, 9793.0, 9802.5588848502, 9809.754756131118, 9819.509024548744, 7909.920900791005, 8451.83096338073, 9620.182284505825, 9784.804303913917, 9858.063483969012, 8238.876416853722, 9782.315237933348, 9820.607175712963, 9821.705348839547, 9826.606935722562, 9649.71050868468, 9709.854352184722, 9747.3664211826, 9748.366356186825, 9758.219342452587, 38686.61313386858, 38837.0, 39013.634522791566, 39169.41245881314, 39653.80173099145, 3357.8824741134185, 3522.5068490411363, 3523.066387407339, 3715.777053376779, 3831.099691572478, 30054.84972575145, 30170.69829301701, 30249.790008399857, 30703.2324191897, 31230.37539249225, 3211.614606247242, 3235.433799085174, 3241.675832416763, 3248.2529018325918, 3307.4046671599103, 398532.0, 478462.0, 482098.0, 484682.57658711483, 486362.0, 56482.0, 56877.431225688044, 57100.71449642725, 57101.0, 57175.14237286444, 33671.831640841636, 58684.706576466844, 59021.40978590159, 59326.70336648289, 59405.70297148573, 15583.688326233412, 16190.028598284141, 16256.349746010264, 16276.674466510603, 16306.0, 12447.066470014604, 12533.0, 12538.122331436713, 12555.87444125547, 12560.99512039053, 18385.63228735418, 18763.530911727044, 18786.342478013205, 18839.0, 18872.905635471736, 6922.3732422880585, 7423.962880185564, 7979.042514898133, 8012.3590112790835, 8093.109757926623, 857.2541888556996, 919.9218066464292, 1025.9384436933808, 1053.4048262731533, 1059.9470026498643, 27063.86468067647, 29746.553801692993, 29913.953011644488, 29914.102576922727, 30020.399592008474, 1038.8649475568127, 1092.1044743310408, 1134.3534185514343, 1157.2188772578477, 1161.5702190189515, 14793.556193314218, 15265.389384424721, 15335.61660958485, 15448.536543903701, 15731.292091855894, 605912.0, 617787.0, 618026.0, 620607.0, 620769.0, 51276.23085653718, 55650.72174639182, 55680.72159639257, 55948.0, 56031.0, 1370.41757253167, 1385.3142694366354, 1412.0962583946282, 1412.427966673499, 1412.4773833681604, 29121.980730674324, 29171.416571668873, 29211.0, 29886.551701724926, 30036.399272014874, 48396.0, 48429.5157048425, 48821.02357952892, 48838.5116148834, 48884.511154887994, 565990.0, 569143.0, 571963.0, 572877.4410303093, 573209.0, 25740.742592573835, 25980.0, 27030.459390812466, 27036.0, 27399.589006165323, 45704.77147614241, 46006.0, 46470.0, 46473.53526464692, 47330.76334618305, 27759.861200693867, 32485.675143248263, 38374.0, 38568.0, 43298.855688812255, 37092.81453592715, 37690.24619507539, 38305.6169438302, 38541.0, 39063.609363906, 32496.0, 34895.778647747204, 37776.0, 37855.62144378521, 38089.42865856959, 31381.215469613442, 33965.98102056942, 34020.97937061892, 34186.316273674885, 34257.82871085629, 23978.88010559936, 25144.24567262985, 25361.873190633927, 25444.236672899842, 25803.483930321665], "value": 19647.231833472164, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.1583100952304, 373.1957631304539, 380.3552977702794, 383.20293788919054, 395.37333326677214, 19355.322563710273, 20899.26852560161, 21224.681629775565, 21359.786402135975, 21505.462363440904, 19844.60310793787, 20138.59722805543, 20740.274090406838, 20983.0, 21072.473188170265, 4074.3684728867074, 4735.360726301939, 5233.58131349493, 5702.429757024295, 6023.969880150593, 3878.961210387888, 5099.872503187431, 5780.855478613026, 5982.97008514959, 6022.126791615213, 4938.38270216223, 5392.245085688007, 6141.447269745722, 6155.322914479404, 6165.969170154143, 6101.694915254219, 6142.754289828402, 6762.357576030269, 6775.0, 6815.693293801791, 5032.0, 5893.086571581412, 5978.581499295051, 6010.909836352458, 6022.277326720778, 23741.643875341884, 24241.515169696682, 24765.752342476524, 25540.23379298624, 26063.348416289555, 2062.4740691123816, 2136.102836808544, 2144.624690679125, 2175.9020844062056, 2357.846739961897, 9649.276304277208, 10082.294239403245, 10291.433971131584, 10292.176625869912, 15090.0, 1580.316962435003, 1632.877534184941, 1872.8314451699412, 1968.4685135013467, 1973.2797528901847, 97067.0, 113477.4326128364, 116338.0, 118166.4091679536, 120298.3985080069, 9435.952820235854, 10024.147947424404, 11233.213675042669, 11311.812259712777, 11583.884161158281, 11884.940575297242, 13981.860181398055, 14280.071795333477, 14285.428582856777, 14338.784918226027, 6625.436837868739, 7035.859282814315, 8361.871147395024, 8565.657373705106, 8568.143185681382, 8463.830723385498, 8472.19514146155, 8488.957555212184, 8624.310055195569, 8636.265917396966, 3787.394016957274, 4446.5331140230455, 4506.3465797459185, 4569.634429245652, 4623.283391074388, 1586.4288856012058, 1908.9045547722558, 1950.4636225038228, 1957.6117137092724, 1965.6068786242522, 816.9264766171074, 905.6060613633091, 929.9070092990647, 973.425678849468, 988.8170688422521, 19206.711899321523, 20708.792912070687, 20998.790012099988, 21010.24127755592, 21179.0, 904.204300215819, 904.895936967244, 938.174406522256, 959.6928982725461, 970.335320305593, 9212.263018958467, 10714.0, 10812.945935270272, 11144.387058711687, 11171.66485005451, 551083.0, 552769.0, 553141.0, 579500.0, 584948.0, 126769.0, 127207.3639631796, 127946.36026819992, 127983.0, 128070.35964820116, 881.2289246908955, 957.2389949989774, 1029.763154474475, 1035.9171266298679, 1076.8115579773507, 15564.92217538905, 16873.578160546083, 18908.527286817938, 19224.65427420067, 19531.921700357725, 54025.18962215648, 54778.904421912135, 55174.724126380715, 55429.722851385486, 56378.0, 74008.0, 76045.0, 78120.43759124899, 81270.59364703139, 82030.58984705278, 8088.0, 8132.552709601029, 8314.293285070595, 8336.04103035774, 8466.407351485337, 12706.745865082832, 13142.014348923678, 13226.4048117833, 13439.395227214995, 13480.258585777876, 11363.375014373958, 11458.885411145782, 11562.248453850472, 11802.704932376415, 11871.881281187078, 9276.304277179373, 11202.047825934887, 11271.943640281746, 11306.265092768943, 11604.88395116038, 11298.60454884075, 11492.195546311677, 11758.647240582795, 11830.940845295718, 11855.703607409883, 7617.27635874591, 7643.5796031218815, 8133.251350959572, 8245.381596380175, 8339.166083391856, 10270.435126068138, 10688.839667405151, 10783.946080269548, 11105.278156920098, 11219.66341009771], "value": 9474.465600221825, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.7609544425168, 1534.2662791046116, 1765.2762367429348, 1881.5578339090303, 1918.1080797429192, 7931.881021784678, 8455.450395724274, 8616.396852220347, 8955.731328060161, 8978.102189781026, 8657.004444488886, 8836.11638836116, 8840.38117331787, 8841.05496790705, 8860.379773415863, 1795.5241860906806, 1949.220311875254, 2179.204590324528, 2413.1312727418117, 2413.7948274396654, 2350.894209760565, 2381.845180063299, 2411.7347091819884, 2413.239829453725, 2421.491486787768, 1871.429214089702, 1902.809719028093, 2260.4461906832767, 2268.54629074185, 2294.162630639821, 2109.7362829646295, 2372.0867466025534, 2380.214529205358, 2384.9999999999914, 2390.8565486070893, 2367.739548649647, 2368.6565448010115, 2372.6203807390907, 2374.9406264843346, 2381.5475059738596, 8804.207621314112, 8924.8661270081, 8927.196552310323, 8940.15068568485, 8955.68655097075, 5262.710550919698, 5334.493223143795, 5385.111456609655, 5402.675839449606, 5408.215808707715, 3496.580410350749, 3553.502509648651, 3643.36241157799, 3673.981630091859, 3703.5926048134684, 1070.4754670211619, 1100.147385776022, 1103.900648941599, 1121.6410748560463, 1127.0757978457668, 11397.316161030365, 11433.428328583537, 11519.769604607945, 11545.595904143314, 11549.653510394617, 4598.988222591024, 4645.953540464552, 4815.494373090845, 4824.107540105092, 4832.903341933212, 9957.850632240523, 9984.400935943866, 9992.25058120644, 10002.399856008662, 10008.899911000797, 5309.734513274321, 5353.839384818461, 5359.464053594687, 5365.839024829262, 5369.865753356197, 4370.082282720601, 4727.149113159664, 4866.221404575251, 4884.096442158212, 4925.285833554115, 3933.4296527003426, 3996.200759848042, 4006.7395619285235, 4053.5338436079637, 4112.280350938634, 1300.336828217616, 1304.373900527733, 1307.405130665545, 1316.3155159317103, 1318.6505576022362, 817.2726273616486, 829.2702421868717, 923.2982932970856, 929.5445231836352, 935.9812803743887, 5907.379725128885, 6001.729922153513, 6015.789447369409, 6226.688665566703, 6325.399087086719, 836.8410002099581, 959.6593209410559, 961.9422834629945, 962.6919385796618, 980.9362391444533, 3900.473436086092, 4020.5979402060143, 4100.528439229467, 4123.690723195772, 4153.439285696392, 10524.947375263228, 10863.076638485818, 10912.454377281105, 10920.617778377877, 10921.781564368668, 10618.044376006197, 10755.731106722395, 10793.136549076055, 10887.510062047228, 10905.18211134168, 7737.5744334062165, 8155.796105097302, 8208.138145494637, 8360.83278334442, 8392.118827523143, 5966.134910438049, 6031.366706495754, 6043.09353596964, 6063.696815159313, 6217.689115544295, 9943.204543636348, 10014.0, 10071.647492337734, 10091.344062635904, 10151.289409741246, 9107.59015844302, 9771.755706107404, 10003.549840257352, 10089.344192627455, 10310.742231443974, 6937.479689023445, 7338.339549440576, 7402.25977402277, 7503.512271702322, 7611.972383728347, 8052.838943220986, 8402.831943361221, 8514.74455766328, 8519.233269005572, 8536.530490822815, 6750.696218670073, 7549.811254718676, 7557.35762460175, 7595.088589369311, 7623.313901748869, 7325.670344834389, 7377.114746230487, 7464.440166987606, 7487.176410594721, 7492.51298665585, 6971.337722916315, 6992.091028166509, 7021.543599666005, 7043.506954513135, 7049.29507049291, 4347.500037495728, 4715.316279139574, 4981.925271121008, 5048.545630893237, 5155.690658560498, 5044.293798868088, 5048.924266135938, 5138.794448222029, 5190.688558686491, 5212.192110222922], "value": 4607.25632830266, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.2707280698693, 298.3868150949798, 310.9113902537776, 313.6925812703549, 316.928691044515, 5353.3843607985, 5455.563554915607, 5484.314460692413, 5618.662880227189, 5674.7439420434675, 4628.514006029369, 5059.59523238141, 5487.067198576242, 5491.450854914507, 5536.861578460541, 4098.426220329149, 4260.169266992938, 4261.76560289184, 4271.743695378272, 4306.978465107686, 4106.568810274923, 4202.835539697058, 4243.6605071594195, 4282.015136518603, 4293.785310734466, 3461.3942560052014, 3827.5646632512808, 4160.0, 4185.665146788265, 4198.244316023115, 3901.824417901187, 4202.432671589327, 4203.390508376268, 4212.49450065994, 4217.903345130258, 3383.5770528683915, 3552.2185119273718, 3565.750397472178, 3565.857365705395, 3651.543557055368, 4897.363342765453, 4938.185234416384, 5248.396434410053, 5367.892642147135, 5424.674519528841, 4130.524989626201, 4400.471943366784, 4763.214069678499, 4776.80892764289, 4781.832635857728, 4692.202325604655, 5119.488051194888, 5326.414094449608, 5343.465653434625, 5745.827625171251, 3872.206197729459, 3884.1066554692297, 3891.455196272524, 3924.3132451820816, 3946.8026598669953, 5452.100403433429, 5901.8819623607715, 6167.568270221132, 6200.875982480326, 6241.750329986795, 3608.6391360863704, 3684.281565094781, 3964.1873415949663, 3997.5802540732802, 4105.69207309447, 4155.2728272552185, 4216.494020717533, 4233.724807887477, 4234.237837189274, 4242.406063151161, 4895.559399654048, 4896.828610998669, 4898.657094003457, 4937.432195297515, 4969.403671559436, 5046.953046953047, 5462.562994960473, 5466.426025267369, 5802.390748971382, 6047.244094488189, 1343.3820442596516, 1394.9232792196324, 1428.557147284345, 1437.4034775568232, 1439.1868594244427, 822.576373167819, 946.3612061858219, 950.6530116507425, 953.7997020625608, 954.2842867849113, 5232.450592687789, 5346.0, 5390.51485366319, 5411.458854114557, 5464.453554644504, 5991.370906054889, 6013.428724271187, 6022.488088512438, 6022.789202377896, 6024.819255422344, 4306.418633484503, 4973.179425394769, 5368.570514358842, 5415.729213539307, 5475.222096753228, 2246.0903334149693, 2288.691026711406, 2616.0974463809957, 2673.2381271337576, 2673.25148958292, 4296.37702533131, 5087.287779710843, 5243.10867152585, 5279.392869819943, 5288.153895376721, 4258.594663760951, 4871.343743127353, 4874.8781280467565, 4943.950560494349, 5021.966103727996, 4710.576048155683, 4732.219183834629, 4994.350734404504, 5223.216517522402, 5246.317978662826, 4099.754014759124, 4439.733615983051, 4535.208592605968, 5001.674221487026, 5041.218611115283, 3930.2117536521387, 4294.76378799169, 5568.526675232489, 6175.660338681236, 6198.01663467686, 5778.942210578009, 6283.397733577908, 6361.809145725636, 6363.90454143179, 6370.872582548416, 3441.5353927219503, 3680.4847321374496, 3735.3463143950244, 3983.3029219885825, 4066.9796651016554, 4884.975575122102, 4942.209246520683, 5717.199592057199, 5845.503132233808, 6078.848028799315, 4986.775595098156, 5927.110933360031, 5950.31571369298, 6014.789482368096, 6042.884990253376, 4937.9259311109645, 5492.423295553909, 5899.262592175978, 5918.319393269657, 5925.140854576149, 4531.0, 5205.315216305205, 5459.699716515471, 5782.421757824184, 5796.275750397306, 5625.493705566519, 5793.899159159915, 5990.46085852258, 6024.307204671519, 6034.969825150846, 4812.711237325771, 5257.0, 5361.51746342831, 5522.86192845166, 5844.736986835687], "value": 4118.538378135287, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.7149226055708, 751.8007727952097, 788.7121200761724, 800.1758189065263, 847.9024912135104, 13444.65553444655, 15022.24888755562, 15659.84340156598, 15931.68136637268, 16244.43144489943, 12435.502579896816, 14573.927130364333, 14829.480968166117, 14830.0, 14856.18290993995, 699.4823830365543, 859.2095272349422, 861.9008813986378, 868.3443999780158, 870.843248215321, 871.1984973824098, 881.2597418168737, 889.3863234368265, 891.073283784863, 895.3687650206589, 916.0656130746635, 931.1479995803832, 950.401247214254, 951.8096380723848, 952.2619969523605, 933.7572231219864, 941.256407438123, 942.3309450290336, 942.5522876633613, 943.8725772020792, 684.7842929477199, 695.8747425463415, 697.0554898113085, 699.1784653032666, 700.908881845362, 15605.68788624221, 15675.529734107995, 15705.68588628221, 15732.921335393365, 15790.842091579052, 14503.492377766834, 14541.982061255716, 14689.853101469062, 14727.55817325482, 14968.0, 11881.881181188164, 12019.819702704466, 12222.083343749255, 12231.388430578436, 12538.749225015448, 3767.4537192107264, 3768.6042965488778, 3959.5644479107277, 4006.779627120507, 4008.7594744315434, 117037.0, 118078.40960795227, 118571.0, 118795.4060229702, 124411.0, 11207.775844483227, 11233.213675042833, 11265.436728163722, 11346.546138154381, 11714.23857449263, 23115.190968316027, 23199.88400057989, 23215.3035408938, 23242.41893952631, 23338.299851004496, 8072.959635201903, 8104.756857294291, 8136.75589732309, 8154.5514996674565, 8353.37349698763, 4414.1171765647, 4428.202923473741, 4441.444819397575, 4508.81964721414, 4613.146567884952, 3574.8927532174075, 3670.15586415126, 3687.760295580779, 3698.334299826003, 3703.000189948699, 668.1481111582674, 668.85619591788, 674.2919934069208, 686.361683634225, 699.9755008574675, 531.2270646209755, 588.9587728858938, 613.8097189871154, 664.6078813499962, 671.3957438305466, 9381.249500039981, 9396.201322887495, 9465.716028519018, 9495.857562136573, 9732.41605503672, 556.5338161255953, 596.5853731656457, 615.7383112177396, 646.8647523596059, 697.1285892634207, 3454.8790792322147, 3795.4686343911876, 4117.238310912491, 4317.287647538184, 4530.569595888452, 113501.0, 159359.0, 163267.0, 166263.0, 167536.97389078347, 135946.28107437797, 137353.3132334332, 141781.0, 141941.0, 146325.26837365958, 67771.66114169397, 77940.0, 116295.0, 118755.40622296833, 120369.79630203586, 8744.169303916118, 8757.562121894007, 8792.340574456814, 8830.867536986822, 8966.730998070068, 61452.385476144664, 62033.68983155055, 65258.02112968404, 66873.33126668671, 68948.0, 101478.0, 102959.0, 103191.0, 103410.93178136245, 104535.0, 10784.676459706221, 11124.443777811239, 11407.828882566933, 12080.154389192672, 12414.131010829517, 14342.067765595202, 14924.552263432115, 14942.925285373503, 15071.773923391378, 15750.606234843763, 13274.336283185996, 13690.726185476435, 14022.228777416933, 14054.297285135906, 14361.85638143647, 13285.513026569011, 13591.252481113634, 14225.64435889111, 14229.50196743109, 14255.786163207353, 13209.669758256121, 13326.93336533311, 13734.725305494034, 13875.653108672364, 14034.719305613626, 9237.0, 11248.212625116163, 11416.714582135513, 11460.885391145981, 12005.39973001364, 8232.644882249091, 11401.408695048578, 11614.477348519506, 11734.295942243492, 11791.410429478663], "value": 7947.694236140809, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1636.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 172.0, 132.0, 115.0, 117.0, 117.0, 116.0, 120.0, 115.0, 115.0], "value": 127.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 113.0, 138.0, 183.0, 144.0, 165.0, 121.0, 141.0, 197.0, 140.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 216.0, 232.0, 199.0, 196.0, 198.0, 187.0, 274.0, 250.0, 191.0], "value": 214.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 88.0, 77.0, 140.0, 65.0, 85.0, 80.0, 92.0, 147.0, 58.0], "value": 90.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 118.0, 71.0, 83.0, 111.0, 145.0, 101.0, 93.0, 115.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 130.0, 104.0, 131.0, 115.0, 85.0, 89.0, 87.0, 77.0, 74.0], "value": 98.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 104.0, 98.0, 151.0, 155.0, 95.0, 95.0, 94.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 92.0, 92.0, 94.0, 97.0, 146.0, 121.0, 93.0, 92.0], "value": 101.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 81.0, 86.0, 85.0, 86.0, 63.0, 59.0, 59.0, 59.0, 59.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 76.0, 73.0, 77.0, 75.0, 69.0, 72.0, 74.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 80.0, 93.0, 94.0, 110.0, 112.0, 88.0, 89.0, 112.0, 115.0], "value": 100.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 136.0, 173.0, 145.0, 127.0, 174.0, 136.0, 192.0, 162.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 177.0, 174.0, 175.0, 178.0, 241.0, 221.0, 172.0, 174.0, 177.0], "value": 186.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 68.0, 71.0, 68.0, 68.0, 68.0, 68.0, 68.0], "value": 68.5, "unit": "score"}]}]} 09:47:25 INFO - Return code: 0 09:47:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:25 ERROR - # TBPL SUCCESS # 09:47:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:25 INFO - 2016-08-31 09:47:25.584 screenresolution[1937:13802] starting screenresolution argv=screenresolution get 09:47:25 INFO - 2016-08-31 09:47:25.587 screenresolution[1937:13802] Display 0: 1600x1200x32@60 09:47:25 INFO - 2016-08-31 09:47:25.593 screenresolution[1938:13805] starting screenresolution argv=screenresolution list 09:47:25 INFO - Available Modes on Display 0 09:47:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:25 INFO - Intel Iris: 09:47:25 INFO - Chipset Model: Intel Iris 09:47:25 INFO - Type: GPU 09:47:25 INFO - Bus: Built-In 09:47:25 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:25 INFO - Vendor: Intel (0x8086) 09:47:25 INFO - Device ID: 0x0a2e 09:47:25 INFO - Revision ID: 0x0009 09:47:25 INFO - Displays: 09:47:25 INFO - Display: 09:47:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:25 INFO - Display Serial Number: 1600x1200 60 09:47:25 INFO - Main Display: Yes 09:47:25 INFO - Mirror: Off 09:47:25 INFO - Online: Yes 09:47:25 INFO - Rotation: Supported 09:47:25 INFO - Return code: 0 09:47:25 INFO - Running post-action listener: _resource_record_post_action 09:47:25 INFO - [mozharness: 2016-08-31 16:47:25.828980Z] Finished run-tests step (success) 09:47:25 INFO - Running post-run listener: _resource_record_post_run 09:47:25 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1024000; Write bytes: 707456000; Read time: 10; Write time: 2025 09:47:25 INFO - TinderboxPrint: CPU usage
54.8% 09:47:25 INFO - TinderboxPrint: I/O read bytes / time
1,024,000 / 10 09:47:25 INFO - TinderboxPrint: I/O write bytes / time
707,456,000 / 2,025 09:47:25 INFO - TinderboxPrint: CPU idle
2,190.8 (45.2%) 09:47:25 INFO - TinderboxPrint: CPU system
984.1 (20.3%) 09:47:25 INFO - TinderboxPrint: CPU user
1,666.9 (34.4%) 09:47:25 INFO - TinderboxPrint: Swap in / out
407,703,552 / 0 09:47:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402782720; Write bytes: 397326336; Read time: 29839; Write time: 852 09:47:25 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 1024000; Write bytes: 297452544; Read time: 10; Write time: 1137 09:47:26 INFO - Running post-run listener: _upload_blobber_files 09:47:26 INFO - Blob upload gear active. 09:47:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:47:26 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:47:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:47:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:28 INFO - (blobuploader) - INFO - Done attempting. 09:47:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:47:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:47:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:30 INFO - (blobuploader) - INFO - Done attempting. 09:47:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:32 INFO - (blobuploader) - INFO - Done attempting. 09:47:32 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:32 INFO - Return code: 0 09:47:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abb808a5785bfeaba45f5007ea06b04ae8d36f3561212ab2235154f69fd92e11150fe5a2b52f87f53417b6c707e76e6f3e08c7eb022795f4fc7386d415d88ab1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/326a665a1372b6399b12aff1e84df4604d1d7f04a0342afa871b40276ff479b96503a1a93fe8410384075aea198b6d4680fd6cf24a52f6d0207fcdefe80f8e1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a197be56dc081f452d1f74243f437919b6364e8c6bd275a2c6791442e8203b11362ef9a08104c082b1e33cdcb64fb1d151633f13ef57432a3438c9e9b69cc5b1"} 09:47:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:32 INFO - Contents: 09:47:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abb808a5785bfeaba45f5007ea06b04ae8d36f3561212ab2235154f69fd92e11150fe5a2b52f87f53417b6c707e76e6f3e08c7eb022795f4fc7386d415d88ab1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/326a665a1372b6399b12aff1e84df4604d1d7f04a0342afa871b40276ff479b96503a1a93fe8410384075aea198b6d4680fd6cf24a52f6d0207fcdefe80f8e1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a197be56dc081f452d1f74243f437919b6364e8c6bd275a2c6791442e8203b11362ef9a08104c082b1e33cdcb64fb1d151633f13ef57432a3438c9e9b69cc5b1"} 09:47:32 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:32 INFO - Copying logs to upload dir... 09:47:32 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.340410 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-31 09:47:32.705873) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 09:47:32.710858) ========= 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.jcex9KQEyN/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.7XkELpSRlZ/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abb808a5785bfeaba45f5007ea06b04ae8d36f3561212ab2235154f69fd92e11150fe5a2b52f87f53417b6c707e76e6f3e08c7eb022795f4fc7386d415d88ab1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/326a665a1372b6399b12aff1e84df4604d1d7f04a0342afa871b40276ff479b96503a1a93fe8410384075aea198b6d4680fd6cf24a52f6d0207fcdefe80f8e1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a197be56dc081f452d1f74243f437919b6364e8c6bd275a2c6791442e8203b11362ef9a08104c082b1e33cdcb64fb1d151633f13ef57432a3438c9e9b69cc5b1"} build_url:https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/abKAJjgzSD-bejk5SEfXeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abb808a5785bfeaba45f5007ea06b04ae8d36f3561212ab2235154f69fd92e11150fe5a2b52f87f53417b6c707e76e6f3e08c7eb022795f4fc7386d415d88ab1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/326a665a1372b6399b12aff1e84df4604d1d7f04a0342afa871b40276ff479b96503a1a93fe8410384075aea198b6d4680fd6cf24a52f6d0207fcdefe80f8e1b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a197be56dc081f452d1f74243f437919b6364e8c6bd275a2c6791442e8203b11362ef9a08104c082b1e33cdcb64fb1d151633f13ef57432a3438c9e9b69cc5b1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 09:47:32.744873) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 09:47:32.745228) ========= 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.jcex9KQEyN/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.7XkELpSRlZ/Listeners TMPDIR=/var/folders/m5/r6dgwqsj10g93g673s3k_wyc00000w/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.005041 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 09:47:32.794277) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 09:47:32.794602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 09:47:32.794951) ========= ========= Total master_lag: 0.19 =========