builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0107 starttime: 1470660741.07 results: success (0) buildid: 20160808031752 builduid: c9d2f9c3ca074b0999bdc0a8216d97a6 revision: 1fa92a8698a9f605528701fb8cf33608e3e19650 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.071530) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.071987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.072270) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.097016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.097369) ========= 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.FYfHeYurGY/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.YRsOFG4qID/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-08 05:52: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% 7.93M=0.001s 2016-08-08 05:52:21 (7.93 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105177 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.225008) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.225338) ========= 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.FYfHeYurGY/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.YRsOFG4qID/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.027560 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:21.274162) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:52:21.274528) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 1fa92a8698a9f605528701fb8cf33608e3e19650 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 1fa92a8698a9f605528701fb8cf33608e3e19650 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FYfHeYurGY/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.YRsOFG4qID/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-08 05:52:21,350 truncating revision to first 12 chars 2016-08-08 05:52:21,350 Setting DEBUG logging. 2016-08-08 05:52:21,350 attempt 1/10 2016-08-08 05:52:21,350 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/1fa92a8698a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 05:52:22,558 unpacking tar archive at: autoland-1fa92a8698a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.486438 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:52:22.783623) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:22.783988) ========= script_repo_revision: 1fa92a8698a9f605528701fb8cf33608e3e19650 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:22.784451) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:22.784737) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:52:22.803649) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-08 05:52:22.803955) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FYfHeYurGY/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.YRsOFG4qID/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:52:23 INFO - MultiFileLogger online at 20160808 05:52:23 in /builds/slave/test 05:52:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 05:52:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:52:23 INFO - {'append_to_log': False, 05:52:23 INFO - 'base_work_dir': '/builds/slave/test', 05:52:23 INFO - 'blob_upload_branch': 'Autoland', 05:52:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:52:23 INFO - 'branch': 'Autoland', 05:52:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:52:23 INFO - 'config_files': ('talos/mac_config.py',), 05:52:23 INFO - 'default_actions': ('clobber', 05:52:23 INFO - 'read-buildbot-config', 05:52:23 INFO - 'download-and-extract', 05:52:23 INFO - 'populate-webroot', 05:52:23 INFO - 'create-virtualenv', 05:52:23 INFO - 'install', 05:52:23 INFO - 'run-tests'), 05:52:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:52:23 INFO - 'download_minidump_stackwalk': True, 05:52:23 INFO - 'download_symbols': 'ondemand', 05:52:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:52:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:52:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:52:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:52:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:52:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:52:23 INFO - 'installer_path': 'installer.exe', 05:52:23 INFO - 'log_level': 'info', 05:52:23 INFO - 'log_name': 'talos', 05:52:23 INFO - 'log_to_console': True, 05:52:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:52:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:52:23 INFO - 'opt_config_files': (), 05:52:23 INFO - 'pip_index': False, 05:52:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:52:23 INFO - 'cmd': ('bash', 05:52:23 INFO - '-c', 05:52:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:52:23 INFO - 'enabled': True, 05:52:23 INFO - 'halt_on_failure': False, 05:52:23 INFO - 'name': 'check_screen_resolution'},), 05:52:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:52:23 INFO - 'cmd': ('bash', 05:52:23 INFO - '-c', 05:52:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:52:23 INFO - 'enabled': True, 05:52:23 INFO - 'halt_on_failure': False, 05:52:23 INFO - 'name': 'check_screen_resolution'},), 05:52:23 INFO - 'run_cmd_checks_enabled': True, 05:52:23 INFO - 'sps_profile': False, 05:52:23 INFO - 'sps_profile_interval': 0, 05:52:23 INFO - 'suite': 'dromaeojs-e10s', 05:52:23 INFO - 'system_bits': '32', 05:52:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:52:23 INFO - 'title': 't-yosemite-r7-0107', 05:52:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:52:23 INFO - 'use_talos_json': True, 05:52:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:52:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:52:23 INFO - 'work_dir': 'build'} 05:52:23 INFO - [mozharness: 2016-08-08 12:52:23.014989Z] Running clobber step. 05:52:23 INFO - Running pre-action listener: _resource_record_pre_action 05:52:23 INFO - Running main action method: clobber 05:52:23 INFO - rmtree: /builds/slave/test/build 05:52:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:52:25 INFO - Running post-action listener: _resource_record_post_action 05:52:25 INFO - [mozharness: 2016-08-08 12:52:25.164828Z] Finished clobber step (success) 05:52:25 INFO - [mozharness: 2016-08-08 12:52:25.164957Z] Running read-buildbot-config step. 05:52:25 INFO - Running pre-action listener: _resource_record_pre_action 05:52:25 INFO - Running main action method: read_buildbot_config 05:52:25 INFO - Using buildbot properties: 05:52:25 INFO - { 05:52:25 INFO - "project": "", 05:52:25 INFO - "product": "firefox", 05:52:25 INFO - "script_repo_revision": "production", 05:52:25 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 05:52:25 INFO - "repository": "", 05:52:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 05:52:25 INFO - "buildid": "20160808031752", 05:52:25 INFO - "pgo_build": "False", 05:52:25 INFO - "basedir": "/builds/slave/test", 05:52:25 INFO - "buildnumber": 115, 05:52:25 INFO - "slavename": "t-yosemite-r7-0107", 05:52:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:52:25 INFO - "platform": "yosemite_r7", 05:52:25 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:52:25 INFO - "branch": "autoland", 05:52:25 INFO - "repo_path": "integration/autoland", 05:52:25 INFO - "revision": "1fa92a8698a9f605528701fb8cf33608e3e19650", 05:52:25 INFO - "stage_platform": "macosx64", 05:52:25 INFO - "builduid": "c9d2f9c3ca074b0999bdc0a8216d97a6", 05:52:25 INFO - "slavebuilddir": "test" 05:52:25 INFO - } 05:52:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:52:25 INFO - Running post-action listener: _resource_record_post_action 05:52:25 INFO - [mozharness: 2016-08-08 12:52:25.168486Z] Finished read-buildbot-config step (success) 05:52:25 INFO - [mozharness: 2016-08-08 12:52:25.168570Z] Running download-and-extract step. 05:52:25 INFO - Running pre-action listener: _resource_record_pre_action 05:52:25 INFO - Running main action method: download_and_extract 05:52:25 INFO - mkdir: /builds/slave/test/build/tests 05:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:25 INFO - trying https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:52:25 INFO - Downloading https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:26 INFO - Downloaded 1199 bytes. 05:52:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:52:26 INFO - Using the following test package requirements: 05:52:26 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:52:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:52:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:52:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'jsshell-mac.zip'], 05:52:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:52:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:52:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:52:26 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:52:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:52:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:52:26 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:52:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:52:26 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')]} 05:52:26 INFO - trying https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:52:26 INFO - Downloading https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:27 INFO - Downloaded 16324264 bytes. 05:52:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:52:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:52:30 INFO - Return code: 0 05:52:30 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 05:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:30 INFO - trying https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:52:30 INFO - Downloading https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:31 INFO - Downloaded 16324264 bytes. 05:52:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:52:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:52:35 INFO - Return code: 0 05:52:35 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')]} 05:52:35 INFO - trying https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:52:35 INFO - Downloading https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:36 INFO - Downloaded 14413723 bytes. 05:52:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:52:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:52:36 INFO - Return code: 0 05:52:36 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')]} 05:52:36 INFO - trying https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:52:36 INFO - Downloading https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:52:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/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 05:52:39 INFO - Downloaded 111979244 bytes. 05:52:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:52:39 INFO - mkdir: /builds/slave/test/properties 05:52:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:52:39 INFO - Writing to file /builds/slave/test/properties/build_url 05:52:39 INFO - Contents: 05:52:39 INFO - build_url:https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:52:40 INFO - Running post-action listener: _resource_record_post_action 05:52:40 INFO - Running post-action listener: set_extra_try_arguments 05:52:40 INFO - [mozharness: 2016-08-08 12:52:40.044866Z] Finished download-and-extract step (success) 05:52:40 INFO - [mozharness: 2016-08-08 12:52:40.044979Z] Running populate-webroot step. 05:52:40 INFO - Running pre-action listener: _resource_record_pre_action 05:52:40 INFO - Running main action method: populate_webroot 05:52:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:52:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:52:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:52:40 INFO - '--tppagecycles', 05:52:40 INFO - '7'], 05:52:40 INFO - 'tests': ['tsvgm']}}, 05:52:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:52:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:52:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:52:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:52:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['damp', 'tps']}, 05:52:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'tests': ['damp', 'tps']}, 05:52:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['dromaeo_dom']}, 05:52:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:52:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['basic_compositor_video']}, 05:52:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:52:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['a11yr', 05:52:40 INFO - 'ts_paint', 05:52:40 INFO - 'tpaint', 05:52:40 INFO - 'sessionrestore', 05:52:40 INFO - 'sessionrestore_no_auto_restore', 05:52:40 INFO - 'tabpaint']}, 05:52:40 INFO - 'other-e10s': {'tests': ['a11yr', 05:52:40 INFO - 'ts_paint', 05:52:40 INFO - 'tpaint', 05:52:40 INFO - 'sessionrestore', 05:52:40 INFO - 'sessionrestore_no_auto_restore', 05:52:40 INFO - 'tabpaint']}, 05:52:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:52:40 INFO - 'ts_paint', 05:52:40 INFO - 'tpaint', 05:52:40 INFO - 'sessionrestore', 05:52:40 INFO - 'sessionrestore_no_auto_restore', 05:52:40 INFO - 'tabpaint']}, 05:52:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:52:40 INFO - 'ts_paint', 05:52:40 INFO - 'tpaint', 05:52:40 INFO - 'sessionrestore', 05:52:40 INFO - 'sessionrestore_no_auto_restore', 05:52:40 INFO - 'tabpaint']}, 05:52:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['a11yr', 05:52:40 INFO - 'ts_paint', 05:52:40 INFO - 'tpaint', 05:52:40 INFO - 'sessionrestore', 05:52:40 INFO - 'sessionrestore_no_auto_restore', 05:52:40 INFO - 'tabpaint']}, 05:52:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['a11yr', 05:52:40 INFO - 'ts_paint', 05:52:40 INFO - 'tpaint', 05:52:40 INFO - 'sessionrestore', 05:52:40 INFO - 'sessionrestore_no_auto_restore', 05:52:40 INFO - 'tabpaint']}, 05:52:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['tsvgx', 05:52:40 INFO - 'tsvgr_opacity', 05:52:40 INFO - 'tart', 05:52:40 INFO - 'tscrollx', 05:52:40 INFO - 'cart']}, 05:52:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:52:40 INFO - 'tsvgr_opacity', 05:52:40 INFO - 'tart', 05:52:40 INFO - 'tscrollx', 05:52:40 INFO - 'cart']}, 05:52:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'talos_options': ['--disable-e10s'], 05:52:40 INFO - 'tests': ['tp5o']}, 05:52:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'tests': ['tp5o']}, 05:52:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'talos_options': ['--disable-e10s', 05:52:40 INFO - '--xperf_path', 05:52:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:40 INFO - 'tests': ['tp5n']}, 05:52:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:40 INFO - 'talos_options': ['--xperf_path', 05:52:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:40 INFO - 'tests': ['tp5n']}}, 05:52:40 INFO - 'talos.zip': {'path': '', 05:52:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:52:40 INFO - Running post-action listener: _resource_record_post_action 05:52:40 INFO - [mozharness: 2016-08-08 12:52:40.064758Z] Finished populate-webroot step (success) 05:52:40 INFO - [mozharness: 2016-08-08 12:52:40.064892Z] Running create-virtualenv step. 05:52:40 INFO - Running pre-action listener: _resource_record_pre_action 05:52:40 INFO - Running main action method: create_virtualenv 05:52:40 INFO - Creating virtualenv /builds/slave/test/build/venv 05:52:40 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 05:52:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:52:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:52:40 INFO - Using real prefix '/tools/python27' 05:52:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:52:40 INFO - Installing distribute.............................................................................................................................................................................................done. 05:52:43 INFO - Installing pip.................done. 05:52:43 INFO - Return code: 0 05:52:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:43 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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:52:43 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 05:52:43 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 05:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:43 INFO - 'HOME': '/Users/cltbld', 05:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:43 INFO - 'LOGNAME': 'cltbld', 05:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:43 INFO - 'MOZ_NO_REMOTE': '1', 05:52:43 INFO - 'NO_EM_RESTART': '1', 05:52:43 INFO - 'PAGER': '/bin/cat', 05:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:43 INFO - 'PWD': '/builds/slave/test', 05:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:43 INFO - 'SHELL': '/bin/bash', 05:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:52:43 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:43 INFO - 'USER': 'cltbld', 05:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:43 INFO - 'XPC_FLAGS': '0x0', 05:52:43 INFO - 'XPC_SERVICE_NAME': '0', 05:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:44 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 05:52:44 INFO - Cleaning up... 05:52:44 INFO - Return code: 0 05:52:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:52:44 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')]} 05:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:44 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')]} 05:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:44 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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:52:44 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 05:52:44 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 05:52:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:52:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:44 INFO - 'HOME': '/Users/cltbld', 05:52:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:44 INFO - 'LOGNAME': 'cltbld', 05:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:44 INFO - 'MOZ_NO_REMOTE': '1', 05:52:44 INFO - 'NO_EM_RESTART': '1', 05:52:44 INFO - 'PAGER': '/bin/cat', 05:52:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:44 INFO - 'PWD': '/builds/slave/test', 05:52:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:44 INFO - 'SHELL': '/bin/bash', 05:52:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:52:44 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:52:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:44 INFO - 'USER': 'cltbld', 05:52:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:44 INFO - 'XPC_FLAGS': '0x0', 05:52:44 INFO - 'XPC_SERVICE_NAME': '0', 05:52:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:44 INFO - Downloading/unpacking psutil>=3.1.1 05:52:44 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 05:52:44 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 05:52:44 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 05:52:44 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 05:52:44 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 05:52:44 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 05:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:52:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:47 INFO - Installing collected packages: psutil 05:52:47 INFO - Running setup.py install for psutil 05:52:47 INFO - building 'psutil._psutil_osx' extension 05:52:47 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 05:52:48 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 05:52:48 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 05:52:48 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 05:52:48 INFO - building 'psutil._psutil_posix' extension 05:52:48 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 05:52:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:52:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:52:48 INFO - ^ 05:52:48 INFO - 1 warning generated. 05:52:48 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 05:52:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:48 INFO - Successfully installed psutil 05:52:48 INFO - Cleaning up... 05:52:48 INFO - Return code: 0 05:52:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:52: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')]} 05:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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')]} 05:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:52: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:52: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 mozsystemmonitor==0.3 05:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:48 INFO - 'HOME': '/Users/cltbld', 05:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:48 INFO - 'LOGNAME': 'cltbld', 05:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:48 INFO - 'MOZ_NO_REMOTE': '1', 05:52:48 INFO - 'NO_EM_RESTART': '1', 05:52:48 INFO - 'PAGER': '/bin/cat', 05:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:48 INFO - 'PWD': '/builds/slave/test', 05:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:48 INFO - 'SHELL': '/bin/bash', 05:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:52:48 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:48 INFO - 'USER': 'cltbld', 05:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:48 INFO - 'XPC_FLAGS': '0x0', 05:52:48 INFO - 'XPC_SERVICE_NAME': '0', 05:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:52: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 05:52: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 05:52: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 05:52: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 05:52: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 05:52: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 05:52:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:52:52 INFO - Installing collected packages: mozsystemmonitor 05:52:52 INFO - Running setup.py install for mozsystemmonitor 05:52:52 INFO - Successfully installed mozsystemmonitor 05:52:52 INFO - Cleaning up... 05:52:52 INFO - Return code: 0 05:52:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:52:52 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')]} 05:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:52 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')]} 05:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:52 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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:52:52 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 05:52:52 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 05:52:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:52:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:52 INFO - 'HOME': '/Users/cltbld', 05:52:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:52 INFO - 'LOGNAME': 'cltbld', 05:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:52 INFO - 'MOZ_NO_REMOTE': '1', 05:52:52 INFO - 'NO_EM_RESTART': '1', 05:52:52 INFO - 'PAGER': '/bin/cat', 05:52:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:52 INFO - 'PWD': '/builds/slave/test', 05:52:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:52 INFO - 'SHELL': '/bin/bash', 05:52:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:52:52 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:52:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:52 INFO - 'USER': 'cltbld', 05:52:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:52 INFO - 'XPC_FLAGS': '0x0', 05:52:52 INFO - 'XPC_SERVICE_NAME': '0', 05:52:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:52 INFO - Downloading/unpacking jsonschema==2.5.1 05:52:52 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 05:52:52 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 05:52:52 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 05:52:52 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 05:52:52 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 05:52:52 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 05:52:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:52:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:52:55 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 05:52:55 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 05:52:55 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 05:52:55 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 05:52:55 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 05:52:55 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 05:52:55 INFO - Downloading functools32-3.2.3-2.tar.gz 05:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:52:55 INFO - warning: no files found matching '*.txt' 05:52:55 INFO - no previously-included directories found matching 'build' 05:52:55 INFO - no previously-included directories found matching 'dist' 05:52:55 INFO - no previously-included directories found matching '.git*' 05:52:55 INFO - Installing collected packages: jsonschema, functools32 05:52:55 INFO - Running setup.py install for functools32 05:52:55 INFO - warning: no files found matching '*.txt' 05:52:55 INFO - no previously-included directories found matching 'build' 05:52:55 INFO - no previously-included directories found matching 'dist' 05:52:55 INFO - no previously-included directories found matching '.git*' 05:52:55 INFO - Successfully installed jsonschema functools32 05:52:55 INFO - Cleaning up... 05:52:56 INFO - Return code: 0 05:52:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:52: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')]} 05:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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')]} 05:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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', '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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:52: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:52: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 functools32==3.2.3-2 05:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:56 INFO - 'HOME': '/Users/cltbld', 05:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:56 INFO - 'LOGNAME': 'cltbld', 05:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:56 INFO - 'MOZ_NO_REMOTE': '1', 05:52:56 INFO - 'NO_EM_RESTART': '1', 05:52:56 INFO - 'PAGER': '/bin/cat', 05:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:56 INFO - 'PWD': '/builds/slave/test', 05:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:56 INFO - 'SHELL': '/bin/bash', 05:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:52:56 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:56 INFO - 'USER': 'cltbld', 05:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:56 INFO - 'XPC_FLAGS': '0x0', 05:52:56 INFO - 'XPC_SERVICE_NAME': '0', 05:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:52:56 INFO - Cleaning up... 05:52:56 INFO - Return code: 0 05:52:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:52: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')]} 05:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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')]} 05:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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', '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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:52: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 blobuploader==1.2.4 05:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:56 INFO - 'HOME': '/Users/cltbld', 05:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:56 INFO - 'LOGNAME': 'cltbld', 05:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:56 INFO - 'MOZ_NO_REMOTE': '1', 05:52:56 INFO - 'NO_EM_RESTART': '1', 05:52:56 INFO - 'PAGER': '/bin/cat', 05:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:56 INFO - 'PWD': '/builds/slave/test', 05:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:56 INFO - 'SHELL': '/bin/bash', 05:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:52:56 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:56 INFO - 'USER': 'cltbld', 05:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:56 INFO - 'XPC_FLAGS': '0x0', 05:52:56 INFO - 'XPC_SERVICE_NAME': '0', 05:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:56 INFO - Downloading/unpacking blobuploader==1.2.4 05:52: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 05:52: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 05:52: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 05:52: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 05:52: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 05:52: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 05:52:58 INFO - Downloading blobuploader-1.2.4.tar.gz 05:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:52:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:52:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:53:00 INFO - Downloading docopt-0.6.1.tar.gz 05:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:53:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:53:00 INFO - Running setup.py install for blobuploader 05:53:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:53:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:53:00 INFO - Running setup.py install for requests 05:53:00 INFO - Running setup.py install for docopt 05:53:01 INFO - Successfully installed blobuploader requests docopt 05:53:01 INFO - Cleaning up... 05:53:01 INFO - Return code: 0 05:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:01 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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:53:01 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 05:53:01 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 05:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:01 INFO - 'HOME': '/Users/cltbld', 05:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:01 INFO - 'LOGNAME': 'cltbld', 05:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:01 INFO - 'MOZ_NO_REMOTE': '1', 05:53:01 INFO - 'NO_EM_RESTART': '1', 05:53:01 INFO - 'PAGER': '/bin/cat', 05:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:01 INFO - 'PWD': '/builds/slave/test', 05:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:01 INFO - 'SHELL': '/bin/bash', 05:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:53:01 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:01 INFO - 'USER': 'cltbld', 05:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:01 INFO - 'XPC_FLAGS': '0x0', 05:53:01 INFO - 'XPC_SERVICE_NAME': '0', 05:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:53:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-SNlsqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:53:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-OwyMFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:53:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-CrNQtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:53:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-IOzwRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:53:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-wLeD0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:53:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-JmfULa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:53:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-_Pot5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-vHuxbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-GxjCma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-dEytfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-HVSFQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-aRJQn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-DTFdQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-kBToGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-q9YiJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:53:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-8SX3Bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:53:03 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-fAT5L1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:53:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:53:03 INFO - Running setup.py install for manifestparser 05:53:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:53:03 INFO - Running setup.py install for mozcrash 05:53:03 INFO - Running setup.py install for mozdebug 05:53:03 INFO - Running setup.py install for mozdevice 05:53:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:53:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:53:03 INFO - Running setup.py install for mozfile 05:53:03 INFO - Running setup.py install for mozhttpd 05:53:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:53:03 INFO - Running setup.py install for mozinfo 05:53:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:53:04 INFO - Running setup.py install for mozInstall 05:53:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:53:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:53:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:53:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:53:04 INFO - Running setup.py install for mozleak 05:53:04 INFO - Running setup.py install for mozlog 05:53:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:53:04 INFO - Running setup.py install for moznetwork 05:53:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:53:04 INFO - Running setup.py install for mozprocess 05:53:04 INFO - Running setup.py install for mozprofile 05:53:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:53:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:53:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:53:05 INFO - Running setup.py install for mozrunner 05:53:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:53:05 INFO - Running setup.py install for mozscreenshot 05:53:05 INFO - Running setup.py install for moztest 05:53:05 INFO - Running setup.py install for mozversion 05:53:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:53:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:53:05 INFO - Cleaning up... 05:53:05 INFO - Return code: 0 05:53:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:05 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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:53:05 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 05:53:05 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 05:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:05 INFO - 'HOME': '/Users/cltbld', 05:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:05 INFO - 'LOGNAME': 'cltbld', 05:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:05 INFO - 'MOZ_NO_REMOTE': '1', 05:53:05 INFO - 'NO_EM_RESTART': '1', 05:53:05 INFO - 'PAGER': '/bin/cat', 05:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:05 INFO - 'PWD': '/builds/slave/test', 05:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:05 INFO - 'SHELL': '/bin/bash', 05:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:53:05 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:05 INFO - 'USER': 'cltbld', 05:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:05 INFO - 'XPC_FLAGS': '0x0', 05:53:05 INFO - 'XPC_SERVICE_NAME': '0', 05:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:53:05 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-oAYmaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-hKs9Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-H2fGqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-yI_PEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-orHyN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-2IM4QE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-aXL_IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-CDbg6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-fCAV42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:53:06 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)) 05:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:53:06 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-oqUIcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:53:07 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)) 05:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:53:07 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-HfJNUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:53:07 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)) 05:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:53:07 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-i9r6ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:53:07 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)) 05:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:53:07 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-IMuUOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:53:07 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)) 05:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:53:07 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-0yf0Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:53:07 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)) 05:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:07 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-oUfvnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:53:07 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)) 05:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:53:07 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-gcQL1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:53:07 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)) 05:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:53:07 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-XKZgcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:53:07 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)) 05:53:07 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)) 05:53:07 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)) 05:53:07 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)) 05:53:07 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)) 05:53:07 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)) 05:53:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:53:07 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 05:53:07 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 05:53:07 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 05:53:07 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 05:53:07 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 05:53:07 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 05:53:10 INFO - Downloading blessings-1.6.tar.gz 05:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:53:10 INFO - Installing collected packages: blessings 05:53:10 INFO - Running setup.py install for blessings 05:53:10 INFO - Successfully installed blessings 05:53:10 INFO - Cleaning up... 05:53:10 INFO - Return code: 0 05:53:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:53:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:53:10 INFO - Reading from file tmpfile_stdout 05:53:10 INFO - Current package versions: 05:53:10 INFO - blessings == 1.6 05:53:10 INFO - blobuploader == 1.2.4 05:53:10 INFO - docopt == 0.6.1 05:53:10 INFO - functools32 == 3.2.3-2 05:53:10 INFO - jsonschema == 2.5.1 05:53:10 INFO - manifestparser == 1.1 05:53:10 INFO - mozInstall == 1.12 05:53:10 INFO - mozcrash == 0.17 05:53:10 INFO - mozdebug == 0.1 05:53:10 INFO - mozdevice == 0.48 05:53:10 INFO - mozfile == 1.2 05:53:10 INFO - mozhttpd == 0.7 05:53:10 INFO - mozinfo == 0.9 05:53:10 INFO - mozleak == 0.1 05:53:10 INFO - mozlog == 3.3 05:53:10 INFO - moznetwork == 0.27 05:53:10 INFO - mozprocess == 0.23 05:53:10 INFO - mozprofile == 0.28 05:53:10 INFO - mozrunner == 6.12 05:53:10 INFO - mozscreenshot == 0.1 05:53:10 INFO - mozsystemmonitor == 0.3 05:53:10 INFO - moztest == 0.7 05:53:10 INFO - mozversion == 1.4 05:53:10 INFO - psutil == 3.1.1 05:53:10 INFO - requests == 1.2.3 05:53:10 INFO - wsgiref == 0.1.2 05:53:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:10 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 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:53:10 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 05:53:10 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 05:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:10 INFO - 'HOME': '/Users/cltbld', 05:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:10 INFO - 'LOGNAME': 'cltbld', 05:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:10 INFO - 'MOZ_NO_REMOTE': '1', 05:53:10 INFO - 'NO_EM_RESTART': '1', 05:53:10 INFO - 'PAGER': '/bin/cat', 05:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:10 INFO - 'PWD': '/builds/slave/test', 05:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:10 INFO - 'SHELL': '/bin/bash', 05:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:53:10 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:10 INFO - 'USER': 'cltbld', 05:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:10 INFO - 'XPC_FLAGS': '0x0', 05:53:10 INFO - 'XPC_SERVICE_NAME': '0', 05:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 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)) 05:53:11 INFO - Cleaning up... 05:53:11 INFO - Return code: 0 05:53:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:53:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10437e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda39d95410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, '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.FYfHeYurGY/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.YRsOFG4qID/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 05:53:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:53:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:53:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:11 INFO - 'HOME': '/Users/cltbld', 05:53:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:11 INFO - 'LOGNAME': 'cltbld', 05:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:11 INFO - 'MOZ_NO_REMOTE': '1', 05:53:11 INFO - 'NO_EM_RESTART': '1', 05:53:11 INFO - 'PAGER': '/bin/cat', 05:53:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:11 INFO - 'PWD': '/builds/slave/test', 05:53:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:11 INFO - 'SHELL': '/bin/bash', 05:53:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:53:11 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:53:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:11 INFO - 'USER': 'cltbld', 05:53:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:11 INFO - 'XPC_FLAGS': '0x0', 05:53:11 INFO - 'XPC_SERVICE_NAME': '0', 05:53:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:53:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:53:11 INFO - Cleaning up... 05:53:11 INFO - Return code: 0 05:53:11 INFO - Running post-action listener: _resource_record_post_action 05:53:11 INFO - Running post-action listener: _start_resource_monitoring 05:53:11 INFO - Starting resource monitoring. 05:53:11 INFO - [mozharness: 2016-08-08 12:53:11.360079Z] Finished create-virtualenv step (success) 05:53:11 INFO - [mozharness: 2016-08-08 12:53:11.361021Z] Running install step. 05:53:11 INFO - Running pre-action listener: _resource_record_pre_action 05:53:11 INFO - Running main action method: install 05:53:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:53:11 INFO - Reading from file tmpfile_stdout 05:53:11 INFO - Detecting whether we're running mozinstall >=1.0... 05:53:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:53:11 INFO - Reading from file tmpfile_stdout 05:53:11 INFO - Output received: 05:53:11 INFO - Usage: mozinstall [options] installer 05:53:11 INFO - Options: 05:53:11 INFO - -h, --help show this help message and exit 05:53:11 INFO - -d DEST, --destination=DEST 05:53:11 INFO - Directory to install application into. [default: 05:53:11 INFO - "/builds/slave/test"] 05:53:11 INFO - --app=APP Application being installed. [default: firefox] 05:53:11 INFO - mkdir: /builds/slave/test/build/application 05:53:11 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'] 05:53:11 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 05:53:46 INFO - Reading from file tmpfile_stdout 05:53:46 INFO - Output received: 05:53:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:53:46 INFO - Running post-action listener: _resource_record_post_action 05:53:46 INFO - [mozharness: 2016-08-08 12:53:46.120549Z] Finished install step (success) 05:53:46 INFO - [mozharness: 2016-08-08 12:53:46.120689Z] Running run-tests step. 05:53:46 INFO - Running pre-action listener: _resource_record_pre_action 05:53:46 INFO - Running main action method: run_tests 05:53:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:53:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:53:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:53:46 INFO - 2016-08-08 05:53:46.135 screenresolution[1639:7558] starting screenresolution argv=screenresolution get 05:53:46 INFO - 2016-08-08 05:53:46.137 screenresolution[1639:7558] Display 0: 1600x1200x32@60 05:53:46 INFO - 2016-08-08 05:53:46.143 screenresolution[1640:7560] starting screenresolution argv=screenresolution list 05:53:46 INFO - Available Modes on Display 0 05:53:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:53:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:53:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:53:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:53:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:53:46 INFO - Intel Iris: 05:53:46 INFO - Chipset Model: Intel Iris 05:53:46 INFO - Type: GPU 05:53:46 INFO - Bus: Built-In 05:53:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:53:46 INFO - Vendor: Intel (0x8086) 05:53:46 INFO - Device ID: 0x0a2e 05:53:46 INFO - Revision ID: 0x0009 05:53:46 INFO - Displays: 05:53:46 INFO - Display: 05:53:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:53:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:53:46 INFO - Display Serial Number: 1600x1200 60 05:53:46 INFO - Main Display: Yes 05:53:46 INFO - Mirror: Off 05:53:46 INFO - Online: Yes 05:53:46 INFO - Rotation: Supported 05:53:46 INFO - Return code: 0 05:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:53:46 INFO - Python 2.7.3 05:53:46 INFO - Return code: 0 05:53:46 INFO - grabbing minidump binary from tooltool 05:53:46 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')]} 05:53:46 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 0x10424dd30>, '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 0x103fb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104245b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:53:46 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 05:53:46 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 05:53:46 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 05:53:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:53:46 INFO - Return code: 0 05:53:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:53:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:53:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:53:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:53:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 05:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0107 --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 05:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FYfHeYurGY/Render', 05:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:46 INFO - 'HOME': '/Users/cltbld', 05:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:46 INFO - 'LOGNAME': 'cltbld', 05:53:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:53:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:46 INFO - 'MOZ_NO_REMOTE': '1', 05:53:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:53:46 INFO - 'NO_EM_RESTART': '1', 05:53:46 INFO - 'PAGER': '/bin/cat', 05:53:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:46 INFO - 'PWD': '/builds/slave/test', 05:53:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:46 INFO - 'SHELL': '/bin/bash', 05:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YRsOFG4qID/Listeners', 05:53:46 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 05:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:46 INFO - 'USER': 'cltbld', 05:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:46 INFO - 'XPC_FLAGS': '0x0', 05:53:46 INFO - 'XPC_SERVICE_NAME': '0', 05:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 05:53:46 INFO - mozversion application_buildid: 20160808031752 05:53:46 INFO - mozversion application_changeset: 1fa92a8698a9f605528701fb8cf33608e3e19650 05:53:46 INFO - mozversion application_display_name: Nightly 05:53:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:53:46 INFO - mozversion application_name: Firefox 05:53:46 INFO - mozversion application_remotingname: firefox 05:53:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:53:46 INFO - mozversion application_vendor: Mozilla 05:53:46 INFO - mozversion application_version: 51.0a1 05:53:46 INFO - mozversion platform_buildid: 20160808031752 05:53:46 INFO - mozversion platform_changeset: 1fa92a8698a9f605528701fb8cf33608e3e19650 05:53:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:53:46 INFO - mozversion platform_version: 51.0a1 05:53:46 INFO - using testdate: 1470660826 05:53:46 INFO - actual date: 1470660826 05:53:46 INFO - starting webserver on 'localhost:49272' 05:53:46 INFO - SUITE-START | Running 2 tests 05:53:46 INFO - TEST-START | dromaeo_css 05:53:46 INFO - operating with platform_type : mac_ 05:53:46 INFO - Initialising browser for dromaeo_css test... 05:53:46 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmp887rt2/profile http://localhost:49272/getInfo.html) 05:53:49 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 05:53:49 INFO - PROCESS | 1650 | colorDepth:24 05:53:49 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 05:53:49 INFO - PROCESS | 1650 | __metrics 05:53:49 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:53:50 INFO - TEST-INFO | 1650: exit 0 05:53:50 INFO - Browser initialized. 05:53:50 INFO - Running cycle 1/1 for dromaeo_css test... 05:53:50 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmp887rt2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:54:02 INFO - PROCESS | 1653 | RSS: Main: 254652416 05:54:02 INFO - PROCESS | 1653 | 05:54:52 INFO - PROCESS | 1653 | 1470660892313 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] 05:57:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 05:57:06 INFO - PROCESS | 1653 | RSS: Main: 267644928 05:57:06 INFO - PROCESS | 1653 | 06:00:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 06:00:09 INFO - PROCESS | 1653 | RSS: Main: 280301568 06:00:09 INFO - PROCESS | 1653 | 06:03:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 06:03:12 INFO - PROCESS | 1653 | RSS: Main: 287617024 06:03:12 INFO - PROCESS | 1653 | 06:06:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 06:06:14 INFO - PROCESS | 1653 | RSS: Main: 287383552 06:06:14 INFO - PROCESS | 1653 | 06:09:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 06:09:18 INFO - PROCESS | 1653 | RSS: Main: 292618240 06:09:18 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 06:12:20 INFO - PROCESS | 1653 | RSS: Main: 292524032 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | __start_tp_report 06:12:20 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 06:12:20 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 06:12:20 INFO - PROCESS | 1653 | |i|pagename|runs| 06:12:20 INFO - PROCESS | 1653 | |0;dojo.html;2907.898223562175;3455.0153206336186;3462.688358047775;3483.425234836252;3512.8067956262403;5780.739866705998;5879.529637628984;5934.881302373955;5971.880562388749;5985.850353741159;5445.156000819869;5815.244018277618;5887.293524777033;5982.192404025455;6027.789027384042;5280.973595132029;5312.574994000471;5323.733813309338;5410.810621628224;5442.945570544283;5423.484768946943;5432.239486471897;5472.616916815825;5501.972490137544;5514.255575497296;5178.766955486974;5348.1442969124755;5394.73026348681;5396.1905714142995;5404.675719456825;5245.632805703603;5278.2346559749;5303.893922121574;5306.09796334624;5312.548433383167;4480.417545719069;5058.064258112261;5177.559907407875;5226.850092979538;5351.545118664918;5748.109043098285;5906.173135760997;5928.67392293428;5985.191999080155;6012.158297838306;6181.227346581677;6206.472449841769;6246;6269.467095296905;6280.528960327948;6276.811695649138;6333;6336.271328797201;6339.175907132089;6373.203349581302;5879.941200588025;5941.197938278364;6000.849978750566;6006.579539432285;6008.819735407988;6286.654234017173;6376.3623637635865;6489.059086432441;6515.7067931943175;6527.379898909596;6229;6244.968775156095;6265.498760099181;6269.558001659552;6279.309275979638;6182.69086545671;6198.783042593487;6233.438990490878;6332.303446620868;6366.936330636727;6173.98129308668;6202.503799696104;6241.78153764616;6243.906341404882;6272.466840318624;6176.166217560662;6224.937750622526;6229.065640154013;6244.875102497925;6276.372362763687;4960.131976904099;4974.452810190948;4980.800767969241;5079.949200508022;5098.413682426569;4471.731696098244;4488.573585509372;4519.819207231674;4567.81728730847;4573.268277075652;5836.708164591753;5959.106134079836;6011.489023433057;6029.8191054268445;6035.275766907999;6163.859685958112;6249.96875015622;6280.0893870389455;6281.120643109968;6382.5532212744665;6001.1898393717165;6002.069679199731;6021.036634138517;6035.849103772441;6068.6358818471035;5525.834224973257;5553.4724201200825;5574.60977731563;5608.943910560843;5622.662640241599;6385.904211436925;6484.837879053061;6495.025746137928;6618.30507796684;6646.136002319764;5564.304461942257;6150.015997440388;6345.1434056401795;6382.106505089198;6395.072714456284;6530.8040758777315;6580.30906754803;6604.669766511752;6611.239707433707;6623.834404139935;6152.64641778808;6183.96908015457;6191.690415479118;6191.81424557264;6300.432961033529;6303.621782693053;6325.240971083499;6332.366763323815;6352.523560732871;6384.553081284265;4614.838480653161;6194.5973511721595;6211.534134939807;6221.346758590283;6266.624002559861;5958.582899197015;6107.144999700135;6156.661383623944;6164.044573091178;6190.288116866618;5163.6127290454115;6058.151858739868;6112.419320164578;6127.2034635498885;6163.322034576283;6164.969175154096;6168.660723660242;6170.074488826712;6188.938110618837;6213.130161777264;6050.818475445743;6066.4540191382985;6070.726817293321;6087.9391206087375;6107.067445037884 06:12:20 INFO - PROCESS | 1653 | |1;ext.html;485.52903683427064;511.698098122108;523.9599395200527;531.2031952071892;551.6414330685052;36624.08439789003;37059;41946.37080443792;41969.79015104924;42013.57986420135;39119.80440097803;42931.78534107325;43110.13779724404;43168;43253.78373108138;9555.187809036222;9758.219342452623;9795.412275263507;9808.31341806074;9814.950925245364;9146.588403521824;9629.422234665908;9658.517074146299;9718.805623887518;9726.902730972706;9067.909320906772;9624.80750384992;9653.469059201741;9763.75590610237;9780.755481112958;9551.474668893208;9659.178969787576;9673.274504412127;9728.659496917644;9729.756756081084;9673.613055477772;9713.90286097144;9745.805083898282;9781.804363912754;9787.314887957846;34137.829310853536;40552.98617534585;40718.389224161365;41027.589724102676;41132.588674113176;3111.5177147542167;3390.304987477561;3497.667721566447;3514.332276867387;3541.0793193769805;29585.408291834043;31512.739490420587;32306.83846580752;32323.545440455237;32354.676453235403;3090.490069138612;3164.8734050637945;3179.8410079495934;3198.18446296193;3278.7704860659765;452408;453801;457472;457636;459454.7027264876;34039.829800850835;34154;34253.972380828614;34255.65744342574;34269.82865085659;35056.298874022374;35146.82426587851;35842;36286;36656.63343366532;16302.83697163037;16445.177741112897;16463.91768041152;16483.093429861474;16546.58633534147;12400.19398739079;12530.561430349893;12535.498580056877;12549.435275412628;12569.371531423392;19606.60786784285;19742.50643733918;19905.402837914884;20122.39632811018;20156;8250.46371985819;8282.296004839656;8361.623726932303;8364.790880227922;8367.246947774729;886.3175354976632;925.4909799610188;956.7321150077989;992.9751756206152;1006.2453160129902;29992.350420726634;30291.69708302889;31235.687643123732;31269.905553305525;31489.21276968094;889.7731078574922;890.3900827932887;990.4057565460666;1047.36634336227;1139.3050239353988;13946.651333716738;15849.841501585068;15967.041977481389;16134.515964521082;16282.860199786139;690507.4746262666;700207;704701;709044;709192.4540377265;47841;61009.76368190326;61342.69328653328;61470.38529614736;61625.691871541254;1366.5080570994435;1384.0795870746042;1399.2514005007272;1405.1217988757028;1411.4565892131398;25412.872935635205;30352.696473034986;30814.845925770227;30835.766569336974;31499.527507086954;52643.473565263856;52981.205281921575;53229.46770532245;53236.46763532315;53392;602214;602905;604591;605335;606262;27276;27684.58473122945;27706;27758.02846900349;27794.305142371602;48684.02631947412;49621.75945601389;50868.49131508637;51059;51081.23378149256;40621.593784061784;41056.58943410647;41313.79343103265;41633.1673366537;42436;41811.7909410451;42156.57843421526;42550.78724606357;43092.569074308856;43659.56340436555;33994.83002584971;39283.607163928;39701.801490993515;40159.3976090353;40353.79823100866;33069;35999.100022499646;36064.63935360613;36385;36868.446973294886;24841.130560430298;25642.358941026625;26471;26590.936362545282;26592 06:12:20 INFO - PROCESS | 1653 | |2;jquery.html;366.8679275460832;373.4976456665785;383.8426245239452;390.5352630369861;399.9900002499939;16612.169391530435;18672.719909201354;20027;20200.19199232029;20454.89772551139;19887.90056049718;19973.60052798943;20035.398938031845;20320.69518957217;20632.968351582436;5617.662940223599;5934.317553481362;5977.880442391161;6029.005214139662;6103.176071230371;5306.204069389584;6155.446009859112;6156.538259630545;6159.10692949522;6159.291681456622;5890.381509941459;5995.010823214165;6010.27876654802;6085.756569737206;6107.053406721965;5857.297124345063;5980.31226408962;6048.69756512177;6062.514998800086;6073.4533891949495;5094.77073531692;5187.688738675692;5237.607179461517;5244.554212891909;5258.3689957205;20042.498937526532;24639.876800615883;25097;25122.49755004908;25490.725463726925;2286.954260914789;2295.4490922178725;2419.987900060506;2498.3504288884837;3034.089773068071;11790.469428875635;15080.698386032218;15308.004979676396;15472.690546189013;16025.599360015976;1621.8540331370234;1761.3835157694832;1832.9175187116612;1893.7538120044444;1954.8631595788302;112806.43596781963;115700.42149789198;115956;116145.41927290394;116394.41802790931;9056;10457.68626941193;10606;10903.073238774634;11036.613718519813;13097.672558185932;13187.076904616584;13387.196768193744;13440.663983400298;13484.460621575026;8030.755232938787;8217.465864718773;8365.07984121746;8387.83224335522;8526.062133165347;7359.227281135403;8083.585372559778;8380.245777879898;8392.412531122885;8433.325333973267;5781.6241944274325;6568.930786802238;6634.635095069818;6645.435138013323;6649.401553860176;1865.9346922857635;1891.7067854482577;1894.725264836591;1919.9136038878005;1952.2093552111417;914.6707185413246;938.9389689670286;968.5447839515416;998.1017084623896;1061.0874647802884;21297.893510532347;21798;21834.017469213923;21916.780832191795;21933.451663708536;825.8719898415756;896.587569717924;956.9569369378395;1004.457592899826;1081.5565618096543;11006.174536909764;11663.950244478037;11689.707757306136;11727.765444691058;11758.3532905691;592076;605472;614112;625337;627601;109705;111220.33169502464;121909;126332.99999999817;128311.35844320906;960.6685693435753;989.7080361293292;1021.5505177721778;1024.0476356988072;1029.2332212501733;17558.12209389551;19060.61878762389;19580.11889464949;19687.72029818057;19908.303209387603;45663;47349.05301893874;56778;56964.71517642385;61066;86712;87537;87953;88175.55912220398;88451.25066870633;8161.387895907712;8187.877181842158;8304.173081921968;8355.12271211514;8370.748877533684;12017.63947081589;13017.958563314724;13027.804582931074;13458.46166153382;13513.729725405634;9580.712578622653;11216.327020378803;11268.943655281671;11530.76938461243;11715.589954351557;9521.809773778277;9989.850152247576;10579.36523808574;11119.610813621484;11489.195756297313;11342.659720208407;11449.198556101326;11833.52665893389;11846.822297665369;11941.52233910634;7916.604169791372;8000.15998320168;8264.504129752235;8377.70678026266;8391.118932512243;10364.689059328231;11378.601748939078;11515;11539.480723367637;11572.768544629229 06:12:20 INFO - PROCESS | 1653 | |3;mootools.html;1531.479297039007;1537.6540278437353;1764.8941063536188;1861.8789778664395;1863.8695291329611;7885.250901164394;8210.425270231071;8290.585470726463;8844.15980481854;8852.247558957488;7339.88990165148;8266.380021498382;8407.117252688471;8521.829563408728;8543.444676096065;1991.2532800199924;1997.9400617981485;2369.92890213293;2372.3357459911167;2384;2032.7904896586508;2223.3330000999717;2377.869217193054;2382.976170238293;2388.5103553771437;1970.822625963663;1972.7829938706732;2361.527694461106;2367.9289621311386;2378.21518898763;2282.052948026567;2312.0867257433306;2323.488832456857;2352.2472808701223;2356.7643235676296;2322.7096612923383;2339.415146213447;2352.0709319818575;2353.082297903819;2357.3045951444365;8624.568771561397;8649.956750216208;8681.652733890636;8698.695545655935;8754.343424243207;5182.870428239286;5338.092524270884;5339.065663508871;5343.984642917833;5349.545288650469;3153.526970954375;3469.496922946184;3505.5267538882435;3631.382664946965;3688.5389326334207;1047.2250534604414;1082.577794660083;1093.7703082352716;1094.3652681444823;1110.9166812489016;11406.486708098155;11457;11457.656270311903;11600.709982250426;11601.303921764722;4307.901485121274;4656.534346565317;4729.4797572267025;4844.273358996108;4853.19922212838;9379.155875971195;10006.54970526328;10022.148117410103;10049.447280399501;10076.29465937392;5340.172273297645;5344.893102137936;5346.1713434417725;5354.9464505354445;5362.785488580491;4165.812538435777;4393.472783266028;4728.739919304471;4841.15279826029;4909.975450122798;3987.6211759882763;3987.6809855211513;4096.528899176633;4107.301758701028;4125.319322311845;1271.205496564647;1290.6644272489032;1292.3409061378577;1301.8763217494322;1304.302198323892;839.6851180807197;878.5914549734276;919.4069824962861;931.9487428191379;980.593053882645;5313.814016509404;5765.221695071112;5988.9101663475085;6009.519238460913;6074.605150665193;433.1315711997488;446.792241607654;451.76733981999297;830.6718846055774;955.8852937647388;3880.708946828999;3973.384125460558;4109.609587089221;4114.259433301974;4123.690723195772;10425.739356515996;10658.573657053632;10662.786744265071;10676.839847402296;10685.358878467317;10624.575016999388;10635.46822658864;10643.254972151875;10650.254482186172;10656.360618362924;6780.660966951731;7673.884891726629;8041.678332866737;8266.669333226724;8287.875681864776;5969.134475501115;6046.7278972445465;6106.877862442815;6113.205283313141;6194.566380353332;9995.400275983464;10023.498825058863;10134.341267817568;10163.695089147337;10176.185905127424;8487.8726819099;9898.653547125816;10034.899651003396;10119.139873110867;10298.794024119625;7011.719531218694;7346.889796653161;7558.735444259424;7592.658330375036;7608.923910760822;8336.916630833613;8382;8391.454555453876;8416.989961204694;8432.915670843213;7328.083989501312;7372.705091796268;7428.294312040348;7464.104307483137;7561.130469996021;7323.523970942085;7383.741569045273;7436.405087593088;7517.511361761468;7541.509801862861;5991.071383935497;6641.800745977628;6888.517803753689;7030.261822508563;7073.434125270069;4588.082383523375;4925.867050578316;4935.506449355035;4980.5019498049905;5097.6941383517105;4930.13722598551;5004.499550045112;5008.874778130576;5075.898482030412;5186.740662966912 06:12:20 INFO - PROCESS | 1653 | |4;prototype.html;287.62658306586064;295.9097475270043;301.79327160894786;308.65584872866754;320.7658409361166;5384.757685904134;5475.138452926004;5566.55467562595;5754.748567059609;5812.44781745734;5592.636478628897;5613.971930140344;5701.287339082614;5725.885482290351;5737.196792449061;3799.810009499528;3992.7803970781597;4370.847020354288;4386.583274588925;4395.340698895167;4040.636342729154;4233.555476674951;4337.262665346882;4423.447069116361;4423.7566933818625;3468.878589249377;3568;4232.767197804135;4260.510041345238;4293.184294983943;3744.382176940815;4113;4298;4310.6335961443;4317.0934103838235;3564.8395822187804;3699.6115407882053;3701.481792549045;3704.129529560551;3723;5512.090505066659;5533.335999680024;5559.444055594448;5569.275994120779;5586.329640443173;4902.117618828645;4932.401724043201;4937.8271760488215;4947;4953.23224900141;4794.256890182028;5532.419095994943;5702.6863522506665;5883.8529036774;6017.909731354033;3488.982555087234;3855.8047005428407;3965.8413663453425;4022.738521996061;4029.6977726670616;5304.973475132639;5938.762449502058;6118.082287656843;6367.42693157618;6401.431649245965;3436.621971583174;3790.4314352847296;3799.6200379962333;4091.1408604193452;4103.2409004333695;4103.261412945639;4132.090939993197;4133.793310334471;4157.958420415817;4164.459150114477;4517.638588912879;4853.878653033702;4889.190999330271;4917.336159618406;4922.286268491049;5823.767049318065;6085.878282434326;6101.60339577926;6103.542234332443;6115.1744514490865;1394.4213151542199;1414.6251243420502;1426.2868565717142;1429.6497358147112;1437.4537675683382;689.7928624906414;756.1191212237682;832.6685281212581;852.2713080316364;882.1090698394668;4482.641388688897;4768.856934291976;4777.948851252789;4778.546038126373;4780.808767649325;4064.723754697379;4285.61429471349;4936.679115857458;5128.230760387661;5194.68831870089;3933.0167458135465;4708.011317623269;4716.481187069419;4785.856424307276;4793.1894144154785;2867.354845159822;2951.977895141835;3126.249300391788;3254.023792862132;3278.6885245901603;6435.908329792743;6440.742370305135;6451.709673064724;6464.288928217892;6491.383318584727;4680.82341711106;5729.0260655688635;6001;6155.368332683738;6402.519811014099;5520.868222014477;5597.389683654021;6200.534959877937;6228.719707613168;6422.903656445157;4773.284007398918;5660.943390566042;6116.5412594056515;6188.288346840171;6215.9378406217165;4642.744649044335;4781.880452988564;5070.870234501592;5901.852453688693;6083.718605522929;5338.197446340702;6182.783602573888;6526.738930442729;6549.8690026200165;6568.934310656832;3648.744587878929;3673.651003154696;3931.5282166140246;4070.7354021988476;4088.713790034669;6181.381861813783;6219.937800622117;6227;6238.438540531374;6253.5935164216;5747.8563035924435;5780.421957804354;6038.486728628116;6118.663473508822;6157.72290246931;4806.591439727662;5599.04816181242;5976.491998180203;5976.61152025117;6030.6080104793045;5848.736806843787;5895.941040589711;5896.17453556511;5900.527957763455;5902.4097590240635;5238.816641417532;5521.917171242354;5762.884742305214;5975.790847320323;6027.668478233739;4942.851714448572;5211.426743058185;5579.581531385081;5972.372900845348;6004.307350979204 06:12:20 INFO - PROCESS | 1653 | |5;yui.html;701.7859552836386;718.3032458515238;727.476217123671;769.3921801776597;819.7950512371907;15474.38102475901;15648.608784780386;15789.28948197331;15842.84157158428;15851.920740396281;14369.425222991067;14446;14455.710885782304;14467.421303147861;14487.637809054779;680.3468670076733;842.6671464771412;844.1937949258459;865.9307255419583;869.8999615044256;755.1693137548707;863.8876945997011;868.5570359116833;869.6825658634584;871.8953725552942;907.5552979040256;928.3084102343763;929.2751653710117;929.9116583924516;931.8881734191906;917.124146440148;923.1922068190333;923.8244733500647;925.5233554719291;930.2000279759402;659.5877576514679;678.7963610916754;684.4729558240127;686.2417029182781;687.1582311668217;14728.926355368261;14950.40198392063;14955.177465239407;14984.850151498564;14993.32530036151;14712.264386780618;14793.11241325524;14831.851681483155;14879.256037198096;14935.626609334744;10608;11887.94056029723;12030.977366923822;12172.878271217263;12356.691082722913;3825.9808700956596;3839.404892241679;4048.392741088831;4075.286824805647;4076.7961601920083;91096.54451727765;114973;116554;116679;119104;9596.808063838684;11336.376499292619;11545.422728863523;11566.42167891602;11872.643820685393;19372.547058970635;20710.482237944172;23262.883685581462;23789.405264868517;23867.880660596587;7275.4179665626625;8215.630296636666;8217.055038670629;8276.668933242603;8504.787380315542;4485.46174459067;4618.076384723069;4644.674872758942;4649.837255696035;4759;3569.785812851237;3671.504346913186;3704.629537046274;3716.3682174030478;3730.720195985312;666.3835951744713;667.7896462614261;687.08274453605;694.8818700820873;699.4544255480733;504.84349851546136;661.1504217081027;684.5653010338372;691.9411849992706;708.155255559271;9283.48940808262;9317.301202409846;9484.95257523708;9705.417674939526;9963.452010139512;562.0978329780643;604.3654163128786;651.8924377477758;685.9416949559244;686.7664993902097;4377.387165796791;4689.835855745033;4738.123447162286;4742.454617718938;4771.928421073617;162842;164129;173347;173995;176272;109545;113237.86762132273;126907;129075;133937;111961;116026;117421;118639.40680296543;120776;9642.903570964201;9895.505224738878;9961.113517658374;10048.145907597644;10089.798204036026;52912;60178.69910650419;61875;64333.35666643274;65039.67480162569;69735;86352.27295454181;90464.09535904556;98441;98655;11964.940175299067;12093.213941093802;12177.939110304393;12212.816807747713;12330.19853709506;14828;15077.849221507644;15091.396344146551;15322;15493.612659683147;14072.648183795078;14175.64560885986;14201.644958876112;14207.857921420653;14228.72340425527;14097.859021410064;14154.079984800956;14221.217833019284;14654.19401932904;14895.851041489446;13486.325683715579;13504.662383440493;14118.435262589792;14402.20787856678;14454.783178252545;10892.40091794959;11170.888291116984;11252;11255.212135150461;11751.412429378668;9812.313138080266;10004.499775011365;10647.574097036031;11445.885541144482;11751 06:12:20 INFO - PROCESS | 1653 | __end_tp_report 06:12:20 INFO - PROCESS | 1653 | __start_cc_report 06:12:20 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,423 06:12:20 INFO - PROCESS | 1653 | __end_cc_report 06:12:20 INFO - PROCESS | 1653 | __startTimestamp1470661940961__endTimestamp 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | ------- Summary: start ------- 06:12:20 INFO - PROCESS | 1653 | Number of tests: 6 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5845.76 Median:6070.73 stddev:665.58 (11.0%) stddev-sans-first:626.45 06:12:20 INFO - PROCESS | 1653 | Values: 2907.9 3455.0 3462.7 3483.4 3512.8 5780.7 5879.5 5934.9 5971.9 5985.9 5445.2 5815.2 5887.3 5982.2 6027.8 5281.0 5312.6 5323.7 5410.8 5442.9 5423.5 5432.2 5472.6 5502.0 5514.3 5178.8 5348.1 5394.7 5396.2 5404.7 5245.6 5278.2 5303.9 5306.1 5312.5 4480.4 5058.1 5177.6 5226.9 5351.5 5748.1 5906.2 5928.7 5985.2 6012.2 6181.2 6206.5 6246.0 6269.5 6280.5 6276.8 6333.0 6336.3 6339.2 6373.2 5879.9 5941.2 6000.8 6006.6 6008.8 6286.7 6376.4 6489.1 6515.7 6527.4 6229.0 6245.0 6265.5 6269.6 6279.3 6182.7 6198.8 6233.4 6332.3 6366.9 6174.0 6202.5 6241.8 6243.9 6272.5 6176.2 6224.9 6229.1 6244.9 6276.4 4960.1 4974.5 4980.8 5079.9 5098.4 4471.7 4488.6 4519.8 4567.8 4573.3 5836.7 5959.1 6011.5 6029.8 6035.3 6163.9 6250.0 6280.1 6281.1 6382.6 6001.2 6002.1 6021.0 6035.8 6068.6 5525.8 5553.5 5574.6 5608.9 5622.7 6385.9 6484.8 6495.0 6618.3 6646.1 5564.3 6150.0 6345.1 6382.1 6395.1 6530.8 6580.3 6604.7 6611.2 6623.8 6152.6 6184.0 6191.7 6191.8 6300.4 6303.6 6325.2 6332.4 6352.5 6384.6 4614.8 6194.6 6211.5 6221.3 6266.6 5958.6 6107.1 6156.7 6164.0 6190.3 5163.6 6058.2 6112.4 6127.2 6163.3 6165.0 6168.7 6170.1 6188.9 6213.1 6050.8 6066.5 6070.7 6087.9 6107.1 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:70913.15 Median:41042.09 stddev:159829.23 (389.4%) stddev-sans-first:160190.13 06:12:20 INFO - PROCESS | 1653 | Values: 485.5 511.7 524.0 531.2 551.6 36624.1 37059.0 41946.4 41969.8 42013.6 39119.8 42931.8 43110.1 43168.0 43253.8 9555.2 9758.2 9795.4 9808.3 9815.0 9146.6 9629.4 9658.5 9718.8 9726.9 9067.9 9624.8 9653.5 9763.8 9780.8 9551.5 9659.2 9673.3 9728.7 9729.8 9673.6 9713.9 9745.8 9781.8 9787.3 34137.8 40553.0 40718.4 41027.6 41132.6 3111.5 3390.3 3497.7 3514.3 3541.1 29585.4 31512.7 32306.8 32323.5 32354.7 3090.5 3164.9 3179.8 3198.2 3278.8 452408.0 453801.0 457472.0 457636.0 459454.7 34039.8 34154.0 34254.0 34255.7 34269.8 35056.3 35146.8 35842.0 36286.0 36656.6 16302.8 16445.2 16463.9 16483.1 16546.6 12400.2 12530.6 12535.5 12549.4 12569.4 19606.6 19742.5 19905.4 20122.4 20156.0 8250.5 8282.3 8361.6 8364.8 8367.2 886.3 925.5 956.7 993.0 1006.2 29992.4 30291.7 31235.7 31269.9 31489.2 889.8 890.4 990.4 1047.4 1139.3 13946.7 15849.8 15967.0 16134.5 16282.9 690507.5 700207.0 704701.0 709044.0 709192.5 47841.0 61009.8 61342.7 61470.4 61625.7 1366.5 1384.1 1399.3 1405.1 1411.5 25412.9 30352.7 30814.8 30835.8 31499.5 52643.5 52981.2 53229.5 53236.5 53392.0 602214.0 602905.0 604591.0 605335.0 606262.0 27276.0 27684.6 27706.0 27758.0 27794.3 48684.0 49621.8 50868.5 51059.0 51081.2 40621.6 41056.6 41313.8 41633.2 42436.0 41811.8 42156.6 42550.8 43092.6 43659.6 33994.8 39283.6 39701.8 40159.4 40353.8 33069.0 35999.1 36064.6 36385.0 36868.4 24841.1 25642.4 26471.0 26590.9 26592.0 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:35531.97 Median:13529.93 stddev:102027.87 (754.1%) stddev-sans-first:102279.92 06:12:20 INFO - PROCESS | 1653 | Values: 366.9 373.5 383.8 390.5 400.0 16612.2 18672.7 20027.0 20200.2 20454.9 19887.9 19973.6 20035.4 20320.7 20633.0 5617.7 5934.3 5977.9 6029.0 6103.2 5306.2 6155.4 6156.5 6159.1 6159.3 5890.4 5995.0 6010.3 6085.8 6107.1 5857.3 5980.3 6048.7 6062.5 6073.5 5094.8 5187.7 5237.6 5244.6 5258.4 20042.5 24639.9 25097.0 25122.5 25490.7 2287.0 2295.4 2420.0 2498.4 3034.1 11790.5 15080.7 15308.0 15472.7 16025.6 1621.9 1761.4 1832.9 1893.8 1954.9 112806.4 115700.4 115956.0 116145.4 116394.4 9056.0 10457.7 10606.0 10903.1 11036.6 13097.7 13187.1 13387.2 13440.7 13484.5 8030.8 8217.5 8365.1 8387.8 8526.1 7359.2 8083.6 8380.2 8392.4 8433.3 5781.6 6568.9 6634.6 6645.4 6649.4 1865.9 1891.7 1894.7 1919.9 1952.2 914.7 938.9 968.5 998.1 1061.1 21297.9 21798.0 21834.0 21916.8 21933.5 825.9 896.6 957.0 1004.5 1081.6 11006.2 11664.0 11689.7 11727.8 11758.4 592076.0 605472.0 614112.0 625337.0 627601.0 109705.0 111220.3 121909.0 126333.0 128311.4 960.7 989.7 1021.6 1024.0 1029.2 17558.1 19060.6 19580.1 19687.7 19908.3 45663.0 47349.1 56778.0 56964.7 61066.0 86712.0 87537.0 87953.0 88175.6 88451.3 8161.4 8187.9 8304.2 8355.1 8370.7 12017.6 13018.0 13027.8 13458.5 13513.7 9580.7 11216.3 11268.9 11530.8 11715.6 9521.8 9989.9 10579.4 11119.6 11489.2 11342.7 11449.2 11833.5 11846.8 11941.5 7916.6 8000.2 8264.5 8377.7 8391.1 10364.7 11378.6 11515.0 11539.5 11572.8 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5652.42 Median:4785.32 stddev:3171.40 (66.3%) stddev-sans-first:3165.18 06:12:20 INFO - PROCESS | 1653 | Values: 1531.5 1537.7 1764.9 1861.9 1863.9 7885.3 8210.4 8290.6 8844.2 8852.2 7339.9 8266.4 8407.1 8521.8 8543.4 1991.3 1997.9 2369.9 2372.3 2384.0 2032.8 2223.3 2377.9 2383.0 2388.5 1970.8 1972.8 2361.5 2367.9 2378.2 2282.1 2312.1 2323.5 2352.2 2356.8 2322.7 2339.4 2352.1 2353.1 2357.3 8624.6 8650.0 8681.7 8698.7 8754.3 5182.9 5338.1 5339.1 5344.0 5349.5 3153.5 3469.5 3505.5 3631.4 3688.5 1047.2 1082.6 1093.8 1094.4 1110.9 11406.5 11457.0 11457.7 11600.7 11601.3 4307.9 4656.5 4729.5 4844.3 4853.2 9379.2 10006.5 10022.1 10049.4 10076.3 5340.2 5344.9 5346.2 5354.9 5362.8 4165.8 4393.5 4728.7 4841.2 4910.0 3987.6 3987.7 4096.5 4107.3 4125.3 1271.2 1290.7 1292.3 1301.9 1304.3 839.7 878.6 919.4 931.9 980.6 5313.8 5765.2 5988.9 6009.5 6074.6 433.1 446.8 451.8 830.7 955.9 3880.7 3973.4 4109.6 4114.3 4123.7 10425.7 10658.6 10662.8 10676.8 10685.4 10624.6 10635.5 10643.3 10650.3 10656.4 6780.7 7673.9 8041.7 8266.7 8287.9 5969.1 6046.7 6106.9 6113.2 6194.6 9995.4 10023.5 10134.3 10163.7 10176.2 8487.9 9898.7 10034.9 10119.1 10298.8 7011.7 7346.9 7558.7 7592.7 7608.9 8336.9 8382.0 8391.5 8417.0 8432.9 7328.1 7372.7 7428.3 7464.1 7561.1 7323.5 7383.7 7436.4 7517.5 7541.5 5991.1 6641.8 6888.5 7030.3 7073.4 4588.1 4925.9 4935.5 4980.5 5097.7 4930.1 5004.5 5008.9 5075.9 5186.7 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4735.48 Median:5099.55 stddev:1498.15 (29.4%) stddev-sans-first:1464.68 06:12:20 INFO - PROCESS | 1653 | Values: 287.6 295.9 301.8 308.7 320.8 5384.8 5475.1 5566.6 5754.7 5812.4 5592.6 5614.0 5701.3 5725.9 5737.2 3799.8 3992.8 4370.8 4386.6 4395.3 4040.6 4233.6 4337.3 4423.4 4423.8 3468.9 3568.0 4232.8 4260.5 4293.2 3744.4 4113.0 4298.0 4310.6 4317.1 3564.8 3699.6 3701.5 3704.1 3723.0 5512.1 5533.3 5559.4 5569.3 5586.3 4902.1 4932.4 4937.8 4947.0 4953.2 4794.3 5532.4 5702.7 5883.9 6017.9 3489.0 3855.8 3965.8 4022.7 4029.7 5305.0 5938.8 6118.1 6367.4 6401.4 3436.6 3790.4 3799.6 4091.1 4103.2 4103.3 4132.1 4133.8 4158.0 4164.5 4517.6 4853.9 4889.2 4917.3 4922.3 5823.8 6085.9 6101.6 6103.5 6115.2 1394.4 1414.6 1426.3 1429.6 1437.5 689.8 756.1 832.7 852.3 882.1 4482.6 4768.9 4777.9 4778.5 4780.8 4064.7 4285.6 4936.7 5128.2 5194.7 3933.0 4708.0 4716.5 4785.9 4793.2 2867.4 2952.0 3126.2 3254.0 3278.7 6435.9 6440.7 6451.7 6464.3 6491.4 4680.8 5729.0 6001.0 6155.4 6402.5 5520.9 5597.4 6200.5 6228.7 6422.9 4773.3 5660.9 6116.5 6188.3 6215.9 4642.7 4781.9 5070.9 5901.9 6083.7 5338.2 6182.8 6526.7 6549.9 6568.9 3648.7 3673.7 3931.5 4070.7 4088.7 6181.4 6219.9 6227.0 6238.4 6253.6 5747.9 5780.4 6038.5 6118.7 6157.7 4806.6 5599.0 5976.5 5976.6 6030.6 5848.7 5895.9 5896.2 5900.5 5902.4 5238.8 5521.9 5762.9 5975.8 6027.7 4942.9 5211.4 5579.6 5972.4 6004.3 06:12:20 INFO - PROCESS | 1653 | 06:12:20 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25537.31 Median:3688.07 stddev:41546.94 (1126.5%) stddev-sans-first:41621.65 06:12:20 INFO - PROCESS | 1653 | Values: 701.8 718.3 727.5 769.4 819.8 15474.4 15648.6 15789.3 15842.8 15851.9 14369.4 14446.0 14455.7 14467.4 14487.6 680.3 842.7 844.2 865.9 869.9 755.2 863.9 868.6 869.7 871.9 907.6 928.3 929.3 929.9 931.9 917.1 923.2 923.8 925.5 930.2 659.6 678.8 684.5 686.2 687.2 14728.9 14950.4 14955.2 14984.9 14993.3 14712.3 14793.1 14831.9 14879.3 14935.6 10608.0 11887.9 12031.0 12172.9 12356.7 3826.0 3839.4 4048.4 4075.3 4076.8 91096.5 114973.0 116554.0 116679.0 119104.0 9596.8 11336.4 11545.4 11566.4 11872.6 19372.5 20710.5 23262.9 23789.4 23867.9 7275.4 8215.6 8217.1 8276.7 8504.8 4485.5 4618.1 4644.7 4649.8 4759.0 3569.8 3671.5 3704.6 3716.4 3730.7 666.4 667.8 687.1 694.9 699.5 504.8 661.2 684.6 691.9 708.2 9283.5 9317.3 9485.0 9705.4 9963.5 562.1 604.4 651.9 685.9 686.8 4377.4 4689.8 4738.1 4742.5 4771.9 162842.0 164129.0 173347.0 173995.0 176272.0 109545.0 113237.9 126907.0 129075.0 133937.0 111961.0 116026.0 117421.0 118639.4 120776.0 9642.9 9895.5 9961.1 10048.1 10089.8 52912.0 60178.7 61875.0 64333.4 65039.7 69735.0 86352.3 90464.1 98441.0 98655.0 11964.9 12093.2 12177.9 12212.8 12330.2 14828.0 15077.8 15091.4 15322.0 15493.6 14072.6 14175.6 14201.6 14207.9 14228.7 14097.9 14154.1 14221.2 14654.2 14895.9 13486.3 13504.7 14118.4 14402.2 14454.8 10892.4 11170.9 11252.0 11255.2 11751.4 9812.3 10004.5 10647.6 11445.9 11751.0 06:12:20 INFO - PROCESS | 1653 | -------- Summary: end -------- 06:12:20 INFO - PROCESS | 1653 | 06:12:21 INFO - TEST-INFO | 1653: exit 0 06:12:21 INFO - TEST-OK | dromaeo_css | took 1114920ms 06:12:21 INFO - TEST-START | kraken 06:12:21 INFO - operating with platform_type : mac_ 06:12:21 INFO - Initialising browser for kraken test... 06:12:21 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmp336WV1/profile http://localhost:49272/getInfo.html) 06:12:24 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 06:12:24 INFO - PROCESS | 1665 | colorDepth:24 06:12:24 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 06:12:24 INFO - PROCESS | 1665 | __metrics 06:12:24 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 06:12:24 INFO - TEST-INFO | 1665: exit 0 06:12:24 INFO - Browser initialized. 06:12:24 INFO - Running cycle 1/1 for kraken test... 06:12:24 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmp336WV1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:12:36 INFO - PROCESS | 1667 | RSS: Main: 254713856 06:12:36 INFO - PROCESS | 1667 | 06:12:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 06:12:40 INFO - PROCESS | 1667 | RSS: Main: 268152832 06:12:40 INFO - PROCESS | 1667 | 06:12:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 06:12:43 INFO - PROCESS | 1667 | RSS: Main: 276307968 06:12:43 INFO - PROCESS | 1667 | 06:12:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 06:12:47 INFO - PROCESS | 1667 | RSS: Main: 279134208 06:12:47 INFO - PROCESS | 1667 | 06:12:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 06:12:50 INFO - PROCESS | 1667 | RSS: Main: 281542656 06:12:50 INFO - PROCESS | 1667 | 06:12:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:12:53 INFO - PROCESS | 1667 | RSS: Main: 282087424 06:12:53 INFO - PROCESS | 1667 | 06:12:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 06:12:56 INFO - PROCESS | 1667 | RSS: Main: 282116096 06:12:56 INFO - PROCESS | 1667 | 06:12:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 06:13:00 INFO - PROCESS | 1667 | RSS: Main: 281849856 06:13:00 INFO - PROCESS | 1667 | 06:13:03 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 06:13:04 INFO - PROCESS | 1667 | RSS: Main: 282529792 06:13:04 INFO - PROCESS | 1667 | 06:13:05 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:13:06 INFO - PROCESS | 1667 | RSS: Main: 282431488 06:13:06 INFO - PROCESS | 1667 | 06:13:08 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:13:09 INFO - PROCESS | 1667 | RSS: Main: 282324992 06:13:09 INFO - PROCESS | 1667 | 06:13:11 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:13:12 INFO - PROCESS | 1667 | RSS: Main: 282296320 06:13:12 INFO - PROCESS | 1667 | 06:13:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:13:15 INFO - PROCESS | 1667 | RSS: Main: 282521600 06:13:15 INFO - PROCESS | 1667 | 06:13:18 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:13:19 INFO - PROCESS | 1667 | RSS: Main: 282566656 06:13:19 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 06:13:21 INFO - PROCESS | 1667 | RSS: Main: 283815936 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | __start_tp_report 06:13:21 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 06:13:21 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 06:13:21 INFO - PROCESS | 1667 | |i|pagename|runs| 06:13:21 INFO - PROCESS | 1667 | |0;ai-astar;105;117;109;120;116;118;118;119;120;120 06:13:21 INFO - PROCESS | 1667 | |1;audio-beat-detection;144;114;136;162;115;132;131;113;166;115 06:13:21 INFO - PROCESS | 1667 | |2;audio-dft;198;207;211;236;200;193;200;197;204;195 06:13:21 INFO - PROCESS | 1667 | |3;audio-fft;70;86;75;146;51;69;86;83;133;60 06:13:21 INFO - PROCESS | 1667 | |4;audio-oscillator;70;75;126;71;73;81;112;72;73;114 06:13:21 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;105;88;91;86;135;121;92;92;122;90 06:13:21 INFO - PROCESS | 1667 | |6;imaging-darkroom;98;99;97;104;135;97;97;97;97;117 06:13:21 INFO - PROCESS | 1667 | |7;imaging-desaturate;90;94;92;91;96;93;94;81;148;151 06:13:21 INFO - PROCESS | 1667 | |8;json-parse-financial;54;85;85;58;85;58;48;75;82;84 06:13:21 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;53;98;59;87;108;65;73;67;99;97 06:13:21 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;88;92;86;98;71;89;99;75;77;96 06:13:21 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;145;145;130;151;158;149;153;157;153;146 06:13:21 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;168;171;168;172;170;167;202;230;182;157 06:13:21 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;70;68;70;69;69;80;88;87;88;88 06:13:21 INFO - PROCESS | 1667 | __end_tp_report 06:13:21 INFO - PROCESS | 1667 | __start_cc_report 06:13:21 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1053 06:13:21 INFO - PROCESS | 1667 | __end_cc_report 06:13:21 INFO - PROCESS | 1667 | __startTimestamp1470662001520__endTimestamp 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | ------- Summary: start ------- 06:13:21 INFO - PROCESS | 1667 | Number of tests: 14 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:116.20 Median:118.50 stddev:5.12 (4.3%) stddev-sans-first:3.47 06:13:21 INFO - PROCESS | 1667 | Values: 105.0 117.0 109.0 120.0 116.0 118.0 118.0 119.0 120.0 120.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:134.00 stddev:19.65 (14.7%) stddev-sans-first:20.42 06:13:21 INFO - PROCESS | 1667 | Values: 144.0 114.0 136.0 162.0 115.0 132.0 131.0 113.0 166.0 115.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:204.10 Median:202.00 stddev:12.48 (6.2%) stddev-sans-first:13.04 06:13:21 INFO - PROCESS | 1667 | Values: 198.0 207.0 211.0 236.0 200.0 193.0 200.0 197.0 204.0 195.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:85.90 Median:72.50 stddev:30.52 (42.1%) stddev-sans-first:31.83 06:13:21 INFO - PROCESS | 1667 | Values: 70.0 86.0 75.0 146.0 51.0 69.0 86.0 83.0 133.0 60.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:72.50 stddev:21.64 (29.9%) stddev-sans-first:22.10 06:13:21 INFO - PROCESS | 1667 | Values: 70.0 75.0 126.0 71.0 73.0 81.0 112.0 72.0 73.0 114.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:102.20 Median:89.00 stddev:17.56 (19.7%) stddev-sans-first:18.60 06:13:21 INFO - PROCESS | 1667 | Values: 105.0 88.0 91.0 86.0 135.0 121.0 92.0 92.0 122.0 90.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:103.80 Median:97.00 stddev:12.63 (13.0%) stddev-sans-first:13.22 06:13:21 INFO - PROCESS | 1667 | Values: 98.0 99.0 97.0 104.0 135.0 97.0 97.0 97.0 97.0 117.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:103.00 Median:92.50 stddev:24.85 (26.9%) stddev-sans-first:25.91 06:13:21 INFO - PROCESS | 1667 | Values: 90.0 94.0 92.0 91.0 96.0 93.0 94.0 81.0 148.0 151.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:71.40 Median:83.00 stddev:15.09 (18.2%) stddev-sans-first:14.63 06:13:21 INFO - PROCESS | 1667 | Values: 54.0 85.0 85.0 58.0 85.0 58.0 48.0 75.0 82.0 84.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:80.60 Median:80.00 stddev:19.48 (24.4%) stddev-sans-first:17.92 06:13:21 INFO - PROCESS | 1667 | Values: 53.0 98.0 59.0 87.0 108.0 65.0 73.0 67.0 99.0 97.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:90.50 stddev:9.87 (10.9%) stddev-sans-first:10.46 06:13:21 INFO - PROCESS | 1667 | Values: 88.0 92.0 86.0 98.0 71.0 89.0 99.0 75.0 77.0 96.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:152.00 stddev:8.04 (5.3%) stddev-sans-first:8.42 06:13:21 INFO - PROCESS | 1667 | Values: 145.0 145.0 130.0 151.0 158.0 149.0 153.0 157.0 153.0 146.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:171.50 stddev:21.61 (12.6%) stddev-sans-first:22.57 06:13:21 INFO - PROCESS | 1667 | Values: 168.0 171.0 168.0 172.0 170.0 167.0 202.0 230.0 182.0 157.0 06:13:21 INFO - PROCESS | 1667 | 06:13:21 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.70 Median:83.50 stddev:9.27 (11.1%) stddev-sans-first:9.41 06:13:21 INFO - PROCESS | 1667 | Values: 70.0 68.0 70.0 69.0 69.0 80.0 88.0 87.0 88.0 88.0 06:13:21 INFO - PROCESS | 1667 | -------- Summary: end -------- 06:13:21 INFO - PROCESS | 1667 | 06:13:22 INFO - TEST-INFO | 1667: exit 0 06:13:22 INFO - TEST-OK | kraken | took 60722ms 06:13:22 INFO - SUITE-END | took 1175s 06:13:22 INFO - Completed test suite (00:19:35) 06:13:22 INFO - javascript benchmark 06:13:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7450.941175026269, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2907.898223562175, 3455.0153206336186, 3462.688358047775, 3483.425234836252, 3512.8067956262403, 5780.739866705998, 5879.529637628984, 5934.881302373955, 5971.880562388749, 5985.850353741159, 5445.156000819869, 5815.244018277618, 5887.293524777033, 5982.192404025455, 6027.789027384042, 5280.973595132029, 5312.574994000471, 5323.733813309338, 5410.810621628224, 5442.945570544283, 5423.484768946943, 5432.239486471897, 5472.616916815825, 5501.972490137544, 5514.255575497296, 5178.766955486974, 5348.1442969124755, 5394.73026348681, 5396.1905714142995, 5404.675719456825, 5245.632805703603, 5278.2346559749, 5303.893922121574, 5306.09796334624, 5312.548433383167, 4480.417545719069, 5058.064258112261, 5177.559907407875, 5226.850092979538, 5351.545118664918, 5748.109043098285, 5906.173135760997, 5928.67392293428, 5985.191999080155, 6012.158297838306, 6181.227346581677, 6206.472449841769, 6246.0, 6269.467095296905, 6280.528960327948, 6276.811695649138, 6333.0, 6336.271328797201, 6339.175907132089, 6373.203349581302, 5879.941200588025, 5941.197938278364, 6000.849978750566, 6006.579539432285, 6008.819735407988, 6286.654234017173, 6376.3623637635865, 6489.059086432441, 6515.7067931943175, 6527.379898909596, 6229.0, 6244.968775156095, 6265.498760099181, 6269.558001659552, 6279.309275979638, 6182.69086545671, 6198.783042593487, 6233.438990490878, 6332.303446620868, 6366.936330636727, 6173.98129308668, 6202.503799696104, 6241.78153764616, 6243.906341404882, 6272.466840318624, 6176.166217560662, 6224.937750622526, 6229.065640154013, 6244.875102497925, 6276.372362763687, 4960.131976904099, 4974.452810190948, 4980.800767969241, 5079.949200508022, 5098.413682426569, 4471.731696098244, 4488.573585509372, 4519.819207231674, 4567.81728730847, 4573.268277075652, 5836.708164591753, 5959.106134079836, 6011.489023433057, 6029.8191054268445, 6035.275766907999, 6163.859685958112, 6249.96875015622, 6280.0893870389455, 6281.120643109968, 6382.5532212744665, 6001.1898393717165, 6002.069679199731, 6021.036634138517, 6035.849103772441, 6068.6358818471035, 5525.834224973257, 5553.4724201200825, 5574.60977731563, 5608.943910560843, 5622.662640241599, 6385.904211436925, 6484.837879053061, 6495.025746137928, 6618.30507796684, 6646.136002319764, 5564.304461942257, 6150.015997440388, 6345.1434056401795, 6382.106505089198, 6395.072714456284, 6530.8040758777315, 6580.30906754803, 6604.669766511752, 6611.239707433707, 6623.834404139935, 6152.64641778808, 6183.96908015457, 6191.690415479118, 6191.81424557264, 6300.432961033529, 6303.621782693053, 6325.240971083499, 6332.366763323815, 6352.523560732871, 6384.553081284265, 4614.838480653161, 6194.5973511721595, 6211.534134939807, 6221.346758590283, 6266.624002559861, 5958.582899197015, 6107.144999700135, 6156.661383623944, 6164.044573091178, 6190.288116866618, 5163.6127290454115, 6058.151858739868, 6112.419320164578, 6127.2034635498885, 6163.322034576283, 6164.969175154096, 6168.660723660242, 6170.074488826712, 6188.938110618837, 6213.130161777264, 6050.818475445743, 6066.4540191382985, 6070.726817293321, 6087.9391206087375, 6107.067445037884], "value": 5803.116587836288, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.52903683427064, 511.698098122108, 523.9599395200527, 531.2031952071892, 551.6414330685052, 36624.08439789003, 37059.0, 41946.37080443792, 41969.79015104924, 42013.57986420135, 39119.80440097803, 42931.78534107325, 43110.13779724404, 43168.0, 43253.78373108138, 9555.187809036222, 9758.219342452623, 9795.412275263507, 9808.31341806074, 9814.950925245364, 9146.588403521824, 9629.422234665908, 9658.517074146299, 9718.805623887518, 9726.902730972706, 9067.909320906772, 9624.80750384992, 9653.469059201741, 9763.75590610237, 9780.755481112958, 9551.474668893208, 9659.178969787576, 9673.274504412127, 9728.659496917644, 9729.756756081084, 9673.613055477772, 9713.90286097144, 9745.805083898282, 9781.804363912754, 9787.314887957846, 34137.829310853536, 40552.98617534585, 40718.389224161365, 41027.589724102676, 41132.588674113176, 3111.5177147542167, 3390.304987477561, 3497.667721566447, 3514.332276867387, 3541.0793193769805, 29585.408291834043, 31512.739490420587, 32306.83846580752, 32323.545440455237, 32354.676453235403, 3090.490069138612, 3164.8734050637945, 3179.8410079495934, 3198.18446296193, 3278.7704860659765, 452408.0, 453801.0, 457472.0, 457636.0, 459454.7027264876, 34039.829800850835, 34154.0, 34253.972380828614, 34255.65744342574, 34269.82865085659, 35056.298874022374, 35146.82426587851, 35842.0, 36286.0, 36656.63343366532, 16302.83697163037, 16445.177741112897, 16463.91768041152, 16483.093429861474, 16546.58633534147, 12400.19398739079, 12530.561430349893, 12535.498580056877, 12549.435275412628, 12569.371531423392, 19606.60786784285, 19742.50643733918, 19905.402837914884, 20122.39632811018, 20156.0, 8250.46371985819, 8282.296004839656, 8361.623726932303, 8364.790880227922, 8367.246947774729, 886.3175354976632, 925.4909799610188, 956.7321150077989, 992.9751756206152, 1006.2453160129902, 29992.350420726634, 30291.69708302889, 31235.687643123732, 31269.905553305525, 31489.21276968094, 889.7731078574922, 890.3900827932887, 990.4057565460666, 1047.36634336227, 1139.3050239353988, 13946.651333716738, 15849.841501585068, 15967.041977481389, 16134.515964521082, 16282.860199786139, 690507.4746262666, 700207.0, 704701.0, 709044.0, 709192.4540377265, 47841.0, 61009.76368190326, 61342.69328653328, 61470.38529614736, 61625.691871541254, 1366.5080570994435, 1384.0795870746042, 1399.2514005007272, 1405.1217988757028, 1411.4565892131398, 25412.872935635205, 30352.696473034986, 30814.845925770227, 30835.766569336974, 31499.527507086954, 52643.473565263856, 52981.205281921575, 53229.46770532245, 53236.46763532315, 53392.0, 602214.0, 602905.0, 604591.0, 605335.0, 606262.0, 27276.0, 27684.58473122945, 27706.0, 27758.02846900349, 27794.305142371602, 48684.02631947412, 49621.75945601389, 50868.49131508637, 51059.0, 51081.23378149256, 40621.593784061784, 41056.58943410647, 41313.79343103265, 41633.1673366537, 42436.0, 41811.7909410451, 42156.57843421526, 42550.78724606357, 43092.569074308856, 43659.56340436555, 33994.83002584971, 39283.607163928, 39701.801490993515, 40159.3976090353, 40353.79823100866, 33069.0, 35999.100022499646, 36064.63935360613, 36385.0, 36868.446973294886, 24841.130560430298, 25642.358941026625, 26471.0, 26590.936362545282, 26592.0], "value": 19734.265966668234, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.8679275460832, 373.4976456665785, 383.8426245239452, 390.5352630369861, 399.9900002499939, 16612.169391530435, 18672.719909201354, 20027.0, 20200.19199232029, 20454.89772551139, 19887.90056049718, 19973.60052798943, 20035.398938031845, 20320.69518957217, 20632.968351582436, 5617.662940223599, 5934.317553481362, 5977.880442391161, 6029.005214139662, 6103.176071230371, 5306.204069389584, 6155.446009859112, 6156.538259630545, 6159.10692949522, 6159.291681456622, 5890.381509941459, 5995.010823214165, 6010.27876654802, 6085.756569737206, 6107.053406721965, 5857.297124345063, 5980.31226408962, 6048.69756512177, 6062.514998800086, 6073.4533891949495, 5094.77073531692, 5187.688738675692, 5237.607179461517, 5244.554212891909, 5258.3689957205, 20042.498937526532, 24639.876800615883, 25097.0, 25122.49755004908, 25490.725463726925, 2286.954260914789, 2295.4490922178725, 2419.987900060506, 2498.3504288884837, 3034.089773068071, 11790.469428875635, 15080.698386032218, 15308.004979676396, 15472.690546189013, 16025.599360015976, 1621.8540331370234, 1761.3835157694832, 1832.9175187116612, 1893.7538120044444, 1954.8631595788302, 112806.43596781963, 115700.42149789198, 115956.0, 116145.41927290394, 116394.41802790931, 9056.0, 10457.68626941193, 10606.0, 10903.073238774634, 11036.613718519813, 13097.672558185932, 13187.076904616584, 13387.196768193744, 13440.663983400298, 13484.460621575026, 8030.755232938787, 8217.465864718773, 8365.07984121746, 8387.83224335522, 8526.062133165347, 7359.227281135403, 8083.585372559778, 8380.245777879898, 8392.412531122885, 8433.325333973267, 5781.6241944274325, 6568.930786802238, 6634.635095069818, 6645.435138013323, 6649.401553860176, 1865.9346922857635, 1891.7067854482577, 1894.725264836591, 1919.9136038878005, 1952.2093552111417, 914.6707185413246, 938.9389689670286, 968.5447839515416, 998.1017084623896, 1061.0874647802884, 21297.893510532347, 21798.0, 21834.017469213923, 21916.780832191795, 21933.451663708536, 825.8719898415756, 896.587569717924, 956.9569369378395, 1004.457592899826, 1081.5565618096543, 11006.174536909764, 11663.950244478037, 11689.707757306136, 11727.765444691058, 11758.3532905691, 592076.0, 605472.0, 614112.0, 625337.0, 627601.0, 109705.0, 111220.33169502464, 121909.0, 126332.99999999817, 128311.35844320906, 960.6685693435753, 989.7080361293292, 1021.5505177721778, 1024.0476356988072, 1029.2332212501733, 17558.12209389551, 19060.61878762389, 19580.11889464949, 19687.72029818057, 19908.303209387603, 45663.0, 47349.05301893874, 56778.0, 56964.71517642385, 61066.0, 86712.0, 87537.0, 87953.0, 88175.55912220398, 88451.25066870633, 8161.387895907712, 8187.877181842158, 8304.173081921968, 8355.12271211514, 8370.748877533684, 12017.63947081589, 13017.958563314724, 13027.804582931074, 13458.46166153382, 13513.729725405634, 9580.712578622653, 11216.327020378803, 11268.943655281671, 11530.76938461243, 11715.589954351557, 9521.809773778277, 9989.850152247576, 10579.36523808574, 11119.610813621484, 11489.195756297313, 11342.659720208407, 11449.198556101326, 11833.52665893389, 11846.822297665369, 11941.52233910634, 7916.604169791372, 8000.15998320168, 8264.504129752235, 8377.70678026266, 8391.118932512243, 10364.689059328231, 11378.601748939078, 11515.0, 11539.480723367637, 11572.768544629229], "value": 9779.931590134978, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1531.479297039007, 1537.6540278437353, 1764.8941063536188, 1861.8789778664395, 1863.8695291329611, 7885.250901164394, 8210.425270231071, 8290.585470726463, 8844.15980481854, 8852.247558957488, 7339.88990165148, 8266.380021498382, 8407.117252688471, 8521.829563408728, 8543.444676096065, 1991.2532800199924, 1997.9400617981485, 2369.92890213293, 2372.3357459911167, 2384.0, 2032.7904896586508, 2223.3330000999717, 2377.869217193054, 2382.976170238293, 2388.5103553771437, 1970.822625963663, 1972.7829938706732, 2361.527694461106, 2367.9289621311386, 2378.21518898763, 2282.052948026567, 2312.0867257433306, 2323.488832456857, 2352.2472808701223, 2356.7643235676296, 2322.7096612923383, 2339.415146213447, 2352.0709319818575, 2353.082297903819, 2357.3045951444365, 8624.568771561397, 8649.956750216208, 8681.652733890636, 8698.695545655935, 8754.343424243207, 5182.870428239286, 5338.092524270884, 5339.065663508871, 5343.984642917833, 5349.545288650469, 3153.526970954375, 3469.496922946184, 3505.5267538882435, 3631.382664946965, 3688.5389326334207, 1047.2250534604414, 1082.577794660083, 1093.7703082352716, 1094.3652681444823, 1110.9166812489016, 11406.486708098155, 11457.0, 11457.656270311903, 11600.709982250426, 11601.303921764722, 4307.901485121274, 4656.534346565317, 4729.4797572267025, 4844.273358996108, 4853.19922212838, 9379.155875971195, 10006.54970526328, 10022.148117410103, 10049.447280399501, 10076.29465937392, 5340.172273297645, 5344.893102137936, 5346.1713434417725, 5354.9464505354445, 5362.785488580491, 4165.812538435777, 4393.472783266028, 4728.739919304471, 4841.15279826029, 4909.975450122798, 3987.6211759882763, 3987.6809855211513, 4096.528899176633, 4107.301758701028, 4125.319322311845, 1271.205496564647, 1290.6644272489032, 1292.3409061378577, 1301.8763217494322, 1304.302198323892, 839.6851180807197, 878.5914549734276, 919.4069824962861, 931.9487428191379, 980.593053882645, 5313.814016509404, 5765.221695071112, 5988.9101663475085, 6009.519238460913, 6074.605150665193, 433.1315711997488, 446.792241607654, 451.76733981999297, 830.6718846055774, 955.8852937647388, 3880.708946828999, 3973.384125460558, 4109.609587089221, 4114.259433301974, 4123.690723195772, 10425.739356515996, 10658.573657053632, 10662.786744265071, 10676.839847402296, 10685.358878467317, 10624.575016999388, 10635.46822658864, 10643.254972151875, 10650.254482186172, 10656.360618362924, 6780.660966951731, 7673.884891726629, 8041.678332866737, 8266.669333226724, 8287.875681864776, 5969.134475501115, 6046.7278972445465, 6106.877862442815, 6113.205283313141, 6194.566380353332, 9995.400275983464, 10023.498825058863, 10134.341267817568, 10163.695089147337, 10176.185905127424, 8487.8726819099, 9898.653547125816, 10034.899651003396, 10119.139873110867, 10298.794024119625, 7011.719531218694, 7346.889796653161, 7558.735444259424, 7592.658330375036, 7608.923910760822, 8336.916630833613, 8382.0, 8391.454555453876, 8416.989961204694, 8432.915670843213, 7328.083989501312, 7372.705091796268, 7428.294312040348, 7464.104307483137, 7561.130469996021, 7323.523970942085, 7383.741569045273, 7436.405087593088, 7517.511361761468, 7541.509801862861, 5991.071383935497, 6641.800745977628, 6888.517803753689, 7030.261822508563, 7073.434125270069, 4588.082383523375, 4925.867050578316, 4935.506449355035, 4980.5019498049905, 5097.6941383517105, 4930.13722598551, 5004.499550045112, 5008.874778130576, 5075.898482030412, 5186.740662966912], "value": 4509.960417340969, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.62658306586064, 295.9097475270043, 301.79327160894786, 308.65584872866754, 320.7658409361166, 5384.757685904134, 5475.138452926004, 5566.55467562595, 5754.748567059609, 5812.44781745734, 5592.636478628897, 5613.971930140344, 5701.287339082614, 5725.885482290351, 5737.196792449061, 3799.810009499528, 3992.7803970781597, 4370.847020354288, 4386.583274588925, 4395.340698895167, 4040.636342729154, 4233.555476674951, 4337.262665346882, 4423.447069116361, 4423.7566933818625, 3468.878589249377, 3568.0, 4232.767197804135, 4260.510041345238, 4293.184294983943, 3744.382176940815, 4113.0, 4298.0, 4310.6335961443, 4317.0934103838235, 3564.8395822187804, 3699.6115407882053, 3701.481792549045, 3704.129529560551, 3723.0, 5512.090505066659, 5533.335999680024, 5559.444055594448, 5569.275994120779, 5586.329640443173, 4902.117618828645, 4932.401724043201, 4937.8271760488215, 4947.0, 4953.23224900141, 4794.256890182028, 5532.419095994943, 5702.6863522506665, 5883.8529036774, 6017.909731354033, 3488.982555087234, 3855.8047005428407, 3965.8413663453425, 4022.738521996061, 4029.6977726670616, 5304.973475132639, 5938.762449502058, 6118.082287656843, 6367.42693157618, 6401.431649245965, 3436.621971583174, 3790.4314352847296, 3799.6200379962333, 4091.1408604193452, 4103.2409004333695, 4103.261412945639, 4132.090939993197, 4133.793310334471, 4157.958420415817, 4164.459150114477, 4517.638588912879, 4853.878653033702, 4889.190999330271, 4917.336159618406, 4922.286268491049, 5823.767049318065, 6085.878282434326, 6101.60339577926, 6103.542234332443, 6115.1744514490865, 1394.4213151542199, 1414.6251243420502, 1426.2868565717142, 1429.6497358147112, 1437.4537675683382, 689.7928624906414, 756.1191212237682, 832.6685281212581, 852.2713080316364, 882.1090698394668, 4482.641388688897, 4768.856934291976, 4777.948851252789, 4778.546038126373, 4780.808767649325, 4064.723754697379, 4285.61429471349, 4936.679115857458, 5128.230760387661, 5194.68831870089, 3933.0167458135465, 4708.011317623269, 4716.481187069419, 4785.856424307276, 4793.1894144154785, 2867.354845159822, 2951.977895141835, 3126.249300391788, 3254.023792862132, 3278.6885245901603, 6435.908329792743, 6440.742370305135, 6451.709673064724, 6464.288928217892, 6491.383318584727, 4680.82341711106, 5729.0260655688635, 6001.0, 6155.368332683738, 6402.519811014099, 5520.868222014477, 5597.389683654021, 6200.534959877937, 6228.719707613168, 6422.903656445157, 4773.284007398918, 5660.943390566042, 6116.5412594056515, 6188.288346840171, 6215.9378406217165, 4642.744649044335, 4781.880452988564, 5070.870234501592, 5901.852453688693, 6083.718605522929, 5338.197446340702, 6182.783602573888, 6526.738930442729, 6549.8690026200165, 6568.934310656832, 3648.744587878929, 3673.651003154696, 3931.5282166140246, 4070.7354021988476, 4088.713790034669, 6181.381861813783, 6219.937800622117, 6227.0, 6238.438540531374, 6253.5935164216, 5747.8563035924435, 5780.421957804354, 6038.486728628116, 6118.663473508822, 6157.72290246931, 4806.591439727662, 5599.04816181242, 5976.491998180203, 5976.61152025117, 6030.6080104793045, 5848.736806843787, 5895.941040589711, 5896.17453556511, 5900.527957763455, 5902.4097590240635, 5238.816641417532, 5521.917171242354, 5762.884742305214, 5975.790847320323, 6027.668478233739, 4942.851714448572, 5211.426743058185, 5579.581531385081, 5972.372900845348, 6004.307350979204], "value": 4247.762951786388, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.7859552836386, 718.3032458515238, 727.476217123671, 769.3921801776597, 819.7950512371907, 15474.38102475901, 15648.608784780386, 15789.28948197331, 15842.84157158428, 15851.920740396281, 14369.425222991067, 14446.0, 14455.710885782304, 14467.421303147861, 14487.637809054779, 680.3468670076733, 842.6671464771412, 844.1937949258459, 865.9307255419583, 869.8999615044256, 755.1693137548707, 863.8876945997011, 868.5570359116833, 869.6825658634584, 871.8953725552942, 907.5552979040256, 928.3084102343763, 929.2751653710117, 929.9116583924516, 931.8881734191906, 917.124146440148, 923.1922068190333, 923.8244733500647, 925.5233554719291, 930.2000279759402, 659.5877576514679, 678.7963610916754, 684.4729558240127, 686.2417029182781, 687.1582311668217, 14728.926355368261, 14950.40198392063, 14955.177465239407, 14984.850151498564, 14993.32530036151, 14712.264386780618, 14793.11241325524, 14831.851681483155, 14879.256037198096, 14935.626609334744, 10608.0, 11887.94056029723, 12030.977366923822, 12172.878271217263, 12356.691082722913, 3825.9808700956596, 3839.404892241679, 4048.392741088831, 4075.286824805647, 4076.7961601920083, 91096.54451727765, 114973.0, 116554.0, 116679.0, 119104.0, 9596.808063838684, 11336.376499292619, 11545.422728863523, 11566.42167891602, 11872.643820685393, 19372.547058970635, 20710.482237944172, 23262.883685581462, 23789.405264868517, 23867.880660596587, 7275.4179665626625, 8215.630296636666, 8217.055038670629, 8276.668933242603, 8504.787380315542, 4485.46174459067, 4618.076384723069, 4644.674872758942, 4649.837255696035, 4759.0, 3569.785812851237, 3671.504346913186, 3704.629537046274, 3716.3682174030478, 3730.720195985312, 666.3835951744713, 667.7896462614261, 687.08274453605, 694.8818700820873, 699.4544255480733, 504.84349851546136, 661.1504217081027, 684.5653010338372, 691.9411849992706, 708.155255559271, 9283.48940808262, 9317.301202409846, 9484.95257523708, 9705.417674939526, 9963.452010139512, 562.0978329780643, 604.3654163128786, 651.8924377477758, 685.9416949559244, 686.7664993902097, 4377.387165796791, 4689.835855745033, 4738.123447162286, 4742.454617718938, 4771.928421073617, 162842.0, 164129.0, 173347.0, 173995.0, 176272.0, 109545.0, 113237.86762132273, 126907.0, 129075.0, 133937.0, 111961.0, 116026.0, 117421.0, 118639.40680296543, 120776.0, 9642.903570964201, 9895.505224738878, 9961.113517658374, 10048.145907597644, 10089.798204036026, 52912.0, 60178.69910650419, 61875.0, 64333.35666643274, 65039.67480162569, 69735.0, 86352.27295454181, 90464.09535904556, 98441.0, 98655.0, 11964.940175299067, 12093.213941093802, 12177.939110304393, 12212.816807747713, 12330.19853709506, 14828.0, 15077.849221507644, 15091.396344146551, 15322.0, 15493.612659683147, 14072.648183795078, 14175.64560885986, 14201.644958876112, 14207.857921420653, 14228.72340425527, 14097.859021410064, 14154.079984800956, 14221.217833019284, 14654.19401932904, 14895.851041489446, 13486.325683715579, 13504.662383440493, 14118.435262589792, 14402.20787856678, 14454.783178252545, 10892.40091794959, 11170.888291116984, 11252.0, 11255.212135150461, 11751.412429378668, 9812.313138080266, 10004.499775011365, 10647.574097036031, 11445.885541144482, 11751.0], "value": 7973.9075544633915, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 109.0, 120.0, 116.0, 118.0, 118.0, 119.0, 120.0, 120.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 136.0, 162.0, 115.0, 132.0, 131.0, 113.0, 166.0, 115.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 207.0, 211.0, 236.0, 200.0, 193.0, 200.0, 197.0, 204.0, 195.0], "value": 204.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 86.0, 75.0, 146.0, 51.0, 69.0, 86.0, 83.0, 133.0, 60.0], "value": 85.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 126.0, 71.0, 73.0, 81.0, 112.0, 72.0, 73.0, 114.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 88.0, 91.0, 86.0, 135.0, 121.0, 92.0, 92.0, 122.0, 90.0], "value": 102.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 97.0, 104.0, 135.0, 97.0, 97.0, 97.0, 97.0, 117.0], "value": 103.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 92.0, 91.0, 96.0, 93.0, 94.0, 81.0, 148.0, 151.0], "value": 103.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 85.0, 85.0, 58.0, 85.0, 58.0, 48.0, 75.0, 82.0, 84.0], "value": 71.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 98.0, 59.0, 87.0, 108.0, 65.0, 73.0, 67.0, 99.0, 97.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 86.0, 98.0, 71.0, 89.0, 99.0, 75.0, 77.0, 96.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 130.0, 151.0, 158.0, 149.0, 153.0, 157.0, 153.0, 146.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 168.0, 172.0, 170.0, 167.0, 202.0, 230.0, 182.0, 157.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 69.0, 69.0, 80.0, 88.0, 87.0, 88.0, 88.0], "value": 77.7, "unit": "score"}]}]} 06:13:22 INFO - Return code: 0 06:13:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:13:22 ERROR - # TBPL SUCCESS # 06:13:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:13:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:13:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:13:22 INFO - 2016-08-08 06:13:22.809 screenresolution[1672:10653] starting screenresolution argv=screenresolution get 06:13:22 INFO - 2016-08-08 06:13:22.811 screenresolution[1672:10653] Display 0: 1600x1200x32@60 06:13:22 INFO - 2016-08-08 06:13:22.818 screenresolution[1673:10656] starting screenresolution argv=screenresolution list 06:13:22 INFO - Available Modes on Display 0 06:13:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:13:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:13:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:13:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:13:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:13:23 INFO - Intel Iris: 06:13:23 INFO - Chipset Model: Intel Iris 06:13:23 INFO - Type: GPU 06:13:23 INFO - Bus: Built-In 06:13:23 INFO - VRAM (Dynamic, Max): 1536 MB 06:13:23 INFO - Vendor: Intel (0x8086) 06:13:23 INFO - Device ID: 0x0a2e 06:13:23 INFO - Revision ID: 0x0009 06:13:23 INFO - Displays: 06:13:23 INFO - Display: 06:13:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:13:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:13:23 INFO - Display Serial Number: 1600x1200 60 06:13:23 INFO - Main Display: Yes 06:13:23 INFO - Mirror: Off 06:13:23 INFO - Online: Yes 06:13:23 INFO - Rotation: Supported 06:13:23 INFO - Return code: 0 06:13:23 INFO - Running post-action listener: _resource_record_post_action 06:13:23 INFO - [mozharness: 2016-08-08 13:13:23.060573Z] Finished run-tests step (success) 06:13:23 INFO - Running post-run listener: _resource_record_post_run 06:13:23 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38364160; Write bytes: 711664640; Read time: 386; Write time: 2082 06:13:23 INFO - TinderboxPrint: CPU usage
54.2% 06:13:23 INFO - TinderboxPrint: I/O read bytes / time
38,364,160 / 386 06:13:23 INFO - TinderboxPrint: I/O write bytes / time
711,664,640 / 2,082 06:13:23 INFO - TinderboxPrint: CPU idle
2,220.1 (45.8%) 06:13:23 INFO - TinderboxPrint: CPU system
974.5 (20.1%) 06:13:23 INFO - TinderboxPrint: CPU user
1,651.1 (34.1%) 06:13:23 INFO - TinderboxPrint: Swap in / out
445,652,992 / 0 06:13:23 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392270336; Write bytes: 385418752; Read time: 29305; Write time: 675 06:13:23 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37405696; Write bytes: 298456576; Read time: 370; Write time: 1268 06:13:23 INFO - Running post-run listener: _upload_blobber_files 06:13:23 INFO - Blob upload gear active. 06:13:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:13:23 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 06:13:23 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:13:23 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:13:23 INFO - (blobuploader) - INFO - Open directory for files ... 06:13:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:13:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:13:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:13:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:13:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:13:24 INFO - (blobuploader) - INFO - Done attempting. 06:13:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:13:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:13:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:13:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:13:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:13:25 INFO - (blobuploader) - INFO - Done attempting. 06:13:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:13:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:13:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:13:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:13:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:13:28 INFO - (blobuploader) - INFO - Done attempting. 06:13:28 INFO - (blobuploader) - INFO - Iteration through files over. 06:13:28 INFO - Return code: 0 06:13:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:13:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:13:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/23fdfe80bdb8ba4fddde731dd382320cbfc56777ed817be3f925cf21410c452a2321f1391330856206c557c61139360b940c30ab43f4eda87e62d5aa73762e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25e381b7bfeb56d94a3defbe0b038554bccb1d41fb0e41e56d488d4bd96c77ef5fd14d6108ae985874777c24d32ea0b7501359a52314311b3394c550512e27b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0690a14af3785ef5cc1a28e1f0acffdc69031757b0668ba6c4819c5d4d928ed9da1533bcb754de1795de72ad58ee3ae8729b19c3b97fe137f65cd327208c4edb"} 06:13:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:13:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:13:28 INFO - Contents: 06:13:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/23fdfe80bdb8ba4fddde731dd382320cbfc56777ed817be3f925cf21410c452a2321f1391330856206c557c61139360b940c30ab43f4eda87e62d5aa73762e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25e381b7bfeb56d94a3defbe0b038554bccb1d41fb0e41e56d488d4bd96c77ef5fd14d6108ae985874777c24d32ea0b7501359a52314311b3394c550512e27b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0690a14af3785ef5cc1a28e1f0acffdc69031757b0668ba6c4819c5d4d928ed9da1533bcb754de1795de72ad58ee3ae8729b19c3b97fe137f65cd327208c4edb"} 06:13:28 INFO - Running post-run listener: copy_logs_to_upload_dir 06:13:28 INFO - Copying logs to upload dir... 06:13:28 INFO - mkdir: /builds/slave/test/build/upload/logs 06:13:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.687518 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-08 06:13:28.528812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 06:13:28.532691) ========= 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.FYfHeYurGY/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.YRsOFG4qID/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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/Autoland/sha512/23fdfe80bdb8ba4fddde731dd382320cbfc56777ed817be3f925cf21410c452a2321f1391330856206c557c61139360b940c30ab43f4eda87e62d5aa73762e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25e381b7bfeb56d94a3defbe0b038554bccb1d41fb0e41e56d488d4bd96c77ef5fd14d6108ae985874777c24d32ea0b7501359a52314311b3394c550512e27b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0690a14af3785ef5cc1a28e1f0acffdc69031757b0668ba6c4819c5d4d928ed9da1533bcb754de1795de72ad58ee3ae8729b19c3b97fe137f65cd327208c4edb"} build_url:https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010369 build_url: 'https://queue.taskcluster.net/v1/task/VHwSYtl-T2aUJRC1NaYBvQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/23fdfe80bdb8ba4fddde731dd382320cbfc56777ed817be3f925cf21410c452a2321f1391330856206c557c61139360b940c30ab43f4eda87e62d5aa73762e20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/25e381b7bfeb56d94a3defbe0b038554bccb1d41fb0e41e56d488d4bd96c77ef5fd14d6108ae985874777c24d32ea0b7501359a52314311b3394c550512e27b4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0690a14af3785ef5cc1a28e1f0acffdc69031757b0668ba6c4819c5d4d928ed9da1533bcb754de1795de72ad58ee3ae8729b19c3b97fe137f65cd327208c4edb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 06:13:28.563831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 06:13:28.564122) ========= 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.FYfHeYurGY/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.YRsOFG4qID/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.005161 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 06:13:28.612842) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 06:13:28.613118) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 06:13:28.613417) ========= ========= Total master_lag: 0.17 =========