builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0249 starttime: 1468613601.57 results: success (0) buildid: 20160715085352 builduid: 8bec3572065643d6bbca7c0b061e4d2b revision: 12217312864daca0109a1e8f6c87fb79a2703934 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.567085) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.567551) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.567825) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.591925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.592211) ========= 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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-07-15 13:13:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 165M=0s 2016-07-15 13:13:21 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088094 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.702044) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.702363) ========= 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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.025910 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.749875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:21.750183) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 12217312864daca0109a1e8f6c87fb79a2703934 --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 12217312864daca0109a1e8f6c87fb79a2703934 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-07-15 13:13:21,830 truncating revision to first 12 chars 2016-07-15 13:13:21,830 Setting DEBUG logging. 2016-07-15 13:13:21,830 attempt 1/10 2016-07-15 13:13:21,830 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/12217312864d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 13:13:22,136 unpacking tar archive at: autoland-12217312864d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.572629 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:22.344293) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:22.344645) ========= script_repo_revision: 12217312864daca0109a1e8f6c87fb79a2703934 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:22.345032) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:22.345295) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 13:13:22.363013) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-15 13:13:22.363285) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:13:22 INFO - MultiFileLogger online at 20160715 13:13:22 in /builds/slave/test 13:13:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 13:13:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:13:22 INFO - {'append_to_log': False, 13:13:22 INFO - 'base_work_dir': '/builds/slave/test', 13:13:22 INFO - 'blob_upload_branch': 'Autoland', 13:13:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:13:22 INFO - 'branch': 'Autoland', 13:13:22 INFO - 'buildbot_json_path': 'buildprops.json', 13:13:22 INFO - 'config_files': ('talos/mac_config.py',), 13:13:22 INFO - 'default_actions': ('clobber', 13:13:22 INFO - 'read-buildbot-config', 13:13:22 INFO - 'download-and-extract', 13:13:22 INFO - 'populate-webroot', 13:13:22 INFO - 'create-virtualenv', 13:13:22 INFO - 'install', 13:13:22 INFO - 'run-tests'), 13:13:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:13:22 INFO - 'download_minidump_stackwalk': True, 13:13:22 INFO - 'download_symbols': 'ondemand', 13:13:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:13:22 INFO - 'tooltool.py': '/tools/tooltool.py', 13:13:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:13:22 INFO - '/tools/misc-python/virtualenv.py')}, 13:13:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:13:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:13:22 INFO - 'installer_path': 'installer.exe', 13:13:22 INFO - 'log_level': 'info', 13:13:22 INFO - 'log_name': 'talos', 13:13:22 INFO - 'log_to_console': True, 13:13:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:13:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:13:22 INFO - 'opt_config_files': (), 13:13:22 INFO - 'pip_index': False, 13:13:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:22 INFO - 'cmd': ('bash', 13:13:22 INFO - '-c', 13:13:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:22 INFO - 'enabled': True, 13:13:22 INFO - 'halt_on_failure': False, 13:13:22 INFO - 'name': 'check_screen_resolution'},), 13:13:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:22 INFO - 'cmd': ('bash', 13:13:22 INFO - '-c', 13:13:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:22 INFO - 'enabled': True, 13:13:22 INFO - 'halt_on_failure': False, 13:13:22 INFO - 'name': 'check_screen_resolution'},), 13:13:22 INFO - 'run_cmd_checks_enabled': True, 13:13:22 INFO - 'sps_profile': False, 13:13:22 INFO - 'sps_profile_interval': 0, 13:13:22 INFO - 'suite': 'dromaeojs', 13:13:22 INFO - 'system_bits': '32', 13:13:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:13:22 INFO - 'title': 't-yosemite-r7-0249', 13:13:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:13:22 INFO - 'use_talos_json': True, 13:13:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:13:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:13:22 INFO - 'work_dir': 'build'} 13:13:22 INFO - ##### 13:13:22 INFO - ##### Running clobber step. 13:13:22 INFO - ##### 13:13:22 INFO - Running pre-action listener: _resource_record_pre_action 13:13:22 INFO - Running main action method: clobber 13:13:22 INFO - rmtree: /builds/slave/test/build 13:13:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:13:24 INFO - Running post-action listener: _resource_record_post_action 13:13:24 INFO - ##### 13:13:24 INFO - ##### Finished clobber step (success) 13:13:24 INFO - ##### 13:13:24 INFO - ##### 13:13:24 INFO - ##### Running read-buildbot-config step. 13:13:24 INFO - ##### 13:13:24 INFO - Running pre-action listener: _resource_record_pre_action 13:13:24 INFO - Running main action method: read_buildbot_config 13:13:24 INFO - Using buildbot properties: 13:13:24 INFO - { 13:13:24 INFO - "project": "", 13:13:24 INFO - "product": "firefox", 13:13:24 INFO - "script_repo_revision": "production", 13:13:24 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 13:13:24 INFO - "repository": "", 13:13:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 13:13:24 INFO - "buildid": "20160715085352", 13:13:24 INFO - "pgo_build": "False", 13:13:24 INFO - "basedir": "/builds/slave/test", 13:13:24 INFO - "buildnumber": 37, 13:13:24 INFO - "slavename": "t-yosemite-r7-0249", 13:13:24 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 13:13:24 INFO - "platform": "yosemite_r7", 13:13:24 INFO - "scheduler": "tests-autoland-macosx64-talos", 13:13:24 INFO - "branch": "autoland", 13:13:24 INFO - "repo_path": "integration/autoland", 13:13:24 INFO - "revision": "12217312864daca0109a1e8f6c87fb79a2703934", 13:13:24 INFO - "stage_platform": "macosx64", 13:13:24 INFO - "builduid": "8bec3572065643d6bbca7c0b061e4d2b", 13:13:24 INFO - "slavebuilddir": "test" 13:13:24 INFO - } 13:13:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 13:13:24 INFO - Running post-action listener: _resource_record_post_action 13:13:24 INFO - ##### 13:13:24 INFO - ##### Finished read-buildbot-config step (success) 13:13:24 INFO - ##### 13:13:24 INFO - ##### 13:13:24 INFO - ##### Running download-and-extract step. 13:13:24 INFO - ##### 13:13:24 INFO - Running pre-action listener: _resource_record_pre_action 13:13:24 INFO - Running main action method: download_and_extract 13:13:24 INFO - mkdir: /builds/slave/test/build/tests 13:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:24 INFO - https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:13:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:13:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:13:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 13:13:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 13:13:26 INFO - Downloaded 1199 bytes. 13:13:26 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 13:13:26 INFO - Using the following test package requirements: 13:13:26 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:13:26 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 13:13:26 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 13:13:26 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'jsshell-mac.zip'], 13:13:26 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 13:13:26 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:13:26 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 13:13:26 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 13:13:26 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 13:13:26 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:13:26 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 13:13:26 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:26 INFO - https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:13:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:13:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:13:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:13:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:13:27 INFO - Downloaded 18829248 bytes. 13:13:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:13:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:13:30 INFO - Return code: 0 13:13:30 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:30 INFO - https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:13:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:13:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:13:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:13:31 INFO - Downloaded 18829248 bytes. 13:13:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:13:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:13:35 INFO - Return code: 0 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:35 INFO - https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:13:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:13:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:13:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:13:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:13:36 INFO - Downloaded 14403317 bytes. 13:13:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:13:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:13:36 INFO - Return code: 0 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:36 INFO - https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:13:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:13:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:13:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 13:13:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 13:13:39 INFO - Downloaded 112767207 bytes. 13:13:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:13:39 INFO - mkdir: /builds/slave/test/properties 13:13:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:13:39 INFO - Writing to file /builds/slave/test/properties/build_url 13:13:39 INFO - Contents: 13:13:39 INFO - build_url:https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:13:40 INFO - Running post-action listener: _resource_record_post_action 13:13:40 INFO - Running post-action listener: set_extra_try_arguments 13:13:40 INFO - ##### 13:13:40 INFO - ##### Finished download-and-extract step (success) 13:13:40 INFO - ##### 13:13:40 INFO - ##### 13:13:40 INFO - ##### Running populate-webroot step. 13:13:40 INFO - ##### 13:13:40 INFO - Running pre-action listener: _resource_record_pre_action 13:13:40 INFO - Running main action method: populate_webroot 13:13:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:13:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:13:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:13:40 INFO - '--tppagecycles', 13:13:40 INFO - '7'], 13:13:40 INFO - 'tests': ['tsvgm']}}, 13:13:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:13:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:13:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:13:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:13:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:13:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:13:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['damp', 'tps']}, 13:13:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'tests': ['damp', 'tps']}, 13:13:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['dromaeo_dom']}, 13:13:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:13:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['basic_compositor_video']}, 13:13:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:13:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['a11yr', 13:13:40 INFO - 'ts_paint', 13:13:40 INFO - 'tpaint', 13:13:40 INFO - 'sessionrestore', 13:13:40 INFO - 'sessionrestore_no_auto_restore', 13:13:40 INFO - 'tabpaint']}, 13:13:40 INFO - 'other-e10s': {'tests': ['a11yr', 13:13:40 INFO - 'ts_paint', 13:13:40 INFO - 'tpaint', 13:13:40 INFO - 'sessionrestore', 13:13:40 INFO - 'sessionrestore_no_auto_restore', 13:13:40 INFO - 'tabpaint']}, 13:13:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:13:40 INFO - 'ts_paint', 13:13:40 INFO - 'tpaint', 13:13:40 INFO - 'sessionrestore', 13:13:40 INFO - 'sessionrestore_no_auto_restore', 13:13:40 INFO - 'tabpaint']}, 13:13:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:13:40 INFO - 'ts_paint', 13:13:40 INFO - 'tpaint', 13:13:40 INFO - 'sessionrestore', 13:13:40 INFO - 'sessionrestore_no_auto_restore', 13:13:40 INFO - 'tabpaint']}, 13:13:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['a11yr', 13:13:40 INFO - 'ts_paint', 13:13:40 INFO - 'tpaint', 13:13:40 INFO - 'sessionrestore', 13:13:40 INFO - 'sessionrestore_no_auto_restore', 13:13:40 INFO - 'tabpaint']}, 13:13:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['a11yr', 13:13:40 INFO - 'ts_paint', 13:13:40 INFO - 'tpaint', 13:13:40 INFO - 'sessionrestore', 13:13:40 INFO - 'sessionrestore_no_auto_restore', 13:13:40 INFO - 'tabpaint']}, 13:13:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['tsvgx', 13:13:40 INFO - 'tsvgr_opacity', 13:13:40 INFO - 'tart', 13:13:40 INFO - 'tscrollx', 13:13:40 INFO - 'cart']}, 13:13:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:13:40 INFO - 'tsvgr_opacity', 13:13:40 INFO - 'tart', 13:13:40 INFO - 'tscrollx', 13:13:40 INFO - 'cart']}, 13:13:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'talos_options': ['--disable-e10s'], 13:13:40 INFO - 'tests': ['tp5o']}, 13:13:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'tests': ['tp5o']}, 13:13:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'talos_options': ['--disable-e10s', 13:13:40 INFO - '--xperf_path', 13:13:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:13:40 INFO - 'tests': ['tp5n']}, 13:13:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:13:40 INFO - 'talos_options': ['--xperf_path', 13:13:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:13:40 INFO - 'tests': ['tp5n']}}, 13:13:40 INFO - 'talos.zip': {'path': '', 13:13:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:13:40 INFO - Running post-action listener: _resource_record_post_action 13:13:40 INFO - ##### 13:13:40 INFO - ##### Finished populate-webroot step (success) 13:13:40 INFO - ##### 13:13:40 INFO - ##### 13:13:40 INFO - ##### Running create-virtualenv step. 13:13:40 INFO - ##### 13:13:40 INFO - Running pre-action listener: _resource_record_pre_action 13:13:40 INFO - Running main action method: create_virtualenv 13:13:40 INFO - Creating virtualenv /builds/slave/test/build/venv 13:13: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 13:13:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:13:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:13:41 INFO - Using real prefix '/tools/python27' 13:13:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:13:41 INFO - Installing distribute.............................................................................................................................................................................................done. 13:13:44 INFO - Installing pip.................done. 13:13:44 INFO - Return code: 0 13:13:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:13: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', 'pip>=1.5'] in /builds/slave/test/build 13:13: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 pip>=1.5 13:13:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:13:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:44 INFO - 'HOME': '/Users/cltbld', 13:13:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:44 INFO - 'LOGNAME': 'cltbld', 13:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:44 INFO - 'MOZ_NO_REMOTE': '1', 13:13:44 INFO - 'NO_EM_RESTART': '1', 13:13:44 INFO - 'PAGER': '/bin/cat', 13:13:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:44 INFO - 'PWD': '/builds/slave/test', 13:13:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:44 INFO - 'SHELL': '/bin/bash', 13:13:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:13:44 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:13:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:44 INFO - 'USER': 'cltbld', 13:13:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:44 INFO - 'XPC_FLAGS': '0x0', 13:13:44 INFO - 'XPC_SERVICE_NAME': '0', 13:13:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:13: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 13:13:44 INFO - Cleaning up... 13:13:44 INFO - Return code: 0 13:13:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13: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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:13: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 13:13: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 13:13:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:13:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:44 INFO - 'HOME': '/Users/cltbld', 13:13:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:44 INFO - 'LOGNAME': 'cltbld', 13:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:44 INFO - 'MOZ_NO_REMOTE': '1', 13:13:44 INFO - 'NO_EM_RESTART': '1', 13:13:44 INFO - 'PAGER': '/bin/cat', 13:13:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:44 INFO - 'PWD': '/builds/slave/test', 13:13:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:44 INFO - 'SHELL': '/bin/bash', 13:13:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:13:44 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:13:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:44 INFO - 'USER': 'cltbld', 13:13:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:44 INFO - 'XPC_FLAGS': '0x0', 13:13:44 INFO - 'XPC_SERVICE_NAME': '0', 13:13:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:13:45 INFO - Downloading/unpacking psutil>=3.1.1 13:13:45 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 13:13:45 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 13:13:45 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 13:13:45 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 13:13:45 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 13:13:45 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 13:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:13:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:13:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:13:48 INFO - Installing collected packages: psutil 13:13:48 INFO - Running setup.py install for psutil 13:13:48 INFO - building 'psutil._psutil_osx' extension 13:13: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:13: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 13:13: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 13:13: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 13:13:49 INFO - building 'psutil._psutil_posix' extension 13:13:49 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 13:13:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:13:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:13:49 INFO - ^ 13:13:49 INFO - 1 warning generated. 13:13:49 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 13:13:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:13:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:13:49 INFO - Successfully installed psutil 13:13:49 INFO - Cleaning up... 13:13:49 INFO - Return code: 0 13:13:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13:49 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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:13:49 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 13:13:49 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 13:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:49 INFO - 'HOME': '/Users/cltbld', 13:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:49 INFO - 'LOGNAME': 'cltbld', 13:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:49 INFO - 'MOZ_NO_REMOTE': '1', 13:13:49 INFO - 'NO_EM_RESTART': '1', 13:13:49 INFO - 'PAGER': '/bin/cat', 13:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:49 INFO - 'PWD': '/builds/slave/test', 13:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:49 INFO - 'SHELL': '/bin/bash', 13:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:13:49 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:49 INFO - 'USER': 'cltbld', 13:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:49 INFO - 'XPC_FLAGS': '0x0', 13:13:49 INFO - 'XPC_SERVICE_NAME': '0', 13:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:13:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:13:49 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 13:13:49 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 13:13:49 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 13:13:49 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 13:13:49 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 13:13:49 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 13:13:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:13:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:13:52 INFO - Installing collected packages: mozsystemmonitor 13:13:52 INFO - Running setup.py install for mozsystemmonitor 13:13:52 INFO - Successfully installed mozsystemmonitor 13:13:52 INFO - Cleaning up... 13:13:52 INFO - Return code: 0 13:13:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13: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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:13: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 13:13: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 13:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:52 INFO - 'HOME': '/Users/cltbld', 13:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:52 INFO - 'LOGNAME': 'cltbld', 13:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:52 INFO - 'MOZ_NO_REMOTE': '1', 13:13:52 INFO - 'NO_EM_RESTART': '1', 13:13:52 INFO - 'PAGER': '/bin/cat', 13:13:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:52 INFO - 'PWD': '/builds/slave/test', 13:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:52 INFO - 'SHELL': '/bin/bash', 13:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:13:52 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:52 INFO - 'USER': 'cltbld', 13:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:52 INFO - 'XPC_FLAGS': '0x0', 13:13:52 INFO - 'XPC_SERVICE_NAME': '0', 13:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:13:52 INFO - Downloading/unpacking jsonschema==2.5.1 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:13:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13:55 INFO - Downloading functools32-3.2.3-2.tar.gz 13:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:13:55 INFO - warning: no files found matching '*.txt' 13:13:55 INFO - no previously-included directories found matching 'build' 13:13:55 INFO - no previously-included directories found matching 'dist' 13:13:55 INFO - no previously-included directories found matching '.git*' 13:13:55 INFO - Installing collected packages: jsonschema, functools32 13:13:55 INFO - Running setup.py install for functools32 13:13:55 INFO - warning: no files found matching '*.txt' 13:13:55 INFO - no previously-included directories found matching 'build' 13:13:55 INFO - no previously-included directories found matching 'dist' 13:13:55 INFO - no previously-included directories found matching '.git*' 13:13:55 INFO - Successfully installed jsonschema functools32 13:13:55 INFO - Cleaning up... 13:13:55 INFO - Return code: 0 13:13:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:13:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:13:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:13:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:13:55 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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:13:55 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 13:13:55 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 13:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:55 INFO - 'HOME': '/Users/cltbld', 13:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:55 INFO - 'LOGNAME': 'cltbld', 13:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:55 INFO - 'MOZ_NO_REMOTE': '1', 13:13:55 INFO - 'NO_EM_RESTART': '1', 13:13:55 INFO - 'PAGER': '/bin/cat', 13:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:55 INFO - 'PWD': '/builds/slave/test', 13:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:55 INFO - 'SHELL': '/bin/bash', 13:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:13:55 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:55 INFO - 'USER': 'cltbld', 13:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:55 INFO - 'XPC_FLAGS': '0x0', 13:13:55 INFO - 'XPC_SERVICE_NAME': '0', 13:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:13:56 INFO - Downloading/unpacking blobuploader==1.2.4 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13:58 INFO - Downloading blobuploader-1.2.4.tar.gz 13:13:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:13:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:13:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:13:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:13:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13: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 13:13:59 INFO - Downloading docopt-0.6.1.tar.gz 13:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:13:59 INFO - Installing collected packages: blobuploader, requests, docopt 13:13:59 INFO - Running setup.py install for blobuploader 13:14:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:14:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:14:00 INFO - Running setup.py install for requests 13:14:00 INFO - Running setup.py install for docopt 13:14:00 INFO - Successfully installed blobuploader requests docopt 13:14:00 INFO - Cleaning up... 13:14:00 INFO - Return code: 0 13:14:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:00 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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:14:00 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 13:14:00 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 13:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:00 INFO - 'HOME': '/Users/cltbld', 13:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:00 INFO - 'LOGNAME': 'cltbld', 13:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:00 INFO - 'MOZ_NO_REMOTE': '1', 13:14:00 INFO - 'NO_EM_RESTART': '1', 13:14:00 INFO - 'PAGER': '/bin/cat', 13:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:00 INFO - 'PWD': '/builds/slave/test', 13:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:00 INFO - 'SHELL': '/bin/bash', 13:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:14:00 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:00 INFO - 'USER': 'cltbld', 13:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:00 INFO - 'XPC_FLAGS': '0x0', 13:14:00 INFO - 'XPC_SERVICE_NAME': '0', 13:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-czGkpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-PzaQLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-RKRKrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ZaScpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-PejBtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-qpTOoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-miEEr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-2SqIK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-YLBs6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Fk6c0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-3eoIWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-CuKOp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Y54dDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-pG9cOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Oyn_vo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-jDtLYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-C5kDaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:14:02 INFO - Running setup.py install for manifestparser 13:14:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:14:02 INFO - Running setup.py install for mozcrash 13:14:03 INFO - Running setup.py install for mozdebug 13:14:03 INFO - Running setup.py install for mozdevice 13:14:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Running setup.py install for mozfile 13:14:03 INFO - Running setup.py install for mozhttpd 13:14:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Running setup.py install for mozinfo 13:14:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Running setup.py install for mozInstall 13:14:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:14:03 INFO - Running setup.py install for mozleak 13:14:04 INFO - Running setup.py install for mozlog 13:14:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:14:04 INFO - Running setup.py install for moznetwork 13:14:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:14:04 INFO - Running setup.py install for mozprocess 13:14:04 INFO - Running setup.py install for mozprofile 13:14:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:14:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:14:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:14:04 INFO - Running setup.py install for mozrunner 13:14:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:14:04 INFO - Running setup.py install for mozscreenshot 13:14:05 INFO - Running setup.py install for moztest 13:14:05 INFO - Running setup.py install for mozversion 13:14:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:14:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:14:05 INFO - Cleaning up... 13:14:05 INFO - Return code: 0 13:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14: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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:14: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 13:14: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 13:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:05 INFO - 'HOME': '/Users/cltbld', 13:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:05 INFO - 'LOGNAME': 'cltbld', 13:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:05 INFO - 'MOZ_NO_REMOTE': '1', 13:14:05 INFO - 'NO_EM_RESTART': '1', 13:14:05 INFO - 'PAGER': '/bin/cat', 13:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:05 INFO - 'PWD': '/builds/slave/test', 13:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:05 INFO - 'SHELL': '/bin/bash', 13:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:14:05 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:05 INFO - 'USER': 'cltbld', 13:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:05 INFO - 'XPC_FLAGS': '0x0', 13:14:05 INFO - 'XPC_SERVICE_NAME': '0', 13:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:05 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4WMrXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:05 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)) 13:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:05 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-1hNLKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:05 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)) 13:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:05 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-L7FgRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:05 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)) 13:14:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:05 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-G4x0Q5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14: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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-e3oUzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14: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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-rM9CdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14: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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-S1rWu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14: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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-04RmwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14: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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-8Peif6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14: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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Al6Vti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-nQpEPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:06 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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-hcqwMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:06 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)) 13:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:06 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-HOJoTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14: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)) 13:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:07 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-xGuiar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14: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)) 13:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:07 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-aYJfTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14: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)) 13:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:07 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-lWCpd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14: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)) 13:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:07 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-9wUWOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14: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)) 13:14: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)) 13:14: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)) 13:14: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)) 13:14: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)) 13:14: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)) 13:14:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:14: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 13:14: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 13:14: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 13:14: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 13:14: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 13:14: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 13:14:09 INFO - Downloading blessings-1.6.tar.gz 13:14:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:14:10 INFO - Installing collected packages: blessings 13:14:10 INFO - Running setup.py install for blessings 13:14:10 INFO - Successfully installed blessings 13:14:10 INFO - Cleaning up... 13:14:10 INFO - Return code: 0 13:14:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:14:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:10 INFO - Reading from file tmpfile_stdout 13:14:10 INFO - Current package versions: 13:14:10 INFO - blessings == 1.6 13:14:10 INFO - blobuploader == 1.2.4 13:14:10 INFO - docopt == 0.6.1 13:14:10 INFO - functools32 == 3.2.3-2 13:14:10 INFO - jsonschema == 2.5.1 13:14:10 INFO - manifestparser == 1.1 13:14:10 INFO - mozInstall == 1.12 13:14:10 INFO - mozcrash == 0.17 13:14:10 INFO - mozdebug == 0.1 13:14:10 INFO - mozdevice == 0.48 13:14:10 INFO - mozfile == 1.2 13:14:10 INFO - mozhttpd == 0.7 13:14:10 INFO - mozinfo == 0.9 13:14:10 INFO - mozleak == 0.1 13:14:10 INFO - mozlog == 3.2 13:14:10 INFO - moznetwork == 0.27 13:14:10 INFO - mozprocess == 0.23 13:14:10 INFO - mozprofile == 0.28 13:14:10 INFO - mozrunner == 6.12 13:14:10 INFO - mozscreenshot == 0.1 13:14:10 INFO - mozsystemmonitor == 0.3 13:14:10 INFO - moztest == 0.7 13:14:10 INFO - mozversion == 1.4 13:14:10 INFO - psutil == 3.1.1 13:14:10 INFO - requests == 1.2.3 13:14:10 INFO - wsgiref == 0.1.2 13:14:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14: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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:14: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 13:14: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 13:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:10 INFO - 'HOME': '/Users/cltbld', 13:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:10 INFO - 'LOGNAME': 'cltbld', 13:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:10 INFO - 'MOZ_NO_REMOTE': '1', 13:14:10 INFO - 'NO_EM_RESTART': '1', 13:14:10 INFO - 'PAGER': '/bin/cat', 13:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:10 INFO - 'PWD': '/builds/slave/test', 13:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:10 INFO - 'SHELL': '/bin/bash', 13:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:14:10 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:10 INFO - 'USER': 'cltbld', 13:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:10 INFO - 'XPC_FLAGS': '0x0', 13:14:10 INFO - 'XPC_SERVICE_NAME': '0', 13:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 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)) 13:14:10 INFO - Cleaning up... 13:14:10 INFO - Return code: 0 13:14:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:10 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 0x1077a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107837618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee195778f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, '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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:14:10 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 13:14:10 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 13:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:10 INFO - 'HOME': '/Users/cltbld', 13:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:10 INFO - 'LOGNAME': 'cltbld', 13:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:10 INFO - 'MOZ_NO_REMOTE': '1', 13:14:10 INFO - 'NO_EM_RESTART': '1', 13:14:10 INFO - 'PAGER': '/bin/cat', 13:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:10 INFO - 'PWD': '/builds/slave/test', 13:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:10 INFO - 'SHELL': '/bin/bash', 13:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:14:10 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:10 INFO - 'USER': 'cltbld', 13:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:10 INFO - 'XPC_FLAGS': '0x0', 13:14:10 INFO - 'XPC_SERVICE_NAME': '0', 13:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:14:11 INFO - Cleaning up... 13:14:11 INFO - Return code: 0 13:14:11 INFO - Running post-action listener: _resource_record_post_action 13:14:11 INFO - Running post-action listener: _start_resource_monitoring 13:14:11 INFO - Starting resource monitoring. 13:14:11 INFO - ##### 13:14:11 INFO - ##### Finished create-virtualenv step (success) 13:14:11 INFO - ##### 13:14:11 INFO - ##### 13:14:11 INFO - ##### Running install step. 13:14:11 INFO - ##### 13:14:11 INFO - Running pre-action listener: _resource_record_pre_action 13:14:11 INFO - Running main action method: install 13:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:11 INFO - Reading from file tmpfile_stdout 13:14:11 INFO - Detecting whether we're running mozinstall >=1.0... 13:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:14:11 INFO - Reading from file tmpfile_stdout 13:14:11 INFO - Output received: 13:14:11 INFO - Usage: mozinstall [options] installer 13:14:11 INFO - Options: 13:14:11 INFO - -h, --help show this help message and exit 13:14:11 INFO - -d DEST, --destination=DEST 13:14:11 INFO - Directory to install application into. [default: 13:14:11 INFO - "/builds/slave/test"] 13:14:11 INFO - --app=APP Application being installed. [default: firefox] 13:14:11 INFO - mkdir: /builds/slave/test/build/application 13:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:14:45 INFO - Reading from file tmpfile_stdout 13:14:45 INFO - Output received: 13:14:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:14:45 INFO - Running post-action listener: _resource_record_post_action 13:14:45 INFO - ##### 13:14:45 INFO - ##### Finished install step (success) 13:14:45 INFO - ##### 13:14:45 INFO - ##### 13:14:45 INFO - ##### Running run-tests step. 13:14:45 INFO - ##### 13:14:45 INFO - Running pre-action listener: _resource_record_pre_action 13:14:45 INFO - Running main action method: run_tests 13:14:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:14:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:14:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:14:45 INFO - 2016-07-15 13:14:45.899 screenresolution[1637:7671] starting screenresolution argv=screenresolution get 13:14:45 INFO - 2016-07-15 13:14:45.901 screenresolution[1637:7671] Display 0: 1600x1200x32@60 13:14:45 INFO - 2016-07-15 13:14:45.907 screenresolution[1638:7673] starting screenresolution argv=screenresolution list 13:14:45 INFO - Available Modes on Display 0 13:14:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:14:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:14:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:14:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:14:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:14:46 INFO - Intel Iris: 13:14:46 INFO - Chipset Model: Intel Iris 13:14:46 INFO - Type: GPU 13:14:46 INFO - Bus: Built-In 13:14:46 INFO - VRAM (Dynamic, Max): 1536 MB 13:14:46 INFO - Vendor: Intel (0x8086) 13:14:46 INFO - Device ID: 0x0a2e 13:14:46 INFO - Revision ID: 0x0009 13:14:46 INFO - Displays: 13:14:46 INFO - Display: 13:14:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:14:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:14:46 INFO - Display Serial Number: 1600x1200 60 13:14:46 INFO - Main Display: Yes 13:14:46 INFO - Mirror: Off 13:14:46 INFO - Online: Yes 13:14:46 INFO - Rotation: Supported 13:14:46 INFO - Return code: 0 13:14:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:14:46 INFO - Python 2.7.3 13:14:46 INFO - Return code: 0 13:14:46 INFO - grabbing minidump binary from tooltool 13:14: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:14: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 0x107706d30>, '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 0x10746bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1076feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:14: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 13:14: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 13:14: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 13:14:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:14:46 INFO - Return code: 0 13:14:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:14:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:14:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:14:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:14:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:14: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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:14: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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0249 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:14:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nW7SkV9Oh4/Render', 13:14:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:46 INFO - 'HOME': '/Users/cltbld', 13:14:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:46 INFO - 'LOGNAME': 'cltbld', 13:14:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:14:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:46 INFO - 'MOZ_NO_REMOTE': '1', 13:14:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:14:46 INFO - 'NO_EM_RESTART': '1', 13:14:46 INFO - 'PAGER': '/bin/cat', 13:14:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:46 INFO - 'PWD': '/builds/slave/test', 13:14:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:14:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:46 INFO - 'SHELL': '/bin/bash', 13:14:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jUPVbQ3QCB/Listeners', 13:14:46 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:14:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:46 INFO - 'USER': 'cltbld', 13:14:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:46 INFO - 'XPC_FLAGS': '0x0', 13:14:46 INFO - 'XPC_SERVICE_NAME': '0', 13:14:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:14:46 INFO - mozversion application_buildid: 20160715085352 13:14:46 INFO - mozversion application_changeset: 12217312864daca0109a1e8f6c87fb79a2703934 13:14:46 INFO - mozversion application_display_name: Nightly 13:14:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:14:46 INFO - mozversion application_name: Firefox 13:14:46 INFO - mozversion application_remotingname: firefox 13:14:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:14:46 INFO - mozversion application_vendor: Mozilla 13:14:46 INFO - mozversion application_version: 50.0a1 13:14:46 INFO - mozversion platform_buildid: 20160715085352 13:14:46 INFO - mozversion platform_changeset: 12217312864daca0109a1e8f6c87fb79a2703934 13:14:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:14:46 INFO - mozversion platform_version: 50.0a1 13:14:46 INFO - using testdate: 1468613686 13:14:46 INFO - actual date: 1468613686 13:14:46 INFO - starting webserver on 'localhost:49270' 13:14:46 INFO - SUITE-START | Running 2 tests 13:14:46 INFO - TEST-START | dromaeo_css 13:14:46 INFO - operating with platform_type : mac_ 13:14:46 INFO - Initialising browser for dromaeo_css test... 13:14:46 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpkotFRL/profile http://localhost:49270/getInfo.html) 13:14:48 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 13:14:48 INFO - PROCESS | 1648 | colorDepth:24 13:14:48 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 13:14:48 INFO - PROCESS | 1648 | __metrics 13:14:48 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:14:48 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:14:49 INFO - TEST-INFO | 1648: exit 0 13:14:49 INFO - Browser initialized. 13:14:49 INFO - Running cycle 1/1 for dromaeo_css test... 13:14:49 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpkotFRL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:15:01 INFO - PROCESS | 1650 | RSS: Main: 244088832 13:15:01 INFO - PROCESS | 1650 | 13:15:52 INFO - PROCESS | 1650 | 1468613752402 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] 13:18:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 13:18:05 INFO - PROCESS | 1650 | RSS: Main: 373882880 13:18:05 INFO - PROCESS | 1650 | 13:21:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 13:21:09 INFO - PROCESS | 1650 | RSS: Main: 382521344 13:21:09 INFO - PROCESS | 1650 | 13:24:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 13:24:12 INFO - PROCESS | 1650 | RSS: Main: 373817344 13:24:12 INFO - PROCESS | 1650 | 13:27:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 13:27:15 INFO - PROCESS | 1650 | RSS: Main: 369733632 13:27:15 INFO - PROCESS | 1650 | 13:30:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 13:30:19 INFO - PROCESS | 1650 | RSS: Main: 342478848 13:30:19 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 13:33:22 INFO - PROCESS | 1650 | RSS: Main: 386469888 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | __start_tp_report 13:33:22 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 13:33:22 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 13:33:22 INFO - PROCESS | 1650 | |i|pagename|runs| 13:33:22 INFO - PROCESS | 1650 | |0;dojo.html;2777.7222277772225;3338.4491558892782;3353.932921341573;3365.3101114271576;3394.0157354367234;4627.375304333914;5562.915231529853;5692.544596432286;5743.282089738783;5766.855828604287;5677.375488696251;5722.914156287648;5734.5125664318475;5738.971305143468;5747.798827041054;5181.715005674687;5220.765065572039;5263.789448422078;5264.105102132647;5325.973370133144;4242.427272318244;5259.6055295852775;5278.498542638463;5305.0450918834595;5305.177697456881;5252.52727254547;5261.210818377255;5275.393329767068;5302.973485132588;5307.150855863063;5010.448850626428;5304.124819404813;5332.653377530487;5333.653312534673;5353.625246232766;5135.075686376451;5139.820106296262;5145.176771716508;5261.473852614746;5272.710000949946;5609.298837645294;5619.719014049322;5673.23411339468;5678.488935995739;5705.486506214461;6262.561620686553;6280.214973128359;6286.874262514724;6289.056641503811;6302.46429053531;6127.509799216052;6276.372362763733;6290.591111577733;6307.274663413675;6336.2396512418345;5834.299884013861;5849.210356601804;5858.06270996647;5898.174255604219;5917.230760001173;6234.407731265523;6243.157173781481;6277.403646653561;6287.717052732638;6295.874082518324;5159.974200128975;6001.369856165127;6061.878762424727;6068.918905487468;6135.477655820289;6263.655498947511;6269.341719119518;6282.685865706788;6284.905726414107;6289.056641503719;6219.315875253809;6265.71804268809;6267.561270711098;6276.246850377984;6276.717547710364;6142.815715528542;6175.96912015437;6188.9690551546955;6223.751049958041;6283.246010478681;4945.703257804544;4970.080535101018;4975.975120124376;4989.101961646938;5002.624803139779;4447.62195213404;4448.154850578379;4456.910861782746;4468.776561171929;4487.035287413157;5652.830415087554;5774.82675519735;5795.768169273266;5855.355910849803;5857.20927674767;6182.1345011698395;6227.470664993436;6242.875142497124;6245.906311405332;6263.217097862767;5725.770969161187;5768;5771.451712087345;5779.33537643168;5807.186993820868;5335.546478549367;5467.6719396836315;5474.479924407175;5475.972620136953;5485.917711234335;5279.604029697711;6164.969175154096;6177.9691101546005;6189.8452538686;6277.843053923689;5271.472852714698;6248.656323902229;6262;6262;6263.937360626518;6243.575929410846;6293.716782744696;6304.621722696653;6309.747610095728;6316.7473301069285;5982.102684597346;6118.984682626333;6151.600145990676;6185.474234690102;6241.750329986931;6225.657588832658;6262.655553944577;6289.371062893674;6290.276618188784;6298.937010630018;6182.28903676083;6195.473384762346;6200.286966998853;6202.844928876814;6210.254769427697;5854.853628659318;5869.589128760946;5904.793332233351;5915.349311575637;5952.226210592596;5744.684042377542;5756.539476841759;5763.913541296968;5774.682392468455;5816.534677225727;5866.325372582208;5907.911381329198;5918.437748413894;5947.01874190771;6003.129546215687;5879.7060146993335;5885.293764748258;5885.941140588539;5911.320198177093;5972.223610930551 13:33:22 INFO - PROCESS | 1650 | |1;ext.html;501.2631431795262;530.8221745715185;536.5948708724912;542.1325878594248;566.8299510146954;30543.99999999997;32170.67829321706;42061.36907946383;43108.78445607775;43519;44619.88450288744;44805.55194448055;45029;45032.774836125776;45155.548444515545;9718.36830606012;9899;9901.851472227887;9902;9917.157041651468;8845.336599755012;9042.819143617124;11999.10006749493;12179.57371491998;12180.208286461351;12182.147249692525;12222.816657750142;12244.448999795031;12250.203736757127;12258.93870530646;8872.600732966968;9021.278297736166;12163.08776841736;12246.81629775554;12278.877211227908;8756.693515726982;8816.735497935073;11694.2983420995;11941.761164776743;11951.282923024646;42425.15149696989;42832.78583607093;42876.35685464721;42908.57091429077;42938.57061429377;3770.6606405423645;3881.029742564359;3955.208958208341;4367.538084076114;4428.136513379892;31931.840340798146;31984.360312793615;33170.00489985304;33780.83109584461;33797.155071123176;3326.234965957819;3327.2680010397676;3327.45097058985;3385.4075536780965;3417.2994536120036;462340;464203;464219;466252;466560.9999999966;61271.69364153151;62028.37971620316;62029.68985155045;62034.68982655058;62100.68949655223;65265;65328;65369.34630653728;65499.34500654932;65574.34425655777;16192.19039048043;16884.746728799077;16893.493195204162;16910.239039243264;16915.40796072156;11871.990880775058;12120;12627.494900204072;13027.869721302855;13087.345632718327;16665.833341666428;16686.58283542897;16691.415800446925;16705.331786728395;16740.330386784637;7280.271972802783;7283.927160728431;7356.117265928123;7378.446616503784;7392.741254056083;914.8993610702818;960.8366577681811;990.821652102614;1037.3775734559204;1044.3525014491065;31270.687293127234;31324.686753132177;31533;31792.841035795136;32060.038198854072;1063.7659714862718;1070.4754670211696;1071.5553045486192;1091.82530795074;1216.2094638484878;16221.513354599381;16225.188740562924;16400.933939293907;16408.26162822676;16714.749278760828;688507;847614;866688;868594;870108;61865.690671546356;61928.761424771255;62532.37467625266;62794.68602656957;62817.37182628115;1285.54363201066;1337.2979185927466;1418.3971811979868;1426.5934208750457;1445.9349329280208;29201.657705648162;30573.694263057976;32260.677393225767;32414.83792581022;32493.837530812194;56339.154912677164;57414.71292643677;57535.136972946275;57550;57659.4234057654;538964;560414;560449;560690;563731;23588.29235122949;26118.34704132412;26160;26207.21378358652;26692.8665356672;48183.27725084056;48459.51540484691;48561.5143848557;49176;49776.75111624419;39727.404088938114;39839.601603984745;39858.402123967586;40265.99335016648;41295.838020247465;38061.2387752249;39738;40417.59582404256;40734.38898416467;41097.58902410937;38235.42646860244;38504.614953850105;38651.03372415712;38948.6105138945;39099.609003909594;34291.65708342884;35466.8226658865;36404;36523;36799.63200367962;26231.344216394744;26450.074247401248;26527.734722652527;26596.60105098464;26945.865270673523 13:33:22 INFO - PROCESS | 1650 | |2;jquery.html;363.4022033754476;378.8389934277932;389.8070455124713;391.1355903453366;411.1714894487606;15376.538703838889;15704.450344237954;20155.193792248294;20926.95365231738;20966.16135354588;20428.795712042876;20899.164033438683;21246.468838279052;21564.784352156476;21568.784312156913;5450.236966824647;5716.428357164292;5942.435468630473;5942.6434413935085;5972.283326000886;5839.007368747302;5864.384239654839;5929.851753706171;5931.317898441691;5934.940650593504;5581.860453488675;5825.446582574648;5827.796027139051;5842.32813226478;5882.911756323659;5640.464155905182;5823.4467725566;5852.531797456215;5885.7939972100985;5889.558283128783;5067.49325067494;5074.517920797555;5101.974490127563;5102.872428189288;5106.872328191787;23279.650805237936;26283.868580656974;26588.803503842373;26652.20043398701;26725.465490690076;1857;1981.2075169932143;2165.4694599822988;2428.7449817769057;2933.1054028521294;9340.859887101698;9515.429074255497;9599.856002159973;10125.493725313705;10316.742081447948;1813.7279408088891;1846.6306738652188;1850.2136591948367;1906.266087556287;1918.5011896906906;93035;94311;115358;128565;136974.94537582045;9853.211743060538;10765.56937722498;11082.556697732;11461.426928653535;11562.94218528902;14145.717085658229;14271.357788899524;14271.643208919653;14282.785758213635;14310.212938288287;8422.28410585095;8484.066752657203;8486.363522735819;8598.828023439497;8608.526531040852;8451.830963380698;8455.534945578052;8503.532305723245;8578;8596.613152408157;3683.889483315505;3700.5929347771726;4393;4474.239379305526;4501.3248012797685;1602.9020121217004;1897.9430617081234;1918.529960159742;1927.7975812539482;1968.7932767059253;702.8840241360118;769.9307062364414;805.4559936280297;950.2872845365976;1033.9793204135874;17806.55483612894;20721.896390518254;21779.782202178092;21789.564208715736;21815.45461363447;945.7730144765344;955.5508910811907;967.3373738988815;998.8601595776597;1011.2870426349406;10608.840867386996;10799.028087472165;11022.889771102347;11515.942420287845;11529.250598711056;591233;599441;599862;616277;621754;102225.73291205103;123304;127052;129513;133467.33266333607;960.4621412009298;993.9204863610894;1027.4246422003703;1036.6993571864075;1038.2887721910365;19425.708614370513;19718.112684928925;19778.30775922836;19786.604267914274;19808.81147131177;52478.47521524889;52748;56032;56452.717736411054;59159;83048.33903322021;83057;83629.65481380676;84096.31807363941;84227.26140044854;5360.195970604441;5521.5306698931045;7938.04743430792;8035.834803953511;8189.426740128134;9219.49292788903;12306;12958.481660733465;13020.414081366172;13057.738845222852;10773.89226107729;10806.675799726021;11107.889211078827;11263.436828158396;11543.134264929995;8859.955700221457;9568.61725530971;11268.15488838324;11460.484278207667;11477.483513241757;9428.340016198801;10091.596336146471;11172.262795615126;11683.00694440982;11771.470283837076;7973.242541958504;8037.437379383387;8098.51408915467;8116.350691944748;8272.255497005299;8188.754337369888;10167.593296268067;11273.379964101725;11379.08967282632;11491.195616306779 13:33:22 INFO - PROCESS | 1650 | |3;mootools.html;1563.9374425023;1607.1160861526162;1782.483079906827;1887.9056047197653;1898.6297671953976;8512.957435212824;8842.778930526723;8978.50618215998;8987.73036808896;8990.157017811342;7815.960920195405;8054.516728996265;8435.367347448935;8680.47917124973;8782.692605758799;2365.325882123595;2395.928122156329;2396.8322217444784;2397.2927986244013;2401.135591187171;2358.917437889674;2362.208660098869;2396.125414223813;2397.1130681647815;2398.520295940819;2092.1840482211874;2298.2645553422913;2359.6696462495265;2362.0905951208824;2370.0993622423457;2358.905643774247;2379.0840526397287;2381.535600557892;2384.1059602649007;2389.450426401929;2337.1819863047967;2362.326736879998;2369.324742448394;2373.9525209495714;2374.7625237476286;8915.73252802417;8981.6856410026;8994.820103597956;8995.5052472114;9014.098590140933;5218.321618189649;5302.416734159239;5357.81247656334;5375.784968601251;5388.084025715599;3511.94732079019;3591.3535563598543;3684.9263014739554;3693.7045036397026;3717.6839968602703;1091.8362245663213;1104.3539529375325;1108.4568561404935;1121.747606788466;1127.7969965406223;11833.822492662617;11835.585754498552;11839.822402663967;11843.28940263587;11846.526138954432;4034.7982600870428;4589.862304130881;4810.5670489655395;4818.807247710052;4820.084184005098;9992.500374981222;9998.450085245382;10000;10033.498325083716;10039.799204015879;5372.274742909657;5373.194020896896;5378.085725426687;5378.78484860609;5386.138217885198;4404.603585677304;4537.228671125917;4746.667733258708;4927.827526036572;4930.926036109461;3390.796267325064;3927.7446965947124;3949.9065495287546;4051.4125451809323;4074.694397920168;1066.2056767708107;1261.5521489870964;1262.103906226571;1273.1597145883673;1274.7769140400392;571.2689299063575;786.3001928283832;918.2332752152028;949.852772820214;968.864358989742;5875.294964604275;6034.63792172471;6090.964536028886;6161.568690191733;6163;944.3106532231373;956.3449037409397;983.4296108257337;1015.5988384588046;1114.2144787924494;3953.486046813896;4065.2276067547073;4083.1629515949157;4144.772037537904;4150.833966641361;10714.51784669676;10940.179486538542;11001.339919604849;11018.173636977099;11027.952344527257;10672.519736611872;10867.913208679069;10869.945650271698;10890.237683362247;10898.237123401444;8323.958380208182;8465;8465.703700370457;8471.915280847235;8478.915210847812;5824;5963.7912673056235;6095.939040609537;6113.908291375722;6217.782377616762;9727.221822254061;10012.599496020077;10248.231382646181;10255.07704306616;10309;10185.185185185315;10356.948215259177;10427.265004247785;10437.582496700046;10462.58149674035;6180.598261113013;7348.375388092072;7435.925640743523;7437.8140546486775;7439.367653749492;8284.627191776264;8323.292520135856;8396.664133434595;8398.790030249293;8429.704960326359;5731.5701322400155;7399.187555731567;7476.990606268084;7676.232376762279;7747.18654541265;7131.928680713127;7439.70241190368;7540.095188577406;7572.5835079068975;7662.272084151997;6463.450606698295;7105.715771369294;7187.640617969185;7191.316824901626;7200.71197152108;4965.255211718127;5116.437191908813;5175.301334319969;5178.249153872743;5201.661891977009;5129.333186685707;5148.459411761861;5150.377631046208;5203.24552939814;5229.790808367775 13:33:22 INFO - PROCESS | 1650 | |4;prototype.html;256.5049454552713;303.2252594620743;313.2013365916912;318.9029737702305;319.1844836442888;5344.759485823138;5372.3553173619175;5372.677639341642;5382.6232163748455;5411.918821217685;5003.649744517885;5119.539241468267;5205.505476979691;5207.609429292808;5247.47525247476;3253.4469140246097;3498.7201023918024;4083.469149010632;4098.770368889336;4139.4204811326435;3554.608993010767;3910.687145028405;4075.531313898895;4100.200460910124;4122;3205.8397080146015;3738.252349530091;3810.1139935731712;4073.816678249471;4104.240715467633;3492.353914525821;3993.880183594497;4027.1744292419985;4077.3068578341604;4088.6115818997446;3563.7149028077692;3563.928721425583;3565.750397472178;3577.499150118985;3580.856765729368;4283.2932566126865;4871.805127794884;4929.8028078876805;5255.448177941299;5273.288106105703;3811.294910441577;3949.743266687656;4496.763390067725;4552.430946131733;4638.884027899329;5162.716050617215;5412.621116521845;5624.606277560572;5651.378348381675;5669.716514174316;3195.9201019974453;3839.9248210501105;3845.788481633509;3846.288436639231;3851.9422208666892;4267.8079486423185;5018.7453136716185;5550.806576586054;5558.638688485235;5936.970315148396;3817.2365526894732;3875.8837234883;3876.6898648108086;3890.4164375343926;3900.356441187229;3329.667033296651;3636.7999760012926;3946.5856085111222;3967.761934283952;3971.3447281198855;4124.670026397881;4353.628606988788;4612.215923293048;4666.390095417076;4686.015936653273;5589.776408943678;5606.299212598426;5611.643109893855;5632.943670563324;5658.971705141448;1133.0935251798528;1318.3803612302202;1338.4311667541256;1345.239939434236;1347.3936728472227;746.6042997211487;848.8031875056141;869.9043105258543;873.3799002708148;877.5085951866974;4147.937780933288;4255.5957184067465;4777.378940737752;4968.602511799119;5306.442823503554;5710.885782284331;5840.795572154954;5852.180694702745;5860.882782344329;5868.149118377812;5063.215201643751;5298.6290959633225;5321.760520776497;5330.641695731664;5330.706811125426;3018.320877802521;3023.727864492206;3024.9848750756105;3062.4640687879532;3079.630444346693;5017;5138.743062846843;5889.4699477046415;5937.910931336033;6002.698515816283;5474.315710536183;5603.411641777636;5901.380355062656;5943.821685349447;5955.166276721263;4932.506749325111;5888.735006924613;5923.763049477972;5937.554683398676;5963.582549221513;5546.694931778791;5785.971070144622;5790.131480277992;5799.565032622486;5804.2587223833525;4920.6555541111775;5158.922616160836;5648.4633959773755;5656;5691.886162276648;5295.841124766263;5813.761701744774;5877.647341159544;5900.262467191601;5927.585069045125;3172.7620428468417;3686.7787932724123;3807.162424266657;3822.5978878798446;3845.730798844054;5279.208118782213;5744.224529688606;5811.0121279381765;5813.517553023952;5829.329627092939;4933.506649335038;5400.648957817729;5458.836234912959;5574.77700891976;5663.66018038919;4961.42943561495;5585.804496842591;5592.245046918777;5626.212330273683;5643.802466913638;5192.805654699516;5460.099083651153;5477.863053423537;5535.789242239411;5548.361938377138;4894.779734911866;5479.506844384025;5576.470235327637;5594.4964953152785;5598.468145526169;5486.31421072366;5501.394846566955;5510.3938566758425;5520.8406234691165;5568.526675232651 13:33:22 INFO - PROCESS | 1650 | |5;yui.html;631.9936800631993;672.9966350168248;718.6730037832787;751.0761763031471;819.0130892274808;15147.16690582019;16440.58898527536;16449.342026318933;17470.475885723416;18396.26414943404;16965.57586060349;17022.404215852446;17158.82841171588;17172.227249773758;17358;716.3624374306871;738.9445791565654;894.6466145872377;917.8760867282898;920.1764420843341;889.057598945117;917.4449458077867;923.6028507741669;924.0713083351222;924.8890133184027;954.2604481526803;966.4974213409023;968.6658102954505;968.9951550242274;970.6117552978794;761.756238003839;934.7382732834817;959.040959040959;966.2415004221723;967.0764419978904;702.9086218791575;703.3704834173437;703.8275622472476;705.3123204875257;708.252793303065;15544.067355958565;15588;15589.610259743484;15621.297041633154;15662.9216853915;12077.79222077794;13905.652358691012;13990.090644108202;14016.159030458102;14177.929110354486;13220.338983050906;13281.933590332083;13315.467381304736;13558.525451609146;13748.587542373745;4584.88537786558;4614.930776038362;4635.2583586626315;4822.493638167977;4828.155072862235;109598.45200774024;117731;119751.40124299323;120242;120620.39689801582;9510.334276600572;11017.779644407066;11074.5570177192;11145.665630031112;11254.662360129209;21748;21801.0189541471;21806.672899906513;21821.23625673094;21823.563528729337;7892.329152022027;7976.880346794803;7999.920000799918;8067.805060451203;8178;4508.526604706459;4515.164694531522;4530.275155975029;4563.771811409416;4695.014047050089;3631.3100510902846;3766.9058273542832;3781.7352785304765;3783.7162212834146;3785.1861849702445;664.4451882677921;672.5359501943643;675.790504943469;690.0442886602092;698.9965050174717;614.8585825260216;626.79629120536;629.2826178156813;655.6492276631978;675.1830285354678;8827.470351778913;9295.953520232491;9300;9397.812043759086;9549.61801527945;589.4459208344143;626.6897885546683;661.7683810666229;679.17819438479;709.0427922304847;4593.471750748707;4626.884327891762;4634.165850146938;4667.346571479996;4684.8500568646405;89428.5528572366;108777.91222087837;135845;137008;147118;77664.61167694125;118320.40839795746;127452.36273818572;127504.72495275114;128826;71266.287337127;97813;109925.45037274923;110001;112895;8844.336674749547;8853.424527405698;8861.556922153995;8957.28341732673;9022.68420605302;62587;62669.37330626818;62907.709229076725;63160;64187.03719444305;88746.66879996934;92913;94621.5268923651;95452;96158.03841961491;11885.22746021506;11887.940560297144;12039;12045.397730113635;12326.876731232931;10888.29226100301;13229.40467678976;13695.775715097428;13953.372098255806;13988.58034258974;9428.528573571157;12550.811737823757;12658.746825063632;13528.660300430574;14206.928965355108;13102.475900963855;13856.376463059389;13975.09161904473;13977.371018304404;14275.500357487437;13508.729825403634;13594.93202533981;13607.591772246848;13621.795673064713;13645.385957631732;11285.492152852978;11512.424378781196;11702;11832.34922079294;11847.407629618312;11273.605423810128;11402.20184587071;11529;11625.12811539121;11783.116266280234 13:33:22 INFO - PROCESS | 1650 | __end_tp_report 13:33:22 INFO - PROCESS | 1650 | __start_cc_report 13:33:22 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1294 13:33:22 INFO - PROCESS | 1650 | __end_cc_report 13:33:22 INFO - PROCESS | 1650 | __startTimestamp1468614802422__endTimestamp 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | ------- Summary: start ------- 13:33:22 INFO - PROCESS | 1650 | Number of tests: 6 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5716.81 Median:5866.33 stddev:654.22 (11.2%) stddev-sans-first:614.26 13:33:22 INFO - PROCESS | 1650 | Values: 2777.7 3338.4 3353.9 3365.3 3394.0 4627.4 5562.9 5692.5 5743.3 5766.9 5677.4 5722.9 5734.5 5739.0 5747.8 5181.7 5220.8 5263.8 5264.1 5326.0 4242.4 5259.6 5278.5 5305.0 5305.2 5252.5 5261.2 5275.4 5303.0 5307.2 5010.4 5304.1 5332.7 5333.7 5353.6 5135.1 5139.8 5145.2 5261.5 5272.7 5609.3 5619.7 5673.2 5678.5 5705.5 6262.6 6280.2 6286.9 6289.1 6302.5 6127.5 6276.4 6290.6 6307.3 6336.2 5834.3 5849.2 5858.1 5898.2 5917.2 6234.4 6243.2 6277.4 6287.7 6295.9 5160.0 6001.4 6061.9 6068.9 6135.5 6263.7 6269.3 6282.7 6284.9 6289.1 6219.3 6265.7 6267.6 6276.2 6276.7 6142.8 6176.0 6189.0 6223.8 6283.2 4945.7 4970.1 4976.0 4989.1 5002.6 4447.6 4448.2 4456.9 4468.8 4487.0 5652.8 5774.8 5795.8 5855.4 5857.2 6182.1 6227.5 6242.9 6245.9 6263.2 5725.8 5768.0 5771.5 5779.3 5807.2 5335.5 5467.7 5474.5 5476.0 5485.9 5279.6 6165.0 6178.0 6189.8 6277.8 5271.5 6248.7 6262.0 6262.0 6263.9 6243.6 6293.7 6304.6 6309.7 6316.7 5982.1 6119.0 6151.6 6185.5 6241.8 6225.7 6262.7 6289.4 6290.3 6298.9 6182.3 6195.5 6200.3 6202.8 6210.3 5854.9 5869.6 5904.8 5915.3 5952.2 5744.7 5756.5 5763.9 5774.7 5816.5 5866.3 5907.9 5918.4 5947.0 6003.1 5879.7 5885.3 5885.9 5911.3 5972.2 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:75288.53 Median:21527.41 stddev:171115.29 (794.9%) stddev-sans-first:171503.18 13:33:22 INFO - PROCESS | 1650 | Values: 501.3 530.8 536.6 542.1 566.8 30544.0 32170.7 42061.4 43108.8 43519.0 44619.9 44805.6 45029.0 45032.8 45155.5 9718.4 9899.0 9901.9 9902.0 9917.2 8845.3 9042.8 11999.1 12179.6 12180.2 12182.1 12222.8 12244.4 12250.2 12258.9 8872.6 9021.3 12163.1 12246.8 12278.9 8756.7 8816.7 11694.3 11941.8 11951.3 42425.2 42832.8 42876.4 42908.6 42938.6 3770.7 3881.0 3955.2 4367.5 4428.1 31931.8 31984.4 33170.0 33780.8 33797.2 3326.2 3327.3 3327.5 3385.4 3417.3 462340.0 464203.0 464219.0 466252.0 466561.0 61271.7 62028.4 62029.7 62034.7 62100.7 65265.0 65328.0 65369.3 65499.3 65574.3 16192.2 16884.7 16893.5 16910.2 16915.4 11872.0 12120.0 12627.5 13027.9 13087.3 16665.8 16686.6 16691.4 16705.3 16740.3 7280.3 7283.9 7356.1 7378.4 7392.7 914.9 960.8 990.8 1037.4 1044.4 31270.7 31324.7 31533.0 31792.8 32060.0 1063.8 1070.5 1071.6 1091.8 1216.2 16221.5 16225.2 16400.9 16408.3 16714.7 688507.0 847614.0 866688.0 868594.0 870108.0 61865.7 61928.8 62532.4 62794.7 62817.4 1285.5 1337.3 1418.4 1426.6 1445.9 29201.7 30573.7 32260.7 32414.8 32493.8 56339.2 57414.7 57535.1 57550.0 57659.4 538964.0 560414.0 560449.0 560690.0 563731.0 23588.3 26118.3 26160.0 26207.2 26692.9 48183.3 48459.5 48561.5 49176.0 49776.8 39727.4 39839.6 39858.4 40266.0 41295.8 38061.2 39738.0 40417.6 40734.4 41097.6 38235.4 38504.6 38651.0 38948.6 39099.6 34291.7 35466.8 36404.0 36523.0 36799.6 26231.3 26450.1 26527.7 26596.6 26945.9 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:35074.89 Median:3692.24 stddev:101001.79 (2735.5%) stddev-sans-first:101251.50 13:33:22 INFO - PROCESS | 1650 | Values: 363.4 378.8 389.8 391.1 411.2 15376.5 15704.5 20155.2 20927.0 20966.2 20428.8 20899.2 21246.5 21564.8 21568.8 5450.2 5716.4 5942.4 5942.6 5972.3 5839.0 5864.4 5929.9 5931.3 5934.9 5581.9 5825.4 5827.8 5842.3 5882.9 5640.5 5823.4 5852.5 5885.8 5889.6 5067.5 5074.5 5102.0 5102.9 5106.9 23279.7 26283.9 26588.8 26652.2 26725.5 1857.0 1981.2 2165.5 2428.7 2933.1 9340.9 9515.4 9599.9 10125.5 10316.7 1813.7 1846.6 1850.2 1906.3 1918.5 93035.0 94311.0 115358.0 128565.0 136974.9 9853.2 10765.6 11082.6 11461.4 11562.9 14145.7 14271.4 14271.6 14282.8 14310.2 8422.3 8484.1 8486.4 8598.8 8608.5 8451.8 8455.5 8503.5 8578.0 8596.6 3683.9 3700.6 4393.0 4474.2 4501.3 1602.9 1897.9 1918.5 1927.8 1968.8 702.9 769.9 805.5 950.3 1034.0 17806.6 20721.9 21779.8 21789.6 21815.5 945.8 955.6 967.3 998.9 1011.3 10608.8 10799.0 11022.9 11515.9 11529.3 591233.0 599441.0 599862.0 616277.0 621754.0 102225.7 123304.0 127052.0 129513.0 133467.3 960.5 993.9 1027.4 1036.7 1038.3 19425.7 19718.1 19778.3 19786.6 19808.8 52478.5 52748.0 56032.0 56452.7 59159.0 83048.3 83057.0 83629.7 84096.3 84227.3 5360.2 5521.5 7938.0 8035.8 8189.4 9219.5 12306.0 12958.5 13020.4 13057.7 10773.9 10806.7 11107.9 11263.4 11543.1 8860.0 9568.6 11268.2 11460.5 11477.5 9428.3 10091.6 11172.3 11683.0 11771.5 7973.2 8037.4 8098.5 8116.4 8272.3 8188.8 10167.6 11273.4 11379.1 11491.2 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5771.46 Median:4819.45 stddev:3241.04 (67.2%) stddev-sans-first:3234.71 13:33:22 INFO - PROCESS | 1650 | Values: 1563.9 1607.1 1782.5 1887.9 1898.6 8513.0 8842.8 8978.5 8987.7 8990.2 7816.0 8054.5 8435.4 8680.5 8782.7 2365.3 2395.9 2396.8 2397.3 2401.1 2358.9 2362.2 2396.1 2397.1 2398.5 2092.2 2298.3 2359.7 2362.1 2370.1 2358.9 2379.1 2381.5 2384.1 2389.5 2337.2 2362.3 2369.3 2374.0 2374.8 8915.7 8981.7 8994.8 8995.5 9014.1 5218.3 5302.4 5357.8 5375.8 5388.1 3511.9 3591.4 3684.9 3693.7 3717.7 1091.8 1104.4 1108.5 1121.7 1127.8 11833.8 11835.6 11839.8 11843.3 11846.5 4034.8 4589.9 4810.6 4818.8 4820.1 9992.5 9998.5 10000.0 10033.5 10039.8 5372.3 5373.2 5378.1 5378.8 5386.1 4404.6 4537.2 4746.7 4927.8 4930.9 3390.8 3927.7 3949.9 4051.4 4074.7 1066.2 1261.6 1262.1 1273.2 1274.8 571.3 786.3 918.2 949.9 968.9 5875.3 6034.6 6091.0 6161.6 6163.0 944.3 956.3 983.4 1015.6 1114.2 3953.5 4065.2 4083.2 4144.8 4150.8 10714.5 10940.2 11001.3 11018.2 11028.0 10672.5 10867.9 10869.9 10890.2 10898.2 8324.0 8465.0 8465.7 8471.9 8478.9 5824.0 5963.8 6095.9 6113.9 6217.8 9727.2 10012.6 10248.2 10255.1 10309.0 10185.2 10356.9 10427.3 10437.6 10462.6 6180.6 7348.4 7435.9 7437.8 7439.4 8284.6 8323.3 8396.7 8398.8 8429.7 5731.6 7399.2 7477.0 7676.2 7747.2 7131.9 7439.7 7540.1 7572.6 7662.3 6463.5 7105.7 7187.6 7191.3 7200.7 4965.3 5116.4 5175.3 5178.2 5201.7 5129.3 5148.5 5150.4 5203.2 5229.8 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4527.22 Median:5177.76 stddev:1410.98 (27.3%) stddev-sans-first:1378.05 13:33:22 INFO - PROCESS | 1650 | Values: 256.5 303.2 313.2 318.9 319.2 5344.8 5372.4 5372.7 5382.6 5411.9 5003.6 5119.5 5205.5 5207.6 5247.5 3253.4 3498.7 4083.5 4098.8 4139.4 3554.6 3910.7 4075.5 4100.2 4122.0 3205.8 3738.3 3810.1 4073.8 4104.2 3492.4 3993.9 4027.2 4077.3 4088.6 3563.7 3563.9 3565.8 3577.5 3580.9 4283.3 4871.8 4929.8 5255.4 5273.3 3811.3 3949.7 4496.8 4552.4 4638.9 5162.7 5412.6 5624.6 5651.4 5669.7 3195.9 3839.9 3845.8 3846.3 3851.9 4267.8 5018.7 5550.8 5558.6 5937.0 3817.2 3875.9 3876.7 3890.4 3900.4 3329.7 3636.8 3946.6 3967.8 3971.3 4124.7 4353.6 4612.2 4666.4 4686.0 5589.8 5606.3 5611.6 5632.9 5659.0 1133.1 1318.4 1338.4 1345.2 1347.4 746.6 848.8 869.9 873.4 877.5 4147.9 4255.6 4777.4 4968.6 5306.4 5710.9 5840.8 5852.2 5860.9 5868.1 5063.2 5298.6 5321.8 5330.6 5330.7 3018.3 3023.7 3025.0 3062.5 3079.6 5017.0 5138.7 5889.5 5937.9 6002.7 5474.3 5603.4 5901.4 5943.8 5955.2 4932.5 5888.7 5923.8 5937.6 5963.6 5546.7 5786.0 5790.1 5799.6 5804.3 4920.7 5158.9 5648.5 5656.0 5691.9 5295.8 5813.8 5877.6 5900.3 5927.6 3172.8 3686.8 3807.2 3822.6 3845.7 5279.2 5744.2 5811.0 5813.5 5829.3 4933.5 5400.6 5458.8 5574.8 5663.7 4961.4 5585.8 5592.2 5626.2 5643.8 5192.8 5460.1 5477.9 5535.8 5548.4 4894.8 5479.5 5576.5 5594.5 5598.5 5486.3 5501.4 5510.4 5520.8 5568.5 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:23959.99 Median:4522.72 stddev:37003.34 (818.2%) stddev-sans-first:37065.68 13:33:22 INFO - PROCESS | 1650 | Values: 632.0 673.0 718.7 751.1 819.0 15147.2 16440.6 16449.3 17470.5 18396.3 16965.6 17022.4 17158.8 17172.2 17358.0 716.4 738.9 894.6 917.9 920.2 889.1 917.4 923.6 924.1 924.9 954.3 966.5 968.7 969.0 970.6 761.8 934.7 959.0 966.2 967.1 702.9 703.4 703.8 705.3 708.3 15544.1 15588.0 15589.6 15621.3 15662.9 12077.8 13905.7 13990.1 14016.2 14177.9 13220.3 13281.9 13315.5 13558.5 13748.6 4584.9 4614.9 4635.3 4822.5 4828.2 109598.5 117731.0 119751.4 120242.0 120620.4 9510.3 11017.8 11074.6 11145.7 11254.7 21748.0 21801.0 21806.7 21821.2 21823.6 7892.3 7976.9 7999.9 8067.8 8178.0 4508.5 4515.2 4530.3 4563.8 4695.0 3631.3 3766.9 3781.7 3783.7 3785.2 664.4 672.5 675.8 690.0 699.0 614.9 626.8 629.3 655.6 675.2 8827.5 9296.0 9300.0 9397.8 9549.6 589.4 626.7 661.8 679.2 709.0 4593.5 4626.9 4634.2 4667.3 4684.9 89428.6 108777.9 135845.0 137008.0 147118.0 77664.6 118320.4 127452.4 127504.7 128826.0 71266.3 97813.0 109925.5 110001.0 112895.0 8844.3 8853.4 8861.6 8957.3 9022.7 62587.0 62669.4 62907.7 63160.0 64187.0 88746.7 92913.0 94621.5 95452.0 96158.0 11885.2 11887.9 12039.0 12045.4 12326.9 10888.3 13229.4 13695.8 13953.4 13988.6 9428.5 12550.8 12658.7 13528.7 14206.9 13102.5 13856.4 13975.1 13977.4 14275.5 13508.7 13594.9 13607.6 13621.8 13645.4 11285.5 11512.4 11702.0 11832.3 11847.4 11273.6 11402.2 11529.0 11625.1 11783.1 13:33:22 INFO - PROCESS | 1650 | -------- Summary: end -------- 13:33:22 INFO - PROCESS | 1650 | 13:33:22 INFO - TEST-INFO | 1650: exit 0 13:33:22 INFO - TEST-OK | dromaeo_css | took 1116513ms 13:33:22 INFO - TEST-START | kraken 13:33:22 INFO - operating with platform_type : mac_ 13:33:22 INFO - Initialising browser for kraken test... 13:33:23 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpCznXGB/profile http://localhost:49270/getInfo.html) 13:33:25 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 13:33:25 INFO - PROCESS | 1657 | colorDepth:24 13:33:25 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 13:33:25 INFO - PROCESS | 1657 | __metrics 13:33:25 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:33:25 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:33:26 INFO - TEST-INFO | 1657: exit 0 13:33:26 INFO - Browser initialized. 13:33:26 INFO - Running cycle 1/1 for kraken test... 13:33:26 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpCznXGB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:33:37 INFO - PROCESS | 1658 | RSS: Main: 243662848 13:33:37 INFO - PROCESS | 1658 | 13:33:40 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 13:33:41 INFO - PROCESS | 1658 | RSS: Main: 294318080 13:33:41 INFO - PROCESS | 1658 | 13:33:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 13:33:44 INFO - PROCESS | 1658 | RSS: Main: 305303552 13:33:44 INFO - PROCESS | 1658 | 13:33:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 13:33:48 INFO - PROCESS | 1658 | RSS: Main: 390213632 13:33:48 INFO - PROCESS | 1658 | 13:33:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 13:33:51 INFO - PROCESS | 1658 | RSS: Main: 292077568 13:33:51 INFO - PROCESS | 1658 | 13:33:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:33:54 INFO - PROCESS | 1658 | RSS: Main: 296013824 13:33:54 INFO - PROCESS | 1658 | 13:33:56 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 13:33:57 INFO - PROCESS | 1658 | RSS: Main: 331206656 13:33:57 INFO - PROCESS | 1658 | 13:34:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 13:34:00 INFO - PROCESS | 1658 | RSS: Main: 334675968 13:34:00 INFO - PROCESS | 1658 | 13:34:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 13:34:04 INFO - PROCESS | 1658 | RSS: Main: 331939840 13:34:04 INFO - PROCESS | 1658 | 13:34:06 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:34:06 INFO - PROCESS | 1658 | RSS: Main: 311611392 13:34:06 INFO - PROCESS | 1658 | 13:34:08 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:34:09 INFO - PROCESS | 1658 | RSS: Main: 318857216 13:34:09 INFO - PROCESS | 1658 | 13:34:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:34:12 INFO - PROCESS | 1658 | RSS: Main: 316391424 13:34:12 INFO - PROCESS | 1658 | 13:34:15 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:34:15 INFO - PROCESS | 1658 | RSS: Main: 326090752 13:34:15 INFO - PROCESS | 1658 | 13:34:18 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:34:19 INFO - PROCESS | 1658 | RSS: Main: 328613888 13:34:19 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 13:34:21 INFO - PROCESS | 1658 | RSS: Main: 372428800 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | __start_tp_report 13:34:21 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 13:34:21 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 13:34:21 INFO - PROCESS | 1658 | |i|pagename|runs| 13:34:21 INFO - PROCESS | 1658 | |0;ai-astar;101;114;114;113;114;114;113;113;114;114 13:34:21 INFO - PROCESS | 1658 | |1;audio-beat-detection;142;113;106;159;105;103;128;103;141;103 13:34:21 INFO - PROCESS | 1658 | |2;audio-dft;182;184;182;183;180;183;181;182;253;184 13:34:21 INFO - PROCESS | 1658 | |3;audio-fft;78;73;58;87;57;57;62;70;85;57 13:34:21 INFO - PROCESS | 1658 | |4;audio-oscillator;84;92;92;71;70;67;97;70;67;102 13:34:21 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;88;86;89;86;88;85;90;86;87;85 13:34:21 INFO - PROCESS | 1658 | |6;imaging-darkroom;94;94;94;94;96;94;95;94;95;93 13:34:21 INFO - PROCESS | 1658 | |7;imaging-desaturate;86;85;84;84;92;132;130;83;85;84 13:34:21 INFO - PROCESS | 1658 | |8;json-parse-financial;58;56;56;56;57;55;55;53;75;83 13:34:21 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;68;73;84;78;106;71;73;71;73;71 13:34:21 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;86;83;85;81;79;80;83;83;83;86 13:34:21 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;136;153;152;151;142;133;131;137;236;134 13:34:21 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;165;219;199;161;219;174;164;219;206;162 13:34:21 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;67;70;68;67;66;66;66;66;66 13:34:21 INFO - PROCESS | 1658 | __end_tp_report 13:34:21 INFO - PROCESS | 1658 | __start_cc_report 13:34:21 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1988 13:34:21 INFO - PROCESS | 1658 | __end_cc_report 13:34:21 INFO - PROCESS | 1658 | __startTimestamp1468614861362__endTimestamp 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | ------- Summary: start ------- 13:34:21 INFO - PROCESS | 1658 | Number of tests: 14 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:112.40 Median:114.00 stddev:4.03 (3.5%) stddev-sans-first:0.50 13:34:21 INFO - PROCESS | 1658 | Values: 101.0 114.0 114.0 113.0 114.0 114.0 113.0 113.0 114.0 114.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:120.50 stddev:20.67 (17.2%) stddev-sans-first:20.38 13:34:21 INFO - PROCESS | 1658 | Values: 142.0 113.0 106.0 159.0 105.0 103.0 128.0 103.0 141.0 103.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:189.40 Median:183.00 stddev:22.38 (12.2%) stddev-sans-first:23.58 13:34:21 INFO - PROCESS | 1658 | Values: 182.0 184.0 182.0 183.0 180.0 183.0 181.0 182.0 253.0 184.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:68.40 Median:71.50 stddev:11.91 (16.7%) stddev-sans-first:12.11 13:34:21 INFO - PROCESS | 1658 | Values: 78.0 73.0 58.0 87.0 57.0 57.0 62.0 70.0 85.0 57.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:77.50 stddev:13.67 (17.6%) stddev-sans-first:14.46 13:34:21 INFO - PROCESS | 1658 | Values: 84.0 92.0 92.0 71.0 70.0 67.0 97.0 70.0 67.0 102.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.50 stddev:1.70 (1.9%) stddev-sans-first:1.76 13:34:21 INFO - PROCESS | 1658 | Values: 88.0 86.0 89.0 86.0 88.0 85.0 90.0 86.0 87.0 85.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 13:34:21 INFO - PROCESS | 1658 | Values: 94.0 94.0 94.0 94.0 96.0 94.0 95.0 94.0 95.0 93.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:84.50 stddev:19.40 (23.0%) stddev-sans-first:20.34 13:34:21 INFO - PROCESS | 1658 | Values: 86.0 85.0 84.0 84.0 92.0 132.0 130.0 83.0 85.0 84.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:56.50 stddev:10.07 (17.8%) stddev-sans-first:10.64 13:34:21 INFO - PROCESS | 1658 | Values: 58.0 56.0 56.0 56.0 57.0 55.0 55.0 53.0 75.0 83.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:76.80 Median:73.00 stddev:11.19 (15.3%) stddev-sans-first:11.41 13:34:21 INFO - PROCESS | 1658 | Values: 68.0 73.0 84.0 78.0 106.0 71.0 73.0 71.0 73.0 71.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.00 stddev:2.38 (2.9%) stddev-sans-first:2.24 13:34:21 INFO - PROCESS | 1658 | Values: 86.0 83.0 85.0 81.0 79.0 80.0 83.0 83.0 83.0 86.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:146.50 stddev:31.17 (21.3%) stddev-sans-first:32.61 13:34:21 INFO - PROCESS | 1658 | Values: 136.0 153.0 152.0 151.0 142.0 133.0 131.0 137.0 236.0 134.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.80 Median:202.50 stddev:25.88 (12.8%) stddev-sans-first:25.98 13:34:21 INFO - PROCESS | 1658 | Values: 165.0 219.0 199.0 161.0 219.0 174.0 164.0 219.0 206.0 162.0 13:34:21 INFO - PROCESS | 1658 | 13:34:21 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.29 (1.9%) stddev-sans-first:1.36 13:34:21 INFO - PROCESS | 1658 | Values: 67.0 67.0 70.0 68.0 67.0 66.0 66.0 66.0 66.0 66.0 13:34:21 INFO - PROCESS | 1658 | -------- Summary: end -------- 13:34:21 INFO - PROCESS | 1658 | 13:34:22 INFO - TEST-INFO | 1658: exit 0 13:34:22 INFO - TEST-OK | kraken | took 59201ms 13:34:22 INFO - SUITE-END | took 1175s 13:34:22 INFO - Completed test suite (00:19:36) 13:34:22 INFO - javascript benchmark 13:34:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2777.7222277772225, 3338.4491558892782, 3353.932921341573, 3365.3101114271576, 3394.0157354367234, 4627.375304333914, 5562.915231529853, 5692.544596432286, 5743.282089738783, 5766.855828604287, 5677.375488696251, 5722.914156287648, 5734.5125664318475, 5738.971305143468, 5747.798827041054, 5181.715005674687, 5220.765065572039, 5263.789448422078, 5264.105102132647, 5325.973370133144, 4242.427272318244, 5259.6055295852775, 5278.498542638463, 5305.0450918834595, 5305.177697456881, 5252.52727254547, 5261.210818377255, 5275.393329767068, 5302.973485132588, 5307.150855863063, 5010.448850626428, 5304.124819404813, 5332.653377530487, 5333.653312534673, 5353.625246232766, 5135.075686376451, 5139.820106296262, 5145.176771716508, 5261.473852614746, 5272.710000949946, 5609.298837645294, 5619.719014049322, 5673.23411339468, 5678.488935995739, 5705.486506214461, 6262.561620686553, 6280.214973128359, 6286.874262514724, 6289.056641503811, 6302.46429053531, 6127.509799216052, 6276.372362763733, 6290.591111577733, 6307.274663413675, 6336.2396512418345, 5834.299884013861, 5849.210356601804, 5858.06270996647, 5898.174255604219, 5917.230760001173, 6234.407731265523, 6243.157173781481, 6277.403646653561, 6287.717052732638, 6295.874082518324, 5159.974200128975, 6001.369856165127, 6061.878762424727, 6068.918905487468, 6135.477655820289, 6263.655498947511, 6269.341719119518, 6282.685865706788, 6284.905726414107, 6289.056641503719, 6219.315875253809, 6265.71804268809, 6267.561270711098, 6276.246850377984, 6276.717547710364, 6142.815715528542, 6175.96912015437, 6188.9690551546955, 6223.751049958041, 6283.246010478681, 4945.703257804544, 4970.080535101018, 4975.975120124376, 4989.101961646938, 5002.624803139779, 4447.62195213404, 4448.154850578379, 4456.910861782746, 4468.776561171929, 4487.035287413157, 5652.830415087554, 5774.82675519735, 5795.768169273266, 5855.355910849803, 5857.20927674767, 6182.1345011698395, 6227.470664993436, 6242.875142497124, 6245.906311405332, 6263.217097862767, 5725.770969161187, 5768.0, 5771.451712087345, 5779.33537643168, 5807.186993820868, 5335.546478549367, 5467.6719396836315, 5474.479924407175, 5475.972620136953, 5485.917711234335, 5279.604029697711, 6164.969175154096, 6177.9691101546005, 6189.8452538686, 6277.843053923689, 5271.472852714698, 6248.656323902229, 6262.0, 6262.0, 6263.937360626518, 6243.575929410846, 6293.716782744696, 6304.621722696653, 6309.747610095728, 6316.7473301069285, 5982.102684597346, 6118.984682626333, 6151.600145990676, 6185.474234690102, 6241.750329986931, 6225.657588832658, 6262.655553944577, 6289.371062893674, 6290.276618188784, 6298.937010630018, 6182.28903676083, 6195.473384762346, 6200.286966998853, 6202.844928876814, 6210.254769427697, 5854.853628659318, 5869.589128760946, 5904.793332233351, 5915.349311575637, 5952.226210592596, 5744.684042377542, 5756.539476841759, 5763.913541296968, 5774.682392468455, 5816.534677225727, 5866.325372582208, 5907.911381329198, 5918.437748413894, 5947.01874190771, 6003.129546215687, 5879.7060146993335, 5885.293764748258, 5885.941140588539, 5911.320198177093, 5972.223610930551], "value": 5674.564869639603, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.2631431795262, 530.8221745715185, 536.5948708724912, 542.1325878594248, 566.8299510146954, 30543.99999999997, 32170.67829321706, 42061.36907946383, 43108.78445607775, 43519.0, 44619.88450288744, 44805.55194448055, 45029.0, 45032.774836125776, 45155.548444515545, 9718.36830606012, 9899.0, 9901.851472227887, 9902.0, 9917.157041651468, 8845.336599755012, 9042.819143617124, 11999.10006749493, 12179.57371491998, 12180.208286461351, 12182.147249692525, 12222.816657750142, 12244.448999795031, 12250.203736757127, 12258.93870530646, 8872.600732966968, 9021.278297736166, 12163.08776841736, 12246.81629775554, 12278.877211227908, 8756.693515726982, 8816.735497935073, 11694.2983420995, 11941.761164776743, 11951.282923024646, 42425.15149696989, 42832.78583607093, 42876.35685464721, 42908.57091429077, 42938.57061429377, 3770.6606405423645, 3881.029742564359, 3955.208958208341, 4367.538084076114, 4428.136513379892, 31931.840340798146, 31984.360312793615, 33170.00489985304, 33780.83109584461, 33797.155071123176, 3326.234965957819, 3327.2680010397676, 3327.45097058985, 3385.4075536780965, 3417.2994536120036, 462340.0, 464203.0, 464219.0, 466252.0, 466560.9999999966, 61271.69364153151, 62028.37971620316, 62029.68985155045, 62034.68982655058, 62100.68949655223, 65265.0, 65328.0, 65369.34630653728, 65499.34500654932, 65574.34425655777, 16192.19039048043, 16884.746728799077, 16893.493195204162, 16910.239039243264, 16915.40796072156, 11871.990880775058, 12120.0, 12627.494900204072, 13027.869721302855, 13087.345632718327, 16665.833341666428, 16686.58283542897, 16691.415800446925, 16705.331786728395, 16740.330386784637, 7280.271972802783, 7283.927160728431, 7356.117265928123, 7378.446616503784, 7392.741254056083, 914.8993610702818, 960.8366577681811, 990.821652102614, 1037.3775734559204, 1044.3525014491065, 31270.687293127234, 31324.686753132177, 31533.0, 31792.841035795136, 32060.038198854072, 1063.7659714862718, 1070.4754670211696, 1071.5553045486192, 1091.82530795074, 1216.2094638484878, 16221.513354599381, 16225.188740562924, 16400.933939293907, 16408.26162822676, 16714.749278760828, 688507.0, 847614.0, 866688.0, 868594.0, 870108.0, 61865.690671546356, 61928.761424771255, 62532.37467625266, 62794.68602656957, 62817.37182628115, 1285.54363201066, 1337.2979185927466, 1418.3971811979868, 1426.5934208750457, 1445.9349329280208, 29201.657705648162, 30573.694263057976, 32260.677393225767, 32414.83792581022, 32493.837530812194, 56339.154912677164, 57414.71292643677, 57535.136972946275, 57550.0, 57659.4234057654, 538964.0, 560414.0, 560449.0, 560690.0, 563731.0, 23588.29235122949, 26118.34704132412, 26160.0, 26207.21378358652, 26692.8665356672, 48183.27725084056, 48459.51540484691, 48561.5143848557, 49176.0, 49776.75111624419, 39727.404088938114, 39839.601603984745, 39858.402123967586, 40265.99335016648, 41295.838020247465, 38061.2387752249, 39738.0, 40417.59582404256, 40734.38898416467, 41097.58902410937, 38235.42646860244, 38504.614953850105, 38651.03372415712, 38948.6105138945, 39099.609003909594, 34291.65708342884, 35466.8226658865, 36404.0, 36523.0, 36799.63200367962, 26231.344216394744, 26450.074247401248, 26527.734722652527, 26596.60105098464, 26945.865270673523], "value": 20998.6258785682, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.4022033754476, 378.8389934277932, 389.8070455124713, 391.1355903453366, 411.1714894487606, 15376.538703838889, 15704.450344237954, 20155.193792248294, 20926.95365231738, 20966.16135354588, 20428.795712042876, 20899.164033438683, 21246.468838279052, 21564.784352156476, 21568.784312156913, 5450.236966824647, 5716.428357164292, 5942.435468630473, 5942.6434413935085, 5972.283326000886, 5839.007368747302, 5864.384239654839, 5929.851753706171, 5931.317898441691, 5934.940650593504, 5581.860453488675, 5825.446582574648, 5827.796027139051, 5842.32813226478, 5882.911756323659, 5640.464155905182, 5823.4467725566, 5852.531797456215, 5885.7939972100985, 5889.558283128783, 5067.49325067494, 5074.517920797555, 5101.974490127563, 5102.872428189288, 5106.872328191787, 23279.650805237936, 26283.868580656974, 26588.803503842373, 26652.20043398701, 26725.465490690076, 1857.0, 1981.2075169932143, 2165.4694599822988, 2428.7449817769057, 2933.1054028521294, 9340.859887101698, 9515.429074255497, 9599.856002159973, 10125.493725313705, 10316.742081447948, 1813.7279408088891, 1846.6306738652188, 1850.2136591948367, 1906.266087556287, 1918.5011896906906, 93035.0, 94311.0, 115358.0, 128565.0, 136974.94537582045, 9853.211743060538, 10765.56937722498, 11082.556697732, 11461.426928653535, 11562.94218528902, 14145.717085658229, 14271.357788899524, 14271.643208919653, 14282.785758213635, 14310.212938288287, 8422.28410585095, 8484.066752657203, 8486.363522735819, 8598.828023439497, 8608.526531040852, 8451.830963380698, 8455.534945578052, 8503.532305723245, 8578.0, 8596.613152408157, 3683.889483315505, 3700.5929347771726, 4393.0, 4474.239379305526, 4501.3248012797685, 1602.9020121217004, 1897.9430617081234, 1918.529960159742, 1927.7975812539482, 1968.7932767059253, 702.8840241360118, 769.9307062364414, 805.4559936280297, 950.2872845365976, 1033.9793204135874, 17806.55483612894, 20721.896390518254, 21779.782202178092, 21789.564208715736, 21815.45461363447, 945.7730144765344, 955.5508910811907, 967.3373738988815, 998.8601595776597, 1011.2870426349406, 10608.840867386996, 10799.028087472165, 11022.889771102347, 11515.942420287845, 11529.250598711056, 591233.0, 599441.0, 599862.0, 616277.0, 621754.0, 102225.73291205103, 123304.0, 127052.0, 129513.0, 133467.33266333607, 960.4621412009298, 993.9204863610894, 1027.4246422003703, 1036.6993571864075, 1038.2887721910365, 19425.708614370513, 19718.112684928925, 19778.30775922836, 19786.604267914274, 19808.81147131177, 52478.47521524889, 52748.0, 56032.0, 56452.717736411054, 59159.0, 83048.33903322021, 83057.0, 83629.65481380676, 84096.31807363941, 84227.26140044854, 5360.195970604441, 5521.5306698931045, 7938.04743430792, 8035.834803953511, 8189.426740128134, 9219.49292788903, 12306.0, 12958.481660733465, 13020.414081366172, 13057.738845222852, 10773.89226107729, 10806.675799726021, 11107.889211078827, 11263.436828158396, 11543.134264929995, 8859.955700221457, 9568.61725530971, 11268.15488838324, 11460.484278207667, 11477.483513241757, 9428.340016198801, 10091.596336146471, 11172.262795615126, 11683.00694440982, 11771.470283837076, 7973.242541958504, 8037.437379383387, 8098.51408915467, 8116.350691944748, 8272.255497005299, 8188.754337369888, 10167.593296268067, 11273.379964101725, 11379.08967282632, 11491.195616306779], "value": 9419.587154118017, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1563.9374425023, 1607.1160861526162, 1782.483079906827, 1887.9056047197653, 1898.6297671953976, 8512.957435212824, 8842.778930526723, 8978.50618215998, 8987.73036808896, 8990.157017811342, 7815.960920195405, 8054.516728996265, 8435.367347448935, 8680.47917124973, 8782.692605758799, 2365.325882123595, 2395.928122156329, 2396.8322217444784, 2397.2927986244013, 2401.135591187171, 2358.917437889674, 2362.208660098869, 2396.125414223813, 2397.1130681647815, 2398.520295940819, 2092.1840482211874, 2298.2645553422913, 2359.6696462495265, 2362.0905951208824, 2370.0993622423457, 2358.905643774247, 2379.0840526397287, 2381.535600557892, 2384.1059602649007, 2389.450426401929, 2337.1819863047967, 2362.326736879998, 2369.324742448394, 2373.9525209495714, 2374.7625237476286, 8915.73252802417, 8981.6856410026, 8994.820103597956, 8995.5052472114, 9014.098590140933, 5218.321618189649, 5302.416734159239, 5357.81247656334, 5375.784968601251, 5388.084025715599, 3511.94732079019, 3591.3535563598543, 3684.9263014739554, 3693.7045036397026, 3717.6839968602703, 1091.8362245663213, 1104.3539529375325, 1108.4568561404935, 1121.747606788466, 1127.7969965406223, 11833.822492662617, 11835.585754498552, 11839.822402663967, 11843.28940263587, 11846.526138954432, 4034.7982600870428, 4589.862304130881, 4810.5670489655395, 4818.807247710052, 4820.084184005098, 9992.500374981222, 9998.450085245382, 10000.0, 10033.498325083716, 10039.799204015879, 5372.274742909657, 5373.194020896896, 5378.085725426687, 5378.78484860609, 5386.138217885198, 4404.603585677304, 4537.228671125917, 4746.667733258708, 4927.827526036572, 4930.926036109461, 3390.796267325064, 3927.7446965947124, 3949.9065495287546, 4051.4125451809323, 4074.694397920168, 1066.2056767708107, 1261.5521489870964, 1262.103906226571, 1273.1597145883673, 1274.7769140400392, 571.2689299063575, 786.3001928283832, 918.2332752152028, 949.852772820214, 968.864358989742, 5875.294964604275, 6034.63792172471, 6090.964536028886, 6161.568690191733, 6163.0, 944.3106532231373, 956.3449037409397, 983.4296108257337, 1015.5988384588046, 1114.2144787924494, 3953.486046813896, 4065.2276067547073, 4083.1629515949157, 4144.772037537904, 4150.833966641361, 10714.51784669676, 10940.179486538542, 11001.339919604849, 11018.173636977099, 11027.952344527257, 10672.519736611872, 10867.913208679069, 10869.945650271698, 10890.237683362247, 10898.237123401444, 8323.958380208182, 8465.0, 8465.703700370457, 8471.915280847235, 8478.915210847812, 5824.0, 5963.7912673056235, 6095.939040609537, 6113.908291375722, 6217.782377616762, 9727.221822254061, 10012.599496020077, 10248.231382646181, 10255.07704306616, 10309.0, 10185.185185185315, 10356.948215259177, 10427.265004247785, 10437.582496700046, 10462.58149674035, 6180.598261113013, 7348.375388092072, 7435.925640743523, 7437.8140546486775, 7439.367653749492, 8284.627191776264, 8323.292520135856, 8396.664133434595, 8398.790030249293, 8429.704960326359, 5731.5701322400155, 7399.187555731567, 7476.990606268084, 7676.232376762279, 7747.18654541265, 7131.928680713127, 7439.70241190368, 7540.095188577406, 7572.5835079068975, 7662.272084151997, 6463.450606698295, 7105.715771369294, 7187.640617969185, 7191.316824901626, 7200.71197152108, 4965.255211718127, 5116.437191908813, 5175.301334319969, 5178.249153872743, 5201.661891977009, 5129.333186685707, 5148.459411761861, 5150.377631046208, 5203.24552939814, 5229.790808367775], "value": 4637.467866602686, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.5049454552713, 303.2252594620743, 313.2013365916912, 318.9029737702305, 319.1844836442888, 5344.759485823138, 5372.3553173619175, 5372.677639341642, 5382.6232163748455, 5411.918821217685, 5003.649744517885, 5119.539241468267, 5205.505476979691, 5207.609429292808, 5247.47525247476, 3253.4469140246097, 3498.7201023918024, 4083.469149010632, 4098.770368889336, 4139.4204811326435, 3554.608993010767, 3910.687145028405, 4075.531313898895, 4100.200460910124, 4122.0, 3205.8397080146015, 3738.252349530091, 3810.1139935731712, 4073.816678249471, 4104.240715467633, 3492.353914525821, 3993.880183594497, 4027.1744292419985, 4077.3068578341604, 4088.6115818997446, 3563.7149028077692, 3563.928721425583, 3565.750397472178, 3577.499150118985, 3580.856765729368, 4283.2932566126865, 4871.805127794884, 4929.8028078876805, 5255.448177941299, 5273.288106105703, 3811.294910441577, 3949.743266687656, 4496.763390067725, 4552.430946131733, 4638.884027899329, 5162.716050617215, 5412.621116521845, 5624.606277560572, 5651.378348381675, 5669.716514174316, 3195.9201019974453, 3839.9248210501105, 3845.788481633509, 3846.288436639231, 3851.9422208666892, 4267.8079486423185, 5018.7453136716185, 5550.806576586054, 5558.638688485235, 5936.970315148396, 3817.2365526894732, 3875.8837234883, 3876.6898648108086, 3890.4164375343926, 3900.356441187229, 3329.667033296651, 3636.7999760012926, 3946.5856085111222, 3967.761934283952, 3971.3447281198855, 4124.670026397881, 4353.628606988788, 4612.215923293048, 4666.390095417076, 4686.015936653273, 5589.776408943678, 5606.299212598426, 5611.643109893855, 5632.943670563324, 5658.971705141448, 1133.0935251798528, 1318.3803612302202, 1338.4311667541256, 1345.239939434236, 1347.3936728472227, 746.6042997211487, 848.8031875056141, 869.9043105258543, 873.3799002708148, 877.5085951866974, 4147.937780933288, 4255.5957184067465, 4777.378940737752, 4968.602511799119, 5306.442823503554, 5710.885782284331, 5840.795572154954, 5852.180694702745, 5860.882782344329, 5868.149118377812, 5063.215201643751, 5298.6290959633225, 5321.760520776497, 5330.641695731664, 5330.706811125426, 3018.320877802521, 3023.727864492206, 3024.9848750756105, 3062.4640687879532, 3079.630444346693, 5017.0, 5138.743062846843, 5889.4699477046415, 5937.910931336033, 6002.698515816283, 5474.315710536183, 5603.411641777636, 5901.380355062656, 5943.821685349447, 5955.166276721263, 4932.506749325111, 5888.735006924613, 5923.763049477972, 5937.554683398676, 5963.582549221513, 5546.694931778791, 5785.971070144622, 5790.131480277992, 5799.565032622486, 5804.2587223833525, 4920.6555541111775, 5158.922616160836, 5648.4633959773755, 5656.0, 5691.886162276648, 5295.841124766263, 5813.761701744774, 5877.647341159544, 5900.262467191601, 5927.585069045125, 3172.7620428468417, 3686.7787932724123, 3807.162424266657, 3822.5978878798446, 3845.730798844054, 5279.208118782213, 5744.224529688606, 5811.0121279381765, 5813.517553023952, 5829.329627092939, 4933.506649335038, 5400.648957817729, 5458.836234912959, 5574.77700891976, 5663.66018038919, 4961.42943561495, 5585.804496842591, 5592.245046918777, 5626.212330273683, 5643.802466913638, 5192.805654699516, 5460.099083651153, 5477.863053423537, 5535.789242239411, 5548.361938377138, 4894.779734911866, 5479.506844384025, 5576.470235327637, 5594.4964953152785, 5598.468145526169, 5486.31421072366, 5501.394846566955, 5510.3938566758425, 5520.8406234691165, 5568.526675232651], "value": 4073.185834049355, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.9936800631993, 672.9966350168248, 718.6730037832787, 751.0761763031471, 819.0130892274808, 15147.16690582019, 16440.58898527536, 16449.342026318933, 17470.475885723416, 18396.26414943404, 16965.57586060349, 17022.404215852446, 17158.82841171588, 17172.227249773758, 17358.0, 716.3624374306871, 738.9445791565654, 894.6466145872377, 917.8760867282898, 920.1764420843341, 889.057598945117, 917.4449458077867, 923.6028507741669, 924.0713083351222, 924.8890133184027, 954.2604481526803, 966.4974213409023, 968.6658102954505, 968.9951550242274, 970.6117552978794, 761.756238003839, 934.7382732834817, 959.040959040959, 966.2415004221723, 967.0764419978904, 702.9086218791575, 703.3704834173437, 703.8275622472476, 705.3123204875257, 708.252793303065, 15544.067355958565, 15588.0, 15589.610259743484, 15621.297041633154, 15662.9216853915, 12077.79222077794, 13905.652358691012, 13990.090644108202, 14016.159030458102, 14177.929110354486, 13220.338983050906, 13281.933590332083, 13315.467381304736, 13558.525451609146, 13748.587542373745, 4584.88537786558, 4614.930776038362, 4635.2583586626315, 4822.493638167977, 4828.155072862235, 109598.45200774024, 117731.0, 119751.40124299323, 120242.0, 120620.39689801582, 9510.334276600572, 11017.779644407066, 11074.5570177192, 11145.665630031112, 11254.662360129209, 21748.0, 21801.0189541471, 21806.672899906513, 21821.23625673094, 21823.563528729337, 7892.329152022027, 7976.880346794803, 7999.920000799918, 8067.805060451203, 8178.0, 4508.526604706459, 4515.164694531522, 4530.275155975029, 4563.771811409416, 4695.014047050089, 3631.3100510902846, 3766.9058273542832, 3781.7352785304765, 3783.7162212834146, 3785.1861849702445, 664.4451882677921, 672.5359501943643, 675.790504943469, 690.0442886602092, 698.9965050174717, 614.8585825260216, 626.79629120536, 629.2826178156813, 655.6492276631978, 675.1830285354678, 8827.470351778913, 9295.953520232491, 9300.0, 9397.812043759086, 9549.61801527945, 589.4459208344143, 626.6897885546683, 661.7683810666229, 679.17819438479, 709.0427922304847, 4593.471750748707, 4626.884327891762, 4634.165850146938, 4667.346571479996, 4684.8500568646405, 89428.5528572366, 108777.91222087837, 135845.0, 137008.0, 147118.0, 77664.61167694125, 118320.40839795746, 127452.36273818572, 127504.72495275114, 128826.0, 71266.287337127, 97813.0, 109925.45037274923, 110001.0, 112895.0, 8844.336674749547, 8853.424527405698, 8861.556922153995, 8957.28341732673, 9022.68420605302, 62587.0, 62669.37330626818, 62907.709229076725, 63160.0, 64187.03719444305, 88746.66879996934, 92913.0, 94621.5268923651, 95452.0, 96158.03841961491, 11885.22746021506, 11887.940560297144, 12039.0, 12045.397730113635, 12326.876731232931, 10888.29226100301, 13229.40467678976, 13695.775715097428, 13953.372098255806, 13988.58034258974, 9428.528573571157, 12550.811737823757, 12658.746825063632, 13528.660300430574, 14206.928965355108, 13102.475900963855, 13856.376463059389, 13975.09161904473, 13977.371018304404, 14275.500357487437, 13508.729825403634, 13594.93202533981, 13607.591772246848, 13621.795673064713, 13645.385957631732, 11285.492152852978, 11512.424378781196, 11702.0, 11832.34922079294, 11847.407629618312, 11273.605423810128, 11402.20184587071, 11529.0, 11625.12811539121, 11783.116266280234], "value": 7928.9663675363945, "unit": "score"}], "name": "dromaeo_css", "value": 7429.149615018198, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 114.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 106.0, 159.0, 105.0, 103.0, 128.0, 103.0, 141.0, 103.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 182.0, 183.0, 180.0, 183.0, 181.0, 182.0, 253.0, 184.0], "value": 189.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 58.0, 87.0, 57.0, 57.0, 62.0, 70.0, 85.0, 57.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 92.0, 92.0, 71.0, 70.0, 67.0, 97.0, 70.0, 67.0, 102.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 85.0, 90.0, 86.0, 87.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 96.0, 94.0, 95.0, 94.0, 95.0, 93.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 84.0, 92.0, 132.0, 130.0, 83.0, 85.0, 84.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 56.0, 57.0, 55.0, 55.0, 53.0, 75.0, 83.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 84.0, 78.0, 106.0, 71.0, 73.0, 71.0, 73.0, 71.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 81.0, 79.0, 80.0, 83.0, 83.0, 83.0, 86.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 153.0, 152.0, 151.0, 142.0, 133.0, 131.0, 137.0, 236.0, 134.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 219.0, 199.0, 161.0, 219.0, 174.0, 164.0, 219.0, 206.0, 162.0], "value": 188.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 70.0, 68.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1473.8, "alertThreshold": 2.0}]} 13:34:22 INFO - Return code: 0 13:34:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:34:22 INFO - # TBPL SUCCESS # 13:34:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:34:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:34:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:34:22 INFO - 2016-07-15 13:34:22.696 screenresolution[1662:9991] starting screenresolution argv=screenresolution get 13:34:22 INFO - 2016-07-15 13:34:22.699 screenresolution[1662:9991] Display 0: 1600x1200x32@60 13:34:22 INFO - 2016-07-15 13:34:22.705 screenresolution[1663:9994] starting screenresolution argv=screenresolution list 13:34:22 INFO - Available Modes on Display 0 13:34:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:34:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:34:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:34:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:34:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:34:22 INFO - Intel Iris: 13:34:22 INFO - Chipset Model: Intel Iris 13:34:22 INFO - Type: GPU 13:34:22 INFO - Bus: Built-In 13:34:22 INFO - VRAM (Dynamic, Max): 1536 MB 13:34:22 INFO - Vendor: Intel (0x8086) 13:34:22 INFO - Device ID: 0x0a2e 13:34:22 INFO - Revision ID: 0x0009 13:34:22 INFO - Displays: 13:34:22 INFO - Display: 13:34:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:34:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:34:22 INFO - Display Serial Number: 1600x1200 60 13:34:22 INFO - Main Display: Yes 13:34:22 INFO - Mirror: Off 13:34:22 INFO - Online: Yes 13:34:22 INFO - Rotation: Supported 13:34:22 INFO - Return code: 0 13:34:22 INFO - Running post-action listener: _resource_record_post_action 13:34:22 INFO - ##### 13:34:22 INFO - ##### Finished run-tests step (success) 13:34:22 INFO - ##### 13:34:22 INFO - Running post-run listener: _resource_record_post_run 13:34:22 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31317504; Write bytes: 705568768; Read time: 196; Write time: 1986 13:34:22 INFO - TinderboxPrint: CPU usage
53.4% 13:34:23 INFO - TinderboxPrint: I/O read bytes / time
31,317,504 / 196 13:34:23 INFO - TinderboxPrint: I/O write bytes / time
705,568,768 / 1,986 13:34:23 INFO - TinderboxPrint: CPU idle
2,256.1 (46.6%) 13:34:23 INFO - TinderboxPrint: CPU system
964.4 (19.9%) 13:34:23 INFO - TinderboxPrint: CPU user
1,625.1 (33.5%) 13:34:23 INFO - TinderboxPrint: Swap in / out
434,647,040 / 0 13:34:23 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392673792; Write bytes: 386024960; Read time: 29391; Write time: 819 13:34:23 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30387712; Write bytes: 294388736; Read time: 184; Write time: 1103 13:34:23 INFO - Running post-run listener: _upload_blobber_files 13:34:23 INFO - Blob upload gear active. 13:34:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:34: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 13:34: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'] 13:34: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 13:34:23 INFO - (blobuploader) - INFO - Open directory for files ... 13:34:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:34:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:34:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:25 INFO - (blobuploader) - INFO - Done attempting. 13:34:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:34:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:34:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:25 INFO - (blobuploader) - INFO - Done attempting. 13:34:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:34:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:34:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:28 INFO - (blobuploader) - INFO - Done attempting. 13:34:28 INFO - (blobuploader) - INFO - Iteration through files over. 13:34:28 INFO - Return code: 0 13:34:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:34:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:34:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/50466dd5b232f0cd2bdb30f86242a8f2846bb239fc92a3a00f44d372a6b3d9349885c8c0bc79320a1e2c4e8c088a230cd079290e379a46d64fb052b0b0c77db8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/514185058f68a93acbd3fdc356485ab1dea72e5dafe0fde28d55f71ce42998b2cb5e667f8e6f3a4cbac4b77cfee0c2267a25992e485694752d9afd7da3bd15a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58fadfc581b5490f983b6425e0e227d128c676c420b93f7f097c41784d2d52e6142e849af604821ccb24a261726789e1868ad21755a52d1a6637e4070bfb5286"} 13:34:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:34:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:34:28 INFO - Contents: 13:34:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/50466dd5b232f0cd2bdb30f86242a8f2846bb239fc92a3a00f44d372a6b3d9349885c8c0bc79320a1e2c4e8c088a230cd079290e379a46d64fb052b0b0c77db8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/514185058f68a93acbd3fdc356485ab1dea72e5dafe0fde28d55f71ce42998b2cb5e667f8e6f3a4cbac4b77cfee0c2267a25992e485694752d9afd7da3bd15a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58fadfc581b5490f983b6425e0e227d128c676c420b93f7f097c41784d2d52e6142e849af604821ccb24a261726789e1868ad21755a52d1a6637e4070bfb5286"} 13:34:28 INFO - Running post-run listener: copy_logs_to_upload_dir 13:34:28 INFO - Copying logs to upload dir... 13:34:28 INFO - mkdir: /builds/slave/test/build/upload/logs 13:34:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.053846 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-15 13:34:28.455269) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 13:34:28.458334) ========= 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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/50466dd5b232f0cd2bdb30f86242a8f2846bb239fc92a3a00f44d372a6b3d9349885c8c0bc79320a1e2c4e8c088a230cd079290e379a46d64fb052b0b0c77db8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/514185058f68a93acbd3fdc356485ab1dea72e5dafe0fde28d55f71ce42998b2cb5e667f8e6f3a4cbac4b77cfee0c2267a25992e485694752d9afd7da3bd15a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58fadfc581b5490f983b6425e0e227d128c676c420b93f7f097c41784d2d52e6142e849af604821ccb24a261726789e1868ad21755a52d1a6637e4070bfb5286"} build_url:https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010455 build_url: 'https://queue.taskcluster.net/v1/task/Y_CpgBfRQ6aih6rcy7G2NA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/50466dd5b232f0cd2bdb30f86242a8f2846bb239fc92a3a00f44d372a6b3d9349885c8c0bc79320a1e2c4e8c088a230cd079290e379a46d64fb052b0b0c77db8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/514185058f68a93acbd3fdc356485ab1dea72e5dafe0fde28d55f71ce42998b2cb5e667f8e6f3a4cbac4b77cfee0c2267a25992e485694752d9afd7da3bd15a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/58fadfc581b5490f983b6425e0e227d128c676c420b93f7f097c41784d2d52e6142e849af604821ccb24a261726789e1868ad21755a52d1a6637e4070bfb5286"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 13:34:28.489879) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:34:28.490194) ========= 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.nW7SkV9Oh4/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.jUPVbQ3QCB/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.005202 ========= master_lag: 0.29 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 13:34:28.782899) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 13:34:28.783278) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 13:34:28.784542) ========= ========= Total master_lag: 0.41 =========