builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0359 starttime: 1468983679.36 results: success (0) buildid: 20160718105252 builduid: 3a678ddbbdff44509ee2e29603f57976 revision: 2a69345f4f4eeb804e6a96c70f086f10e9849724 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:19.364327) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:19.364684) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:19.364952) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:19.419209) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:19.419453) ========= 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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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-19 20:01:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 71.0M=0s 2016-07-19 20:01:19 (71.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.550475 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:19.994405) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:19.994945) ========= 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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.026083 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:20.046216) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:20.046831) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 2a69345f4f4eeb804e6a96c70f086f10e9849724 --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 2a69345f4f4eeb804e6a96c70f086f10e9849724 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.62OAsxcCqe/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.bJ9ecPCdCB/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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-19 20:01:20,127 truncating revision to first 12 chars 2016-07-19 20:01:20,127 Setting DEBUG logging. 2016-07-19 20:01:20,127 attempt 1/10 2016-07-19 20:01:20,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/2a69345f4f4e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 20:01:20,825 unpacking tar archive at: autoland-2a69345f4f4e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.964020 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:21.035033) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:21.035615) ========= script_repo_revision: 2a69345f4f4eeb804e6a96c70f086f10e9849724 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:21.036270) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:21.036759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 20:01:21.056993) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-07-19 20:01:21.057681) ========= /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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:01:21 INFO - MultiFileLogger online at 20160719 20:01:21 in /builds/slave/test 20:01:21 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 20:01:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:01:21 INFO - {'append_to_log': False, 20:01:21 INFO - 'base_work_dir': '/builds/slave/test', 20:01:21 INFO - 'blob_upload_branch': 'Autoland', 20:01:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:01:21 INFO - 'branch': 'Autoland', 20:01:21 INFO - 'buildbot_json_path': 'buildprops.json', 20:01:21 INFO - 'config_files': ('talos/mac_config.py',), 20:01:21 INFO - 'default_actions': ('clobber', 20:01:21 INFO - 'read-buildbot-config', 20:01:21 INFO - 'download-and-extract', 20:01:21 INFO - 'populate-webroot', 20:01:21 INFO - 'create-virtualenv', 20:01:21 INFO - 'install', 20:01:21 INFO - 'run-tests'), 20:01:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:01:21 INFO - 'download_minidump_stackwalk': True, 20:01:21 INFO - 'download_symbols': 'ondemand', 20:01:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:01:21 INFO - 'tooltool.py': '/tools/tooltool.py', 20:01:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:01:21 INFO - '/tools/misc-python/virtualenv.py')}, 20:01:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:01:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:01:21 INFO - 'installer_path': 'installer.exe', 20:01:21 INFO - 'log_level': 'info', 20:01:21 INFO - 'log_name': 'talos', 20:01:21 INFO - 'log_to_console': True, 20:01:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:01:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:01:21 INFO - 'opt_config_files': (), 20:01:21 INFO - 'pip_index': False, 20:01:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:01:21 INFO - 'cmd': ('bash', 20:01:21 INFO - '-c', 20:01:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:01:21 INFO - 'enabled': True, 20:01:21 INFO - 'halt_on_failure': False, 20:01:21 INFO - 'name': 'check_screen_resolution'},), 20:01:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:01:21 INFO - 'cmd': ('bash', 20:01:21 INFO - '-c', 20:01:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:01:21 INFO - 'enabled': True, 20:01:21 INFO - 'halt_on_failure': False, 20:01:21 INFO - 'name': 'check_screen_resolution'},), 20:01:21 INFO - 'run_cmd_checks_enabled': True, 20:01:21 INFO - 'sps_profile': False, 20:01:21 INFO - 'sps_profile_interval': 0, 20:01:21 INFO - 'suite': 'dromaeojs', 20:01:21 INFO - 'system_bits': '32', 20:01:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:01:21 INFO - 'title': 't-yosemite-r7-0359', 20:01:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:01:21 INFO - 'use_talos_json': True, 20:01:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:01:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:01:21 INFO - 'work_dir': 'build'} 20:01:21 INFO - ##### 20:01:21 INFO - ##### Running clobber step. 20:01:21 INFO - ##### 20:01:21 INFO - Running pre-action listener: _resource_record_pre_action 20:01:21 INFO - Running main action method: clobber 20:01:21 INFO - rmtree: /builds/slave/test/build 20:01:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:01:23 INFO - Running post-action listener: _resource_record_post_action 20:01:23 INFO - ##### 20:01:23 INFO - ##### Finished clobber step (success) 20:01:23 INFO - ##### 20:01:23 INFO - ##### 20:01:23 INFO - ##### Running read-buildbot-config step. 20:01:23 INFO - ##### 20:01:23 INFO - Running pre-action listener: _resource_record_pre_action 20:01:23 INFO - Running main action method: read_buildbot_config 20:01:23 INFO - Using buildbot properties: 20:01:23 INFO - { 20:01:23 INFO - "project": "", 20:01:23 INFO - "product": "firefox", 20:01:23 INFO - "script_repo_revision": "production", 20:01:23 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:01:23 INFO - "repository": "", 20:01:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:01:23 INFO - "buildid": "20160718105252", 20:01:23 INFO - "pgo_build": "False", 20:01:23 INFO - "basedir": "/builds/slave/test", 20:01:23 INFO - "buildnumber": 47, 20:01:23 INFO - "slavename": "t-yosemite-r7-0359", 20:01:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 20:01:23 INFO - "platform": "yosemite_r7", 20:01:23 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:01:23 INFO - "branch": "autoland", 20:01:23 INFO - "repo_path": "integration/autoland", 20:01:23 INFO - "revision": "2a69345f4f4eeb804e6a96c70f086f10e9849724", 20:01:23 INFO - "stage_platform": "macosx64", 20:01:23 INFO - "builduid": "3a678ddbbdff44509ee2e29603f57976", 20:01:23 INFO - "slavebuilddir": "test" 20:01:23 INFO - } 20:01:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 20:01:23 INFO - Running post-action listener: _resource_record_post_action 20:01:23 INFO - ##### 20:01:23 INFO - ##### Finished read-buildbot-config step (success) 20:01:23 INFO - ##### 20:01:23 INFO - ##### 20:01:23 INFO - ##### Running download-and-extract step. 20:01:23 INFO - ##### 20:01:23 INFO - Running pre-action listener: _resource_record_pre_action 20:01:23 INFO - Running main action method: download_and_extract 20:01:23 INFO - mkdir: /builds/slave/test/build/tests 20:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:01:23 INFO - https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:01:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 20:01:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 20:01:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:24 INFO - Downloaded 1199 bytes. 20:01:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 20:01:24 INFO - Using the following test package requirements: 20:01:24 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:01:24 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 20:01:24 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 20:01:24 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'jsshell-mac.zip'], 20:01:24 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 20:01:24 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:01:24 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 20:01:24 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 20:01:24 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 20:01:24 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:01:24 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 20:01:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:01: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')]} 20:01:24 INFO - https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:01:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:01:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:01:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:30 INFO - Downloaded 18835698 bytes. 20:01:30 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'] 20:01:30 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 20:01:33 INFO - Return code: 0 20:01:33 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 20:01:33 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')]} 20:01:33 INFO - https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:01:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:01:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:01:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:38 INFO - Downloaded 18835698 bytes. 20:01:38 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'] 20:01:38 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 20:01:42 INFO - Return code: 0 20:01:42 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')]} 20:01:42 INFO - https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:43 INFO - Downloaded 14403317 bytes. 20:01:43 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'] 20:01:43 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 20:01:43 INFO - Return code: 0 20:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:01:43 INFO - https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:01:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:01:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:01:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 20:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LYvxBkYeSly_vWsA1VN1jg/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 20:01:46 INFO - Downloaded 112927730 bytes. 20:01:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:01:46 INFO - mkdir: /builds/slave/test/properties 20:01:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:01:46 INFO - Writing to file /builds/slave/test/properties/build_url 20:01:46 INFO - Contents: 20:01:46 INFO - build_url:https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:01:47 INFO - Running post-action listener: _resource_record_post_action 20:01:47 INFO - Running post-action listener: set_extra_try_arguments 20:01:47 INFO - ##### 20:01:47 INFO - ##### Finished download-and-extract step (success) 20:01:47 INFO - ##### 20:01:47 INFO - ##### 20:01:47 INFO - ##### Running populate-webroot step. 20:01:47 INFO - ##### 20:01:47 INFO - Running pre-action listener: _resource_record_pre_action 20:01:47 INFO - Running main action method: populate_webroot 20:01:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:01:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:01:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:01:47 INFO - '--tppagecycles', 20:01:47 INFO - '7'], 20:01:47 INFO - 'tests': ['tsvgm']}}, 20:01:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:01:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:01:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:01:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:01:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:01:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:01:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['damp', 'tps']}, 20:01:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'tests': ['damp', 'tps']}, 20:01:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['dromaeo_dom']}, 20:01:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:01:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['basic_compositor_video']}, 20:01:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:01:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['a11yr', 20:01:47 INFO - 'ts_paint', 20:01:47 INFO - 'tpaint', 20:01:47 INFO - 'sessionrestore', 20:01:47 INFO - 'sessionrestore_no_auto_restore', 20:01:47 INFO - 'tabpaint']}, 20:01:47 INFO - 'other-e10s': {'tests': ['a11yr', 20:01:47 INFO - 'ts_paint', 20:01:47 INFO - 'tpaint', 20:01:47 INFO - 'sessionrestore', 20:01:47 INFO - 'sessionrestore_no_auto_restore', 20:01:47 INFO - 'tabpaint']}, 20:01:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:01:47 INFO - 'ts_paint', 20:01:47 INFO - 'tpaint', 20:01:47 INFO - 'sessionrestore', 20:01:47 INFO - 'sessionrestore_no_auto_restore', 20:01:47 INFO - 'tabpaint']}, 20:01:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:01:47 INFO - 'ts_paint', 20:01:47 INFO - 'tpaint', 20:01:47 INFO - 'sessionrestore', 20:01:47 INFO - 'sessionrestore_no_auto_restore', 20:01:47 INFO - 'tabpaint']}, 20:01:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['a11yr', 20:01:47 INFO - 'ts_paint', 20:01:47 INFO - 'tpaint', 20:01:47 INFO - 'sessionrestore', 20:01:47 INFO - 'sessionrestore_no_auto_restore', 20:01:47 INFO - 'tabpaint']}, 20:01:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['a11yr', 20:01:47 INFO - 'ts_paint', 20:01:47 INFO - 'tpaint', 20:01:47 INFO - 'sessionrestore', 20:01:47 INFO - 'sessionrestore_no_auto_restore', 20:01:47 INFO - 'tabpaint']}, 20:01:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['tsvgx', 20:01:47 INFO - 'tsvgr_opacity', 20:01:47 INFO - 'tart', 20:01:47 INFO - 'tscrollx', 20:01:47 INFO - 'cart']}, 20:01:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:01:47 INFO - 'tsvgr_opacity', 20:01:47 INFO - 'tart', 20:01:47 INFO - 'tscrollx', 20:01:47 INFO - 'cart']}, 20:01:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'talos_options': ['--disable-e10s'], 20:01:47 INFO - 'tests': ['tp5o']}, 20:01:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'tests': ['tp5o']}, 20:01:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'talos_options': ['--disable-e10s', 20:01:47 INFO - '--xperf_path', 20:01:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:01:47 INFO - 'tests': ['tp5n']}, 20:01:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:01:47 INFO - 'talos_options': ['--xperf_path', 20:01:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:01:47 INFO - 'tests': ['tp5n']}}, 20:01:47 INFO - 'talos.zip': {'path': '', 20:01:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:01:47 INFO - Running post-action listener: _resource_record_post_action 20:01:47 INFO - ##### 20:01:47 INFO - ##### Finished populate-webroot step (success) 20:01:47 INFO - ##### 20:01:47 INFO - ##### 20:01:47 INFO - ##### Running create-virtualenv step. 20:01:47 INFO - ##### 20:01:47 INFO - Running pre-action listener: _resource_record_pre_action 20:01:47 INFO - Running main action method: create_virtualenv 20:01:47 INFO - Creating virtualenv /builds/slave/test/build/venv 20:01:47 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 20:01:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:01:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:01:47 INFO - Using real prefix '/tools/python27' 20:01:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:01:47 INFO - Installing distribute.............................................................................................................................................................................................done. 20:01:50 INFO - Installing pip.................done. 20:01:50 INFO - Return code: 0 20:01:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:01:50 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')]} 20:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:50 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')]} 20:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:50 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 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:01:50 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 20:01:50 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 20:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:50 INFO - 'HOME': '/Users/cltbld', 20:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:50 INFO - 'LOGNAME': 'cltbld', 20:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:50 INFO - 'MOZ_NO_REMOTE': '1', 20:01:50 INFO - 'NO_EM_RESTART': '1', 20:01:50 INFO - 'PAGER': '/bin/cat', 20:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:50 INFO - 'PWD': '/builds/slave/test', 20:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:50 INFO - 'SHELL': '/bin/bash', 20:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:01:50 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:50 INFO - 'USER': 'cltbld', 20:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:50 INFO - 'XPC_FLAGS': '0x0', 20:01:50 INFO - 'XPC_SERVICE_NAME': '0', 20:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:51 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 20:01:51 INFO - Cleaning up... 20:01:51 INFO - Return code: 0 20:01:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:01:51 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')]} 20:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:51 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')]} 20:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:51 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 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:01:51 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 20:01:51 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 20:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:51 INFO - 'HOME': '/Users/cltbld', 20:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:51 INFO - 'LOGNAME': 'cltbld', 20:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:51 INFO - 'MOZ_NO_REMOTE': '1', 20:01:51 INFO - 'NO_EM_RESTART': '1', 20:01:51 INFO - 'PAGER': '/bin/cat', 20:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:51 INFO - 'PWD': '/builds/slave/test', 20:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:51 INFO - 'SHELL': '/bin/bash', 20:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:01:51 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:51 INFO - 'USER': 'cltbld', 20:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:51 INFO - 'XPC_FLAGS': '0x0', 20:01:51 INFO - 'XPC_SERVICE_NAME': '0', 20:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:51 INFO - Downloading/unpacking psutil>=3.1.1 20:01:51 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 20:01:51 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 20:01:51 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 20:01:51 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 20:01:51 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 20:01:51 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 20:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:01:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:01:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:01:54 INFO - Installing collected packages: psutil 20:01:54 INFO - Running setup.py install for psutil 20:01:54 INFO - building 'psutil._psutil_osx' extension 20:01:54 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 20:01:54 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 20:01:54 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 20:01:55 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 20:01:55 INFO - building 'psutil._psutil_posix' extension 20:01:55 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 20:01:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:01:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:01:55 INFO - ^ 20:01:55 INFO - 1 warning generated. 20:01:55 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 20:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:01:55 INFO - Successfully installed psutil 20:01:55 INFO - Cleaning up... 20:01:55 INFO - Return code: 0 20:01:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:01: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')]} 20:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01: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')]} 20:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:01: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:01: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 mozsystemmonitor==0.3 20:01:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:01:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:55 INFO - 'HOME': '/Users/cltbld', 20:01:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:55 INFO - 'LOGNAME': 'cltbld', 20:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:55 INFO - 'MOZ_NO_REMOTE': '1', 20:01:55 INFO - 'NO_EM_RESTART': '1', 20:01:55 INFO - 'PAGER': '/bin/cat', 20:01:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:55 INFO - 'PWD': '/builds/slave/test', 20:01:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:55 INFO - 'SHELL': '/bin/bash', 20:01:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:01:55 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:01:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:55 INFO - 'USER': 'cltbld', 20:01:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:55 INFO - 'XPC_FLAGS': '0x0', 20:01:55 INFO - 'XPC_SERVICE_NAME': '0', 20:01:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:01:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:01:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:01:58 INFO - Installing collected packages: mozsystemmonitor 20:01:58 INFO - Running setup.py install for mozsystemmonitor 20:01:58 INFO - Successfully installed mozsystemmonitor 20:01:58 INFO - Cleaning up... 20:01:58 INFO - Return code: 0 20:01:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:01:58 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')]} 20:01:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:58 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')]} 20:01:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:58 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 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:01:58 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 20:01:58 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 20:01:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:01:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:01:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:01:58 INFO - 'HOME': '/Users/cltbld', 20:01:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:01:58 INFO - 'LOGNAME': 'cltbld', 20:01:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:58 INFO - 'MOZ_NO_REMOTE': '1', 20:01:58 INFO - 'NO_EM_RESTART': '1', 20:01:58 INFO - 'PAGER': '/bin/cat', 20:01:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:01:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:58 INFO - 'PWD': '/builds/slave/test', 20:01:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:01:58 INFO - 'SHELL': '/bin/bash', 20:01:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:01:58 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:01:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:01:58 INFO - 'USER': 'cltbld', 20:01:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:01:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:01:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:58 INFO - 'XPC_FLAGS': '0x0', 20:01:58 INFO - 'XPC_SERVICE_NAME': '0', 20:01:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:58 INFO - Downloading/unpacking jsonschema==2.5.1 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:01: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 20:02:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:02:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:02:01 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 20:02:01 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 20:02:01 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 20:02:01 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 20:02:01 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 20:02:01 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 20:02:01 INFO - Downloading functools32-3.2.3-2.tar.gz 20:02:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:02:01 INFO - warning: no files found matching '*.txt' 20:02:01 INFO - no previously-included directories found matching 'build' 20:02:01 INFO - no previously-included directories found matching 'dist' 20:02:01 INFO - no previously-included directories found matching '.git*' 20:02:01 INFO - Installing collected packages: jsonschema, functools32 20:02:01 INFO - Running setup.py install for functools32 20:02:01 INFO - warning: no files found matching '*.txt' 20:02:01 INFO - no previously-included directories found matching 'build' 20:02:01 INFO - no previously-included directories found matching 'dist' 20:02:01 INFO - no previously-included directories found matching '.git*' 20:02:01 INFO - Successfully installed jsonschema functools32 20:02:01 INFO - Cleaning up... 20:02:01 INFO - Return code: 0 20:02:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:01 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 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:02:01 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 20:02:01 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 20:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:01 INFO - 'HOME': '/Users/cltbld', 20:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:01 INFO - 'LOGNAME': 'cltbld', 20:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:01 INFO - 'MOZ_NO_REMOTE': '1', 20:02:01 INFO - 'NO_EM_RESTART': '1', 20:02:01 INFO - 'PAGER': '/bin/cat', 20:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:01 INFO - 'PWD': '/builds/slave/test', 20:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:01 INFO - 'SHELL': '/bin/bash', 20:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:02:01 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:01 INFO - 'USER': 'cltbld', 20:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:01 INFO - 'XPC_FLAGS': '0x0', 20:02:01 INFO - 'XPC_SERVICE_NAME': '0', 20:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:02 INFO - Downloading/unpacking blobuploader==1.2.4 20:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:04 INFO - Downloading blobuploader-1.2.4.tar.gz 20:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:02:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:02:04 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 20:02:04 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 20:02:04 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 20:02:04 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 20:02:04 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 20:02:04 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 20:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:02:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:02:05 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 20:02:05 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 20:02:05 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 20:02:05 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 20:02:05 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 20:02:05 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 20:02:05 INFO - Downloading docopt-0.6.1.tar.gz 20:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:02:05 INFO - Installing collected packages: blobuploader, requests, docopt 20:02:05 INFO - Running setup.py install for blobuploader 20:02:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:02:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:02:06 INFO - Running setup.py install for requests 20:02:06 INFO - Running setup.py install for docopt 20:02:06 INFO - Successfully installed blobuploader requests docopt 20:02:06 INFO - Cleaning up... 20:02:06 INFO - Return code: 0 20:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:06 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')]} 20:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:06 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')]} 20:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:06 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 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:02:06 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 20:02:06 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 20:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:06 INFO - 'HOME': '/Users/cltbld', 20:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:06 INFO - 'LOGNAME': 'cltbld', 20:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:06 INFO - 'MOZ_NO_REMOTE': '1', 20:02:06 INFO - 'NO_EM_RESTART': '1', 20:02:06 INFO - 'PAGER': '/bin/cat', 20:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:06 INFO - 'PWD': '/builds/slave/test', 20:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:06 INFO - 'SHELL': '/bin/bash', 20:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:02:06 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:06 INFO - 'USER': 'cltbld', 20:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:06 INFO - 'XPC_FLAGS': '0x0', 20:02:06 INFO - 'XPC_SERVICE_NAME': '0', 20:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-DcXAcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-i19d2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-rLE1Tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-w1grPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-7w39yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-25G4T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-vOAESI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-T5QllW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-9QLFxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:07 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-HTyFRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:08 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-sMajZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:08 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-5lI1kB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:08 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-103o0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:08 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-A3ZOaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:08 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-usgGys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:08 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-M8PLc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:08 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-STAhvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:02:08 INFO - Running setup.py install for manifestparser 20:02:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:02:09 INFO - Running setup.py install for mozcrash 20:02:09 INFO - Running setup.py install for mozdebug 20:02:09 INFO - Running setup.py install for mozdevice 20:02:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:02:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:02:09 INFO - Running setup.py install for mozfile 20:02:09 INFO - Running setup.py install for mozhttpd 20:02:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:02:09 INFO - Running setup.py install for mozinfo 20:02:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:02:09 INFO - Running setup.py install for mozInstall 20:02:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Running setup.py install for mozleak 20:02:10 INFO - Running setup.py install for mozlog 20:02:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Running setup.py install for moznetwork 20:02:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Running setup.py install for mozprocess 20:02:10 INFO - Running setup.py install for mozprofile 20:02:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:02:10 INFO - Running setup.py install for mozrunner 20:02:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:02:11 INFO - Running setup.py install for mozscreenshot 20:02:11 INFO - Running setup.py install for moztest 20:02:11 INFO - Running setup.py install for mozversion 20:02:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:02:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:02:11 INFO - Cleaning up... 20:02:11 INFO - Return code: 0 20:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:11 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 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:02:11 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 20:02:11 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 20:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:11 INFO - 'HOME': '/Users/cltbld', 20:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:11 INFO - 'LOGNAME': 'cltbld', 20:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:11 INFO - 'MOZ_NO_REMOTE': '1', 20:02:11 INFO - 'NO_EM_RESTART': '1', 20:02:11 INFO - 'PAGER': '/bin/cat', 20:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:11 INFO - 'PWD': '/builds/slave/test', 20:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:11 INFO - 'SHELL': '/bin/bash', 20:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:02:11 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:11 INFO - 'USER': 'cltbld', 20:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:11 INFO - 'XPC_FLAGS': '0x0', 20:02:11 INFO - 'XPC_SERVICE_NAME': '0', 20:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:11 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-P4UhkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:11 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)) 20:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:11 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-DHpyvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-5NaJq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-meu8o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-6ussrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-DR1LFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-Fdktpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-gzQyqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-2Z9tFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-poUNKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-mSweQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:12 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)) 20:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:12 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-AGgmGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:13 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)) 20:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:13 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-qTxPuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:13 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)) 20:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:13 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-ggQaCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:13 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)) 20:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:13 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-r3qcZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:13 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)) 20:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:13 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-i2_kQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:13 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)) 20:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:13 INFO - Running setup.py (path:/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/pip-hnOkMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:13 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)) 20:02:13 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)) 20:02:13 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)) 20:02:13 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)) 20:02:13 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)) 20:02:13 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)) 20:02:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:02:13 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 20:02:13 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 20:02:13 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 20:02:13 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 20:02:13 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 20:02:13 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 20:02:15 INFO - Downloading blessings-1.6.tar.gz 20:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:02:16 INFO - Installing collected packages: blessings 20:02:16 INFO - Running setup.py install for blessings 20:02:16 INFO - Successfully installed blessings 20:02:16 INFO - Cleaning up... 20:02:16 INFO - Return code: 0 20:02:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:02:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:16 INFO - Reading from file tmpfile_stdout 20:02:16 INFO - Current package versions: 20:02:16 INFO - blessings == 1.6 20:02:16 INFO - blobuploader == 1.2.4 20:02:16 INFO - docopt == 0.6.1 20:02:16 INFO - functools32 == 3.2.3-2 20:02:16 INFO - jsonschema == 2.5.1 20:02:16 INFO - manifestparser == 1.1 20:02:16 INFO - mozInstall == 1.12 20:02:16 INFO - mozcrash == 0.17 20:02:16 INFO - mozdebug == 0.1 20:02:16 INFO - mozdevice == 0.48 20:02:16 INFO - mozfile == 1.2 20:02:16 INFO - mozhttpd == 0.7 20:02:16 INFO - mozinfo == 0.9 20:02:16 INFO - mozleak == 0.1 20:02:16 INFO - mozlog == 3.2 20:02:16 INFO - moznetwork == 0.27 20:02:16 INFO - mozprocess == 0.23 20:02:16 INFO - mozprofile == 0.28 20:02:16 INFO - mozrunner == 6.12 20:02:16 INFO - mozscreenshot == 0.1 20:02:16 INFO - mozsystemmonitor == 0.3 20:02:16 INFO - moztest == 0.7 20:02:16 INFO - mozversion == 1.4 20:02:16 INFO - psutil == 3.1.1 20:02:16 INFO - requests == 1.2.3 20:02:16 INFO - wsgiref == 0.1.2 20:02:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:16 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 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:02:16 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 20:02:16 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 20:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:16 INFO - 'HOME': '/Users/cltbld', 20:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:16 INFO - 'LOGNAME': 'cltbld', 20:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:16 INFO - 'MOZ_NO_REMOTE': '1', 20:02:16 INFO - 'NO_EM_RESTART': '1', 20:02:16 INFO - 'PAGER': '/bin/cat', 20:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:16 INFO - 'PWD': '/builds/slave/test', 20:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:16 INFO - 'SHELL': '/bin/bash', 20:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:02:16 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:16 INFO - 'USER': 'cltbld', 20:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:16 INFO - 'XPC_FLAGS': '0x0', 20:02:16 INFO - 'XPC_SERVICE_NAME': '0', 20:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:02:16 INFO - Cleaning up... 20:02:16 INFO - Return code: 0 20:02:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107170a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107205618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd034c1b50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, '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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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 20:02:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:16 INFO - 'HOME': '/Users/cltbld', 20:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:16 INFO - 'LOGNAME': 'cltbld', 20:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:16 INFO - 'MOZ_NO_REMOTE': '1', 20:02:16 INFO - 'NO_EM_RESTART': '1', 20:02:16 INFO - 'PAGER': '/bin/cat', 20:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:16 INFO - 'PWD': '/builds/slave/test', 20:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:16 INFO - 'SHELL': '/bin/bash', 20:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:02:16 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:16 INFO - 'USER': 'cltbld', 20:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:16 INFO - 'XPC_FLAGS': '0x0', 20:02:16 INFO - 'XPC_SERVICE_NAME': '0', 20:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:02:17 INFO - Cleaning up... 20:02:17 INFO - Return code: 0 20:02:17 INFO - Running post-action listener: _resource_record_post_action 20:02:17 INFO - Running post-action listener: _start_resource_monitoring 20:02:17 INFO - Starting resource monitoring. 20:02:17 INFO - ##### 20:02:17 INFO - ##### Finished create-virtualenv step (success) 20:02:17 INFO - ##### 20:02:17 INFO - ##### 20:02:17 INFO - ##### Running install step. 20:02:17 INFO - ##### 20:02:17 INFO - Running pre-action listener: _resource_record_pre_action 20:02:17 INFO - Running main action method: install 20:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:17 INFO - Reading from file tmpfile_stdout 20:02:17 INFO - Detecting whether we're running mozinstall >=1.0... 20:02:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:02:17 INFO - Reading from file tmpfile_stdout 20:02:17 INFO - Output received: 20:02:17 INFO - Usage: mozinstall [options] installer 20:02:17 INFO - Options: 20:02:17 INFO - -h, --help show this help message and exit 20:02:17 INFO - -d DEST, --destination=DEST 20:02:17 INFO - Directory to install application into. [default: 20:02:17 INFO - "/builds/slave/test"] 20:02:17 INFO - --app=APP Application being installed. [default: firefox] 20:02:17 INFO - mkdir: /builds/slave/test/build/application 20:02:17 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'] 20:02:17 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 20:02:51 INFO - Reading from file tmpfile_stdout 20:02:51 INFO - Output received: 20:02:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:02:51 INFO - Running post-action listener: _resource_record_post_action 20:02:51 INFO - ##### 20:02:51 INFO - ##### Finished install step (success) 20:02:51 INFO - ##### 20:02:51 INFO - ##### 20:02:51 INFO - ##### Running run-tests step. 20:02:51 INFO - ##### 20:02:51 INFO - Running pre-action listener: _resource_record_pre_action 20:02:51 INFO - Running main action method: run_tests 20:02:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:02:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:02:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:02:51 INFO - 2016-07-19 20:02:51.600 screenresolution[1643:7614] starting screenresolution argv=screenresolution get 20:02:51 INFO - 2016-07-19 20:02:51.602 screenresolution[1643:7614] Display 0: 1600x1200x32@60 20:02:51 INFO - 2016-07-19 20:02:51.608 screenresolution[1644:7616] starting screenresolution argv=screenresolution list 20:02:51 INFO - Available Modes on Display 0 20:02:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:02:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:02:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:02:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:02:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:02:51 INFO - Intel Iris: 20:02:51 INFO - Chipset Model: Intel Iris 20:02:51 INFO - Type: GPU 20:02:51 INFO - Bus: Built-In 20:02:51 INFO - VRAM (Dynamic, Max): 1536 MB 20:02:51 INFO - Vendor: Intel (0x8086) 20:02:51 INFO - Device ID: 0x0a2e 20:02:51 INFO - Revision ID: 0x0009 20:02:51 INFO - Displays: 20:02:51 INFO - Display: 20:02:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:02:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:02:51 INFO - Display Serial Number: 1600x1200 60 20:02:51 INFO - Main Display: Yes 20:02:51 INFO - Mirror: Off 20:02:51 INFO - Online: Yes 20:02:51 INFO - Rotation: Supported 20:02:51 INFO - Return code: 0 20:02:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:02:51 INFO - Python 2.7.3 20:02:51 INFO - Return code: 0 20:02:51 INFO - grabbing minidump binary from tooltool 20:02:51 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')]} 20:02:51 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 0x1070d4d30>, '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 0x106e39db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1070ccb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:02:51 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 20:02:51 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 20:02:51 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 20:02:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:02:51 INFO - Return code: 0 20:02:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:02:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:02:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:02:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:02:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:02:51 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/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--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 20:02:51 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/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0359 --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 20:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62OAsxcCqe/Render', 20:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:51 INFO - 'HOME': '/Users/cltbld', 20:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:51 INFO - 'LOGNAME': 'cltbld', 20:02:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:02:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:51 INFO - 'MOZ_NO_REMOTE': '1', 20:02:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:02:51 INFO - 'NO_EM_RESTART': '1', 20:02:51 INFO - 'PAGER': '/bin/cat', 20:02:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:51 INFO - 'PWD': '/builds/slave/test', 20:02:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:51 INFO - 'SHELL': '/bin/bash', 20:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJ9ecPCdCB/Listeners', 20:02:51 INFO - 'TMPDIR': '/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/', 20:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:51 INFO - 'USER': 'cltbld', 20:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:51 INFO - 'XPC_FLAGS': '0x0', 20:02:51 INFO - 'XPC_SERVICE_NAME': '0', 20:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:51 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/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0359', '--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 20:02:52 INFO - mozversion application_buildid: 20160718105252 20:02:52 INFO - mozversion application_changeset: 2a69345f4f4eeb804e6a96c70f086f10e9849724 20:02:52 INFO - mozversion application_display_name: Nightly 20:02:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:02:52 INFO - mozversion application_name: Firefox 20:02:52 INFO - mozversion application_remotingname: firefox 20:02:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:02:52 INFO - mozversion application_vendor: Mozilla 20:02:52 INFO - mozversion application_version: 50.0a1 20:02:52 INFO - mozversion platform_buildid: 20160718105252 20:02:52 INFO - mozversion platform_changeset: 2a69345f4f4eeb804e6a96c70f086f10e9849724 20:02:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:02:52 INFO - mozversion platform_version: 50.0a1 20:02:52 INFO - using testdate: 1468983772 20:02:52 INFO - actual date: 1468983772 20:02:52 INFO - starting webserver on 'localhost:49279' 20:02:52 INFO - SUITE-START | Running 2 tests 20:02:52 INFO - TEST-START | dromaeo_css 20:02:52 INFO - operating with platform_type : mac_ 20:02:52 INFO - Initialising browser for dromaeo_css test... 20:02:52 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpaol8K4/profile http://localhost:49279/getInfo.html) 20:02:54 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 20:02:54 INFO - PROCESS | 1654 | colorDepth:24 20:02:54 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 20:02:54 INFO - PROCESS | 1654 | __metrics 20:02:54 INFO - PROCESS | 1654 | 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" 20:02:54 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:02:55 INFO - TEST-INFO | 1654: exit 0 20:02:55 INFO - Browser initialized. 20:02:55 INFO - Running cycle 1/1 for dromaeo_css test... 20:02:55 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmpaol8K4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:03:07 INFO - PROCESS | 1656 | RSS: Main: 245190656 20:03:07 INFO - PROCESS | 1656 | 20:03:58 INFO - PROCESS | 1656 | 1468983838798 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] 20:06:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 20:06:10 INFO - PROCESS | 1656 | RSS: Main: 371785728 20:06:10 INFO - PROCESS | 1656 | 20:09:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 20:09:14 INFO - PROCESS | 1656 | RSS: Main: 372871168 20:09:14 INFO - PROCESS | 1656 | 20:12:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 20:12:17 INFO - PROCESS | 1656 | RSS: Main: 370286592 20:12:17 INFO - PROCESS | 1656 | 20:15:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 20:15:20 INFO - PROCESS | 1656 | RSS: Main: 369410048 20:15:20 INFO - PROCESS | 1656 | 20:18:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 20:18:24 INFO - PROCESS | 1656 | RSS: Main: 342634496 20:18:24 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 20:21:27 INFO - PROCESS | 1656 | RSS: Main: 402436096 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | __start_tp_report 20:21:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 20:21:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 20:21:27 INFO - PROCESS | 1656 | |i|pagename|runs| 20:21:27 INFO - PROCESS | 1656 | |0;dojo.html;2933.3106719920816;3037.756979441645;3426.5888093428794;3440.6559344065595;3444.1389652586854;5036.3200967869325;5703.68629725366;5747.741351639176;5879.147523609074;5895.646261224324;5745.827625171251;5846.473817356437;5855.590108692382;5866.853328666785;5894.764209431628;5251.55361794248;5257.23770053342;5306.495882891119;5352.892942141174;5396.973015134919;5400.702961337125;5449.427810079944;5461.726913654321;5469.097598896197;5489.615726899118;5244.318238628973;5310.62825602208;5314.893702125936;5362.544183744387;5378.058839703036;4210.473690788652;5272.209168624699;5284.524392804666;5307.973460132714;5333.306670132897;5292.57659387252;5303.973480132613;5316;5328.280682107905;5401.3248343957;5705.315362156526;5840.3575606683235;5899.085641725539;5922.230110085704;5943.583949123563;6254.249490061222;6300.5589608727405;6343.080253363282;6361.872762544769;6366.71349789256;6288.811335659938;6314.968425157891;6317.305096439388;6322.71547780351;6326.5255105867245;5832.416758324219;6038.849028774272;6091.817245482643;6093.025115981466;6093.969530152365;6249.937500624936;6261.874762504815;6309.400606942333;6313.9368606314265;6371.840703982438;6229.657368844757;6307.684615769193;6310.495160387158;6315.273743519554;6342.460890824332;6238.688065596702;6314.684265786692;6337.081123237105;6355.491560675135;6371.96814015927;6262.780802671976;6322.589031712924;6340.873182536324;6360.427561519486;6394.456471199907;6350.936490635035;6352.65060421672;6368.458681012074;6375.553711240262;6419.133416988647;4959.876003099951;5006.148954677714;5097.974510127426;5102.08162530748;5146.794128234828;4547.613452856478;4555.589996900295;4604.493505714368;4608.792604332813;4622.583967442946;5943.791967281124;5945.078512830518;5949.851253718692;5951.553633477506;6013.30846952606;6196.287426945869;6232.376762323732;6244.056894500217;6265.561410701207;6315.5263355247625;5000.74996250186;5867.970660146672;5938.138969849348;5938.8812223755285;5963.940360596425;5539.169124631338;5549.889002219933;5554.750036248379;5560.471755183251;5560.805371811967;6223.813285601349;6326.525510586816;6378.362163783585;6391.872162556723;6392.041193820871;6274.937250627435;6298.370162983665;6307.558470907176;6316.115743795965;6319.24169099711;6437.388448097423;6443.355664433707;6444.420002199825;6446.774362897276;6447.2263328400895;6105.914670190187;6223.097650840512;6261.279952805486;6299.338569450325;6338.366163383809;6279.152314437492;6312.652804095819;6318;6333.113364128933;6365.809025729235;6191.628502289697;6282.560220784501;6286.5599408041;6289.213848268966;6295.527835412454;4973.577245933991;4975.50244975514;6194.163787888758;6223.159873417212;6280.937190628218;6128.8774224516155;6140.232470941132;6140.846478838065;6143.723532440961;6161.4146656067605;6050.062240352752;6277.089821975879;6277.937220627735;6278.623282603058;6282.780102696383;6022.759089636541;6082.422169893853;6111.470193407285;6126.754929802936;6152.323244443197 20:21:27 INFO - PROCESS | 1656 | |1;ext.html;474.8124490826126;504.4123596010648;513.1737901977815;521.1219095823537;523.001067960196;31473.842630786843;41954;42339.364909526346;42452.78773606136;42456;44695;44955.32567011497;44970;45125.54874451263;45212.547874521246;8613.019005628708;9680.177184939272;9858.556364963593;9867.802643947116;9891.060349266809;8643.956780216122;11825.231359961575;11978.221415607957;12016.158868879182;12364.938175309157;11955.163138580303;12004.039676825876;12019.879801201963;12023.21849079807;12069.698257543589;8750.34372422071;11669.416529173508;11819.172657913907;12079.818802717966;12090.274583525017;10002.699919002442;11681.707957301136;11864.52541898323;11947.581834635834;11949.880501194964;33958.83020584906;42342;42408.57591424077;42496.3625545617;42581.57418425807;3088.922776930572;3579.83890724918;3684.705223582107;3878.7478813877005;3935.704822138351;32159.839200803846;32775.836120819244;33077.00768976934;33126.503102453484;33137.3372532548;3090.4900691385897;3146.921326966821;3440.587129544446;3450.551428314328;3451.775634583744;520342.3982880099;520500.3974980101;523116.3844180755;523851;524850.9999999962;65104.34895650983;65266.347336526975;65755.34244657587;65760.684786304;65932.34067659358;34995;67513.32486675169;68527;68688;68696.65651671709;16087.034777913363;17030.57423564421;17050.658986820195;17058.402955896483;17112.657746844994;11684.88315116838;11866.821997670042;12897.968162546973;12900.258465107278;13087.541936032194;15576.766348504781;16344.591385215464;19497.60169128117;19516.70724939127;20058.39824805258;6999.860002800017;7669.5398276103615;7878.881816772753;8026.678932842621;8037.196280372033;718.6299055986171;988.7972965542046;1045.6706137566641;1047.779966207105;1071.2982996137541;25945.351366215997;26310.736892631212;26958.460830783668;27677.540307461568;27739.167824965283;1041.390786389955;1075.8063548561333;1096.9122470202365;1114.1309778372881;1124.8425220469142;14868.851311486964;14965.850341496664;15002.24988750558;15155.242237888282;15223;676965;679573;679718;684772;686048;60179.699101504215;62304.68847655733;62366.75266494645;62583.37416625866;62607.37392626107;1263.2988691276246;1278.7186818899827;1371.3760239091189;1408.901376903607;1452.0924422236103;25222.847144106985;26361.60457593176;26635.33411664724;27657.446851062465;27817;52522;53165.99999999845;53214.4678553225;53304;53547.92904141973;514108.4294578653;524476;526185;528254;533627;23815;23870.403239919142;23923.40191495157;25091;26945.326366840203;40985.38521922114;41340.06343844104;43205.56794432141;51167;53144;40712.79643601881;41720.58279417167;41896.95257618584;42107.789461052496;42603.5739642612;39245.60754392534;40647.1870562593;41037.79481102695;42063.36904946367;42172;39326.213475730896;39468.40797387984;39709;39930.60069399269;40315.798421007705;34351;35846.82076589705;36050.27899442049;36093.63906360903;36404.45393319155;17780.288788448834;21067.789322107197;21901;22138.225162119248;22331.77668223297 20:21:27 INFO - PROCESS | 1656 | |2;jquery.html;359.25633937748836;379.96580307772297;387.1849756263066;387.46723255523636;413.2272650144231;15544.145072021034;16526.586835329108;20164.49588760278;20505.589888202227;20513.794862051374;15908.52274431766;19511.90244048778;20183.49541261469;20200.39398818034;20280.695789563168;4111.588841115879;5788.42115788421;5855.180274761516;5888.882222355551;5896.262967129109;6261.467775239087;6668.76659316924;6669.766558170465;6671.466282697397;6671.733130674791;5464.836054918359;6558.278589355167;6597;6690.7323707051655;6695.598264104145;6398.520110991648;6613.470922326226;6648.900266496006;6649.567778094409;6653.268140504565;5747.885042299131;5784.1902133701315;5784.3926387729525;5788.565857560658;5790.305163380379;24183.395415114763;24448.266552003468;24691.50616987668;24791.5041699165;24796.876015619808;2264.682944387787;2495.6880389951257;2538.9876880984953;2906.346072133774;3248.1067706380936;14775.335109920079;15305.770413443808;15308.693826123526;15799.684006319809;16105.677886442205;1807.2951548895942;1816.2916462579606;1891.4042076745918;1919.7120431935182;1925;104045;113132.8473571924;113696;115028.6734229617;117648;11102.555897764161;11478.94260528692;11497.425128743529;11557.479913403917;11717.88282117185;13940.302984850718;14008.649783755487;14034.578962631138;14036.368363423671;14052.805511531635;8475.32197424217;8497.235248827636;8502.78743031429;8544.017437994586;8620.267277281502;8612.78468038304;8615.956920215358;8618.864699129794;8718.782030449163;8742.344324175712;4444.000099977544;4460.598546130799;4466.977665111719;4483.2826747720865;4524.524924882906;1755.1044871538707;1889.8110188980993;1939.527102943758;1948.756405449319;1968.6259610674213;704.7920863345325;724.6014691919423;775.4068137874531;873.5763154869883;983.5082458770614;20580.588388232452;20969.370918872457;21644.4588885276;21664.34634212064;21772.56454870894;909.077286554148;925.9629614815332;976.6972238606055;976.8925418203992;997.680742162501;10135.645252416278;11039.006489415991;11189.72025699364;11481.36852473122;11601.245919015237;509152;528744;530506;530689;553832;121229.39385303017;121471.78528214782;122622;123194;127126;980.1668581705493;996.3523709588825;1009.3489699144158;1010.3230835340339;1012.8581998520214;18301.63396732031;18702.251909923452;18722.531936701693;18823.34118305853;18967.810321896606;54925.45074549203;56611.43388566226;57151;57257.42742572688;57334.7133264331;73010;74594.44872012566;79534.60232699031;82865;82979.58510207411;8027.237412445808;8040.276375126266;8126.878096828434;8147.14454982242;8365.62354694028;12159.148859579744;12738.87261127377;12766.42551085222;13052.282124483245;13151.671208219872;11057.834132487858;11151.72120696989;11404.82892756626;11421.086313094766;11428.657140285804;11109.222354435113;11156.832647510457;11198.664040078811;11449.313041217554;11484.540618375171;10876.652420236494;11491.54033838637;11499.195056345976;11585.6524304271;11719.88280117222;7999.200079991954;8170.591470426574;8185.0996390398195;8202.015758108826;8365.372597055124;5413.350397952271;7124.643767811693;7342.8898566520475;11127.443627818739;11147.721306967067 20:21:27 INFO - PROCESS | 1656 | |3;mootools.html;1313.3564553368851;1576.771368151618;1678.16091954023;1854.9443516694505;1868.7944326124125;8481.575921203937;8665.740027799176;8825.205731484166;8846.601902914368;8856.557172141383;7848.529088254709;8072.313853322475;8480.872786908201;8722.738317850459;8732.607032683529;2358.9646155307682;2387.1525608409024;2389.486260454002;2392.4138586046356;2393.0786647140803;2128.797764212405;2347.753485883983;2348.1077190667525;2396.664466974625;2398.052769156191;1940.7282980382759;2153.536989547255;2298.425393651587;2367.7987371073477;2368.005437716163;2344.0740907341674;2360.4688944987324;2369.822263330257;2372.3001714494267;2373.3236027732187;2342.2153578551206;2356.905723771047;2358;2365.6451532270125;2370.751071137523;8679.132086791333;8796.516191609458;8806.691765788166;8820;8829.735107946772;5242.9213561796605;5403.13549832025;5406.02691515527;5407.5133238008375;5408.59435542332;3517.8416971236356;3606.765560238576;3658.8353524091212;3668.1196512836727;3671.7796932184156;1072.1797824664138;1086.1528008153653;1093.1691914145226;1110.566878917223;1111.183280288987;11697.23967942081;11788.941055294754;11798.823017654742;11799.94100029503;11808.881911180864;3574.873914716856;3853.9421908671393;4755.690880092783;4758.619310455155;4774.331593576902;10024.79950400988;10056;10060.496975151213;10077.54651040705;10093.899061009295;5360.65155764874;5366.409694933554;5376.542993845567;5377.838664840061;5381.838544843583;4796.496367881392;4834.395700537433;4913.6069114471475;4915.631327650441;4919.139150648693;3922.921541569153;3946.3488524393156;3992.84028638857;4027.4160246764054;4029.0330320722815;1184.7156682396162;1226.4297101847683;1229.3115855121162;1265.563789704833;1275.7831168701157;689.2315068698465;872.842888280103;923.3628796130648;942.5146049784366;959.2805395953035;5887.499562537147;5897.439743224387;5971.731272092767;6044.703012855087;6121.418465245794;826.277007618334;909.1953621045291;930.1489137439303;941.4163218804248;979.2117345536911;3436.1753179236803;3919.9608003920166;3964.345882929342;4062.0251139726843;4065;10351.223658225663;10479.633212837514;10637.9468102659;10645.893541064645;10646.627368042082;9099.772505687279;9802.852957205647;9815.067568580973;10082.445465499468;10528.578856845641;7424.83892292964;7519.360854327443;7874.645640946057;8223.835523289621;8394.454360466672;5543.113101903838;5777.480026797609;5997.970010150096;6179.783707570214;6270.216222972129;9196.862047069166;9732.172765314745;10102.747431314276;10116.393016419039;10123.493825308558;10356.741081473023;10394.324368915995;10399.96000399954;10403.271770976262;10415.94792026035;7387.630618469163;7397.704091836266;7469.663865125974;7475.588842613491;7492.925070749223;7987.16134805837;8162.591870406337;8184.713535026245;8213.917860821315;8237.093919668712;7266.2007179211305;7481.7755467336065;7489.325960663567;7634.923650763643;7640.617969101411;7365.373943214887;7365.631718414165;7418.295261950106;7492.925070749223;7513.8872916907385;6584.637844918576;6843.213030501556;6850.0409942607075;6898.413634840894;6904.827379315558;4913.778879950482;4933.9506604933495;4960.603151747924;4962.106820772296;5026.070177017264;5031.227969139343;5071.492850714899;5096.337476128079;5180.870478237924;5198.298229738937 20:21:27 INFO - PROCESS | 1656 | |4;prototype.html;302.55524379162625;304.1332203220821;311.2902582112782;312.4828408983134;316.6754077071001;5341.009034940436;5378.838634840956;5403.540699040582;5429.53848922842;5436.646617969829;5310.283111779909;5310.681359118446;5313.3092697949305;5336.573074154068;5338.946610533893;3119.3293441909927;3953.5255769307723;4035.515738111431;4071.620554939393;4144.191882582898;3368.7641865069454;3467.6358982306874;4025.0943537704075;4085.5097388313297;4091.672666186713;3583.605803361628;4068.613481719232;4106.8973275668195;4117.258893399187;4139.751614903101;3562.412201986669;3728.5339332583426;3751.3247615429214;3952.4268980997595;4102.220578090183;2947.896823611163;3559.181388280684;3566.964330356689;3573.356795776759;3579.7315201359743;5196.506331898464;5200.401953775288;5200.427952925175;5220.634555581111;5253.737313134367;4443.733375997418;4648.953510464886;4657.836975705868;4693.647976401783;4707.6233901287815;5560.86097847553;5586.497215250647;5620.4098569650005;5632.633878797906;5653.745581448845;3454.808091208529;3923.98038009811;3930.862419815293;3943.5859234780223;3959.4852669152824;5168.811173430095;5231.999999999962;5626.859328516779;5730.111832665943;5782.45066718661;3155.684431556826;3980.7810570418324;3982.8605998789903;4002.6197511236387;4014.959850401517;3397.4564069749217;4053.148838743838;4089.345704687236;4098.282800509899;4107.199096176217;4440.557693268689;4491.281394976853;4680.3447517347595;4707.929381059287;4719.027012981799;4478.955210447919;4998.450170481244;5567.635929197372;5637.3235211774845;5655.660660360391;1079.1744315598573;1310.4103153580927;1365.7951307303786;1368.7270838120444;1389.2220356600337;720.4128635162326;853.8036251662153;856.4090777363599;870.7300736771494;897.4166791585391;3981.7810020448574;4338.544452832471;4569.54304569547;4580.862574122715;5061.342025536656;5835.35811060783;5847.619904706181;5856.648601083949;5859.619124756877;5866.6186697864505;4346.782660866944;4432.711873728262;4932.728699921467;5299.44355842631;5331.280277162534;2565.781908537795;2843.5876797864194;3004.7596192304563;3030.4848175809725;3047.5828739636177;4845.5881250094135;5865.941340586625;5889.970550147307;5911.965406053923;5961.046232602849;5284.894302114013;5645.9717701412055;5717.456841600041;5909.881802363929;5955.642661440327;5068.113080210948;5142.97428512855;5383.488568585978;5565.387807341189;5616.438356164433;4805.447373551947;5021.723805190603;5663.799953026527;5713.457221563945;5839.854003649943;4789.4971028041555;4921.434035085867;5084.906745049832;5751.4536119068625;5780.335261444988;4901.460839307602;4949.628777841748;5673.517750991047;5847.18737191472;5879.794207202727;3470.9521382384514;3536.275063612005;3726.4782930389224;3886.3587508061983;3896.941545876871;5481.095619222783;5812.622179558315;5821.970890145522;5862.772146584271;5873.2658417697785;4906.435759887659;5334.453008627454;5734.541236701138;5771.567132465166;5775.33583637887;5046.788770694933;5116.258142569232;5235.267062611314;5616.663000219836;5630.971845140748;5003.799848006184;5070.340855688737;5528.9723551381985;5571.771291483277;5572.609917305912;5217.0870097733505;5482.5888058394985;5555.50000499957;5634.859128521656;5676.375598684224;5452.318460192476;5492.258545096361;5511.04357952921;5523.116301391758;5524.226608274765 20:21:27 INFO - PROCESS | 1656 | |5;yui.html;735.7130719019583;742.4246209187883;779.1662920674877;798.7044793426433;824.9298809601186;11328.886711132885;15871.603209919758;15911.283992220364;15925.681486370266;15929.52211433656;13919.582412527616;14344.067635603678;14448.711025779477;14510.129392236473;14599.854001459982;862.482510493705;870.0342619692136;870.277669534285;870.3341943413294;871.4901782457271;714.3035540348171;715.964201789911;718.9209186989427;729.3253740290237;732.5458215906145;744.2892038103598;904.2856143646511;933.3093510802025;954.1222075690381;954.2699834626517;929.4609126706493;930.9627614895396;931.5249222896372;931.6552875436098;933.3000249812641;549.5164255455214;682.4540367705865;683.852971611106;684.736376495048;689.0525527399825;14559.199244041574;14995.400183992737;15016.174110423923;15020.774688379683;15031.699366012728;14349;14743.262836858114;14796.48212312564;14959.551213463614;15011.924940375227;11929.522819087313;11961.880381196164;11993.340366279852;12003.819942700866;12211.877881221162;3259.0385836178384;3285.3593549257903;3327.633960264369;3371;3379.425497665403;107207;108470;109343.45328273308;109612;109786.90213097847;11122;11259.324440533594;11311.943440282746;11513.481893314822;11802;22799.772002279766;22805.88597057004;22811.771882280966;22886;23304.65043024356;7961.960190199012;7996.360291176692;8125.95937020323;8241.95879020601;8323.458975166595;4420.425344705168;4607.792649330788;4627.629789616823;4632.768361581908;4745.83389581363;3572.910677233038;3723.888283351504;3731.272401881607;3734.9915522808687;3735.1409175889153;557.9665220086807;650.6226388694546;668.3750693101965;690.2993461636443;691.6230654293422;601.879624075178;676.7326905872153;687.2337343861558;691.993080069203;704.7322017633369;8859;9386.624535018524;9537.856932146024;9573.712788616353;9620;567.7246535430313;644.1883227133751;672.8889733193968;686.6086330791401;690.1304356510833;4499.235130027903;4631.86519302773;4632.907341853212;4659.720416774937;4660.46404663468;125008;125283;127351.36324318504;130498;135487.6451235475;135641.3217933904;136802.31598841943;137294;138483;141637;72818;83131.75302370469;91039.54480227688;104664.43003354956;114492;9780.266480013885;9808.607655693977;9831.655892043744;9843.458609776531;9918.504074796087;60818;63439;63541.72916541736;65204;68225;92034;99424.50287748805;99642;100009;101810.98189018015;12582.559610413591;12707.682307942376;12760.6809829755;12967.740645187232;12983.805242921553;14905.329260183535;14978.400863965755;15322;15391.307391167647;15630.45293414725;13884;13975.440982360591;13984.844941762885;14178.858211418166;14513.201773902536;13941;13993.163828255801;14314.355853986388;14582.343794529059;14744.926275368554;13761.243131627456;13880.583582492542;14296.57110286693;14349.856501434851;14398.42406303736;11169.832452513056;11270.94364528172;11282.548698051985;11549.690181125121;12029.578964736542;10759.569617215224;11266.493007814832;11393.772124557298;11485.138614603771;11541 20:21:27 INFO - PROCESS | 1656 | __end_tp_report 20:21:27 INFO - PROCESS | 1656 | __start_cc_report 20:21:27 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1262 20:21:27 INFO - PROCESS | 1656 | __end_cc_report 20:21:27 INFO - PROCESS | 1656 | __startTimestamp1468984887716__endTimestamp 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | ------- Summary: start ------- 20:21:27 INFO - PROCESS | 1656 | Number of tests: 6 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5839.55 Median:6126.75 stddev:677.22 (11.1%) stddev-sans-first:639.77 20:21:27 INFO - PROCESS | 1656 | Values: 2933.3 3037.8 3426.6 3440.7 3444.1 5036.3 5703.7 5747.7 5879.1 5895.6 5745.8 5846.5 5855.6 5866.9 5894.8 5251.6 5257.2 5306.5 5352.9 5397.0 5400.7 5449.4 5461.7 5469.1 5489.6 5244.3 5310.6 5314.9 5362.5 5378.1 4210.5 5272.2 5284.5 5308.0 5333.3 5292.6 5304.0 5316.0 5328.3 5401.3 5705.3 5840.4 5899.1 5922.2 5943.6 6254.2 6300.6 6343.1 6361.9 6366.7 6288.8 6315.0 6317.3 6322.7 6326.5 5832.4 6038.8 6091.8 6093.0 6094.0 6249.9 6261.9 6309.4 6313.9 6371.8 6229.7 6307.7 6310.5 6315.3 6342.5 6238.7 6314.7 6337.1 6355.5 6372.0 6262.8 6322.6 6340.9 6360.4 6394.5 6350.9 6352.7 6368.5 6375.6 6419.1 4959.9 5006.1 5098.0 5102.1 5146.8 4547.6 4555.6 4604.5 4608.8 4622.6 5943.8 5945.1 5949.9 5951.6 6013.3 6196.3 6232.4 6244.1 6265.6 6315.5 5000.7 5868.0 5938.1 5938.9 5963.9 5539.2 5549.9 5554.8 5560.5 5560.8 6223.8 6326.5 6378.4 6391.9 6392.0 6274.9 6298.4 6307.6 6316.1 6319.2 6437.4 6443.4 6444.4 6446.8 6447.2 6105.9 6223.1 6261.3 6299.3 6338.4 6279.2 6312.7 6318.0 6333.1 6365.8 6191.6 6282.6 6286.6 6289.2 6295.5 4973.6 4975.5 6194.2 6223.2 6280.9 6128.9 6140.2 6140.8 6143.7 6161.4 6050.1 6277.1 6277.9 6278.6 6282.8 6022.8 6082.4 6111.5 6126.8 6152.3 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:71419.48 Median:20044.58 stddev:155331.46 (774.9%) stddev-sans-first:155675.88 20:21:27 INFO - PROCESS | 1656 | Values: 474.8 504.4 513.2 521.1 523.0 31473.8 41954.0 42339.4 42452.8 42456.0 44695.0 44955.3 44970.0 45125.5 45212.5 8613.0 9680.2 9858.6 9867.8 9891.1 8644.0 11825.2 11978.2 12016.2 12364.9 11955.2 12004.0 12019.9 12023.2 12069.7 8750.3 11669.4 11819.2 12079.8 12090.3 10002.7 11681.7 11864.5 11947.6 11949.9 33958.8 42342.0 42408.6 42496.4 42581.6 3088.9 3579.8 3684.7 3878.7 3935.7 32159.8 32775.8 33077.0 33126.5 33137.3 3090.5 3146.9 3440.6 3450.6 3451.8 520342.4 520500.4 523116.4 523851.0 524851.0 65104.3 65266.3 65755.3 65760.7 65932.3 34995.0 67513.3 68527.0 68688.0 68696.7 16087.0 17030.6 17050.7 17058.4 17112.7 11684.9 11866.8 12898.0 12900.3 13087.5 15576.8 16344.6 19497.6 19516.7 20058.4 6999.9 7669.5 7878.9 8026.7 8037.2 718.6 988.8 1045.7 1047.8 1071.3 25945.4 26310.7 26958.5 27677.5 27739.2 1041.4 1075.8 1096.9 1114.1 1124.8 14868.9 14965.9 15002.2 15155.2 15223.0 676965.0 679573.0 679718.0 684772.0 686048.0 60179.7 62304.7 62366.8 62583.4 62607.4 1263.3 1278.7 1371.4 1408.9 1452.1 25222.8 26361.6 26635.3 27657.4 27817.0 52522.0 53166.0 53214.5 53304.0 53547.9 514108.4 524476.0 526185.0 528254.0 533627.0 23815.0 23870.4 23923.4 25091.0 26945.3 40985.4 41340.1 43205.6 51167.0 53144.0 40712.8 41720.6 41897.0 42107.8 42603.6 39245.6 40647.2 41037.8 42063.4 42172.0 39326.2 39468.4 39709.0 39930.6 40315.8 34351.0 35846.8 36050.3 36093.6 36404.5 17780.3 21067.8 21901.0 22138.2 22331.8 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:33052.31 Median:13646.28 stddev:89011.93 (652.3%) stddev-sans-first:89227.78 20:21:27 INFO - PROCESS | 1656 | Values: 359.3 380.0 387.2 387.5 413.2 15544.1 16526.6 20164.5 20505.6 20513.8 15908.5 19511.9 20183.5 20200.4 20280.7 4111.6 5788.4 5855.2 5888.9 5896.3 6261.5 6668.8 6669.8 6671.5 6671.7 5464.8 6558.3 6597.0 6690.7 6695.6 6398.5 6613.5 6648.9 6649.6 6653.3 5747.9 5784.2 5784.4 5788.6 5790.3 24183.4 24448.3 24691.5 24791.5 24796.9 2264.7 2495.7 2539.0 2906.3 3248.1 14775.3 15305.8 15308.7 15799.7 16105.7 1807.3 1816.3 1891.4 1919.7 1925.0 104045.0 113132.8 113696.0 115028.7 117648.0 11102.6 11478.9 11497.4 11557.5 11717.9 13940.3 14008.6 14034.6 14036.4 14052.8 8475.3 8497.2 8502.8 8544.0 8620.3 8612.8 8616.0 8618.9 8718.8 8742.3 4444.0 4460.6 4467.0 4483.3 4524.5 1755.1 1889.8 1939.5 1948.8 1968.6 704.8 724.6 775.4 873.6 983.5 20580.6 20969.4 21644.5 21664.3 21772.6 909.1 926.0 976.7 976.9 997.7 10135.6 11039.0 11189.7 11481.4 11601.2 509152.0 528744.0 530506.0 530689.0 553832.0 121229.4 121471.8 122622.0 123194.0 127126.0 980.2 996.4 1009.3 1010.3 1012.9 18301.6 18702.3 18722.5 18823.3 18967.8 54925.5 56611.4 57151.0 57257.4 57334.7 73010.0 74594.4 79534.6 82865.0 82979.6 8027.2 8040.3 8126.9 8147.1 8365.6 12159.1 12738.9 12766.4 13052.3 13151.7 11057.8 11151.7 11404.8 11421.1 11428.7 11109.2 11156.8 11198.7 11449.3 11484.5 10876.7 11491.5 11499.2 11585.7 11719.9 7999.2 8170.6 8185.1 8202.0 8365.4 5413.4 7124.6 7342.9 11127.4 11147.7 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5682.15 Median:4926.54 stddev:3167.87 (64.3%) stddev-sans-first:3159.74 20:21:27 INFO - PROCESS | 1656 | Values: 1313.4 1576.8 1678.2 1854.9 1868.8 8481.6 8665.7 8825.2 8846.6 8856.6 7848.5 8072.3 8480.9 8722.7 8732.6 2359.0 2387.2 2389.5 2392.4 2393.1 2128.8 2347.8 2348.1 2396.7 2398.1 1940.7 2153.5 2298.4 2367.8 2368.0 2344.1 2360.5 2369.8 2372.3 2373.3 2342.2 2356.9 2358.0 2365.6 2370.8 8679.1 8796.5 8806.7 8820.0 8829.7 5242.9 5403.1 5406.0 5407.5 5408.6 3517.8 3606.8 3658.8 3668.1 3671.8 1072.2 1086.2 1093.2 1110.6 1111.2 11697.2 11788.9 11798.8 11799.9 11808.9 3574.9 3853.9 4755.7 4758.6 4774.3 10024.8 10056.0 10060.5 10077.5 10093.9 5360.7 5366.4 5376.5 5377.8 5381.8 4796.5 4834.4 4913.6 4915.6 4919.1 3922.9 3946.3 3992.8 4027.4 4029.0 1184.7 1226.4 1229.3 1265.6 1275.8 689.2 872.8 923.4 942.5 959.3 5887.5 5897.4 5971.7 6044.7 6121.4 826.3 909.2 930.1 941.4 979.2 3436.2 3920.0 3964.3 4062.0 4065.0 10351.2 10479.6 10637.9 10645.9 10646.6 9099.8 9802.9 9815.1 10082.4 10528.6 7424.8 7519.4 7874.6 8223.8 8394.5 5543.1 5777.5 5998.0 6179.8 6270.2 9196.9 9732.2 10102.7 10116.4 10123.5 10356.7 10394.3 10400.0 10403.3 10415.9 7387.6 7397.7 7469.7 7475.6 7492.9 7987.2 8162.6 8184.7 8213.9 8237.1 7266.2 7481.8 7489.3 7634.9 7640.6 7365.4 7365.6 7418.3 7492.9 7513.9 6584.6 6843.2 6850.0 6898.4 6904.8 4913.8 4934.0 4960.6 4962.1 5026.1 5031.2 5071.5 5096.3 5180.9 5198.3 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4506.82 Median:5069.23 stddev:1393.26 (27.5%) stddev-sans-first:1360.96 20:21:27 INFO - PROCESS | 1656 | Values: 302.6 304.1 311.3 312.5 316.7 5341.0 5378.8 5403.5 5429.5 5436.6 5310.3 5310.7 5313.3 5336.6 5338.9 3119.3 3953.5 4035.5 4071.6 4144.2 3368.8 3467.6 4025.1 4085.5 4091.7 3583.6 4068.6 4106.9 4117.3 4139.8 3562.4 3728.5 3751.3 3952.4 4102.2 2947.9 3559.2 3567.0 3573.4 3579.7 5196.5 5200.4 5200.4 5220.6 5253.7 4443.7 4649.0 4657.8 4693.6 4707.6 5560.9 5586.5 5620.4 5632.6 5653.7 3454.8 3924.0 3930.9 3943.6 3959.5 5168.8 5232.0 5626.9 5730.1 5782.5 3155.7 3980.8 3982.9 4002.6 4015.0 3397.5 4053.1 4089.3 4098.3 4107.2 4440.6 4491.3 4680.3 4707.9 4719.0 4479.0 4998.5 5567.6 5637.3 5655.7 1079.2 1310.4 1365.8 1368.7 1389.2 720.4 853.8 856.4 870.7 897.4 3981.8 4338.5 4569.5 4580.9 5061.3 5835.4 5847.6 5856.6 5859.6 5866.6 4346.8 4432.7 4932.7 5299.4 5331.3 2565.8 2843.6 3004.8 3030.5 3047.6 4845.6 5865.9 5890.0 5912.0 5961.0 5284.9 5646.0 5717.5 5909.9 5955.6 5068.1 5143.0 5383.5 5565.4 5616.4 4805.4 5021.7 5663.8 5713.5 5839.9 4789.5 4921.4 5084.9 5751.5 5780.3 4901.5 4949.6 5673.5 5847.2 5879.8 3471.0 3536.3 3726.5 3886.4 3896.9 5481.1 5812.6 5822.0 5862.8 5873.3 4906.4 5334.5 5734.5 5771.6 5775.3 5046.8 5116.3 5235.3 5616.7 5631.0 5003.8 5070.3 5529.0 5571.8 5572.6 5217.1 5482.6 5555.5 5634.9 5676.4 5452.3 5492.3 5511.0 5523.1 5524.2 20:21:27 INFO - PROCESS | 1656 | 20:21:27 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24467.62 Median:3306.50 stddev:38099.39 (1152.3%) stddev-sans-first:38164.60 20:21:27 INFO - PROCESS | 1656 | Values: 735.7 742.4 779.2 798.7 824.9 11328.9 15871.6 15911.3 15925.7 15929.5 13919.6 14344.1 14448.7 14510.1 14599.9 862.5 870.0 870.3 870.3 871.5 714.3 716.0 718.9 729.3 732.5 744.3 904.3 933.3 954.1 954.3 929.5 931.0 931.5 931.7 933.3 549.5 682.5 683.9 684.7 689.1 14559.2 14995.4 15016.2 15020.8 15031.7 14349.0 14743.3 14796.5 14959.6 15011.9 11929.5 11961.9 11993.3 12003.8 12211.9 3259.0 3285.4 3327.6 3371.0 3379.4 107207.0 108470.0 109343.5 109612.0 109786.9 11122.0 11259.3 11311.9 11513.5 11802.0 22799.8 22805.9 22811.8 22886.0 23304.7 7962.0 7996.4 8126.0 8242.0 8323.5 4420.4 4607.8 4627.6 4632.8 4745.8 3572.9 3723.9 3731.3 3735.0 3735.1 558.0 650.6 668.4 690.3 691.6 601.9 676.7 687.2 692.0 704.7 8859.0 9386.6 9537.9 9573.7 9620.0 567.7 644.2 672.9 686.6 690.1 4499.2 4631.9 4632.9 4659.7 4660.5 125008.0 125283.0 127351.4 130498.0 135487.6 135641.3 136802.3 137294.0 138483.0 141637.0 72818.0 83131.8 91039.5 104664.4 114492.0 9780.3 9808.6 9831.7 9843.5 9918.5 60818.0 63439.0 63541.7 65204.0 68225.0 92034.0 99424.5 99642.0 100009.0 101811.0 12582.6 12707.7 12760.7 12967.7 12983.8 14905.3 14978.4 15322.0 15391.3 15630.5 13884.0 13975.4 13984.8 14178.9 14513.2 13941.0 13993.2 14314.4 14582.3 14744.9 13761.2 13880.6 14296.6 14349.9 14398.4 11169.8 11270.9 11282.5 11549.7 12029.6 10759.6 11266.5 11393.8 11485.1 11541.0 20:21:27 INFO - PROCESS | 1656 | -------- Summary: end -------- 20:21:27 INFO - PROCESS | 1656 | 20:21:28 INFO - TEST-INFO | 1656: exit 0 20:21:28 INFO - TEST-OK | dromaeo_css | took 1116179ms 20:21:28 INFO - TEST-START | kraken 20:21:28 INFO - operating with platform_type : mac_ 20:21:28 INFO - Initialising browser for kraken test... 20:21:28 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmp8zvYNA/profile http://localhost:49279/getInfo.html) 20:21:30 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 20:21:30 INFO - PROCESS | 1663 | colorDepth:24 20:21:30 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 20:21:30 INFO - PROCESS | 1663 | __metrics 20:21:30 INFO - PROCESS | 1663 | 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" 20:21:30 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:21:31 INFO - TEST-INFO | 1663: exit 0 20:21:31 INFO - Browser initialized. 20:21:31 INFO - Running cycle 1/1 for kraken test... 20:21:31 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/T/tmp8zvYNA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:21:43 INFO - PROCESS | 1664 | RSS: Main: 245096448 20:21:43 INFO - PROCESS | 1664 | 20:21:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 20:21:47 INFO - PROCESS | 1664 | RSS: Main: 294768640 20:21:47 INFO - PROCESS | 1664 | 20:21:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 20:21:50 INFO - PROCESS | 1664 | RSS: Main: 305307648 20:21:50 INFO - PROCESS | 1664 | 20:21:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 20:21:54 INFO - PROCESS | 1664 | RSS: Main: 390184960 20:21:54 INFO - PROCESS | 1664 | 20:21:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 20:21:57 INFO - PROCESS | 1664 | RSS: Main: 292388864 20:21:57 INFO - PROCESS | 1664 | 20:21:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:21:59 INFO - PROCESS | 1664 | RSS: Main: 296558592 20:21:59 INFO - PROCESS | 1664 | 20:22:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 20:22:03 INFO - PROCESS | 1664 | RSS: Main: 332783616 20:22:03 INFO - PROCESS | 1664 | 20:22:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 20:22:06 INFO - PROCESS | 1664 | RSS: Main: 335523840 20:22:06 INFO - PROCESS | 1664 | 20:22:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 20:22:10 INFO - PROCESS | 1664 | RSS: Main: 331296768 20:22:10 INFO - PROCESS | 1664 | 20:22:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:22:12 INFO - PROCESS | 1664 | RSS: Main: 311840768 20:22:12 INFO - PROCESS | 1664 | 20:22:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:22:15 INFO - PROCESS | 1664 | RSS: Main: 318771200 20:22:15 INFO - PROCESS | 1664 | 20:22:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:22:17 INFO - PROCESS | 1664 | RSS: Main: 316657664 20:22:17 INFO - PROCESS | 1664 | 20:22:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:22:21 INFO - PROCESS | 1664 | RSS: Main: 328278016 20:22:21 INFO - PROCESS | 1664 | 20:22:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:22:25 INFO - PROCESS | 1664 | RSS: Main: 330477568 20:22:25 INFO - PROCESS | 1664 | 20:22:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 20:22:27 INFO - PROCESS | 1664 | RSS: Main: 373878784 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | __start_tp_report 20:22:27 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 20:22:27 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 20:22:27 INFO - PROCESS | 1664 | |i|pagename|runs| 20:22:27 INFO - PROCESS | 1664 | |0;ai-astar;101;112;112;113;111;111;111;107;111;112 20:22:27 INFO - PROCESS | 1664 | |1;audio-beat-detection;141;107;108;158;105;113;156;138;181;106 20:22:27 INFO - PROCESS | 1664 | |2;audio-dft;183;186;182;183;182;183;184;181;183;183 20:22:27 INFO - PROCESS | 1664 | |3;audio-fft;79;74;57;89;73;73;58;66;81;57 20:22:27 INFO - PROCESS | 1664 | |4;audio-oscillator;83;87;96;98;105;100;96;70;73;121 20:22:27 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;88;86;87;86;88;85;88;84;87;87 20:22:27 INFO - PROCESS | 1664 | |6;imaging-darkroom;94;93;94;93;93;115;152;151;93;103 20:22:27 INFO - PROCESS | 1664 | |7;imaging-desaturate;84;86;85;84;84;87;85;83;86;84 20:22:27 INFO - PROCESS | 1664 | |8;json-parse-financial;53;51;51;50;52;54;52;53;51;52 20:22:27 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;75;74;77;72;70;72;71;73;73 20:22:27 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;83;84;84;81;81;83;79;76;83;87 20:22:27 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;136;134;137;138;135;154;180;138;245;152 20:22:27 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;164;167;222;225;164;169;163;161;162;161 20:22:27 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;64;64;64;66;67;68;67;67;66;66 20:22:27 INFO - PROCESS | 1664 | __end_tp_report 20:22:27 INFO - PROCESS | 1664 | __start_cc_report 20:22:27 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2070 20:22:27 INFO - PROCESS | 1664 | __end_cc_report 20:22:27 INFO - PROCESS | 1664 | __startTimestamp1468984947029__endTimestamp 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | ------- Summary: start ------- 20:22:27 INFO - PROCESS | 1664 | Number of tests: 14 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.50 stddev:3.57 (3.2%) stddev-sans-first:1.69 20:22:27 INFO - PROCESS | 1664 | Values: 101.0 112.0 112.0 113.0 111.0 111.0 111.0 107.0 111.0 112.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:139.50 stddev:27.35 (19.6%) stddev-sans-first:28.78 20:22:27 INFO - PROCESS | 1664 | Values: 141.0 107.0 108.0 158.0 105.0 113.0 156.0 138.0 181.0 106.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.33 (0.7%) stddev-sans-first:1.41 20:22:27 INFO - PROCESS | 1664 | Values: 183.0 186.0 182.0 183.0 182.0 183.0 184.0 181.0 183.0 183.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:70.70 Median:73.50 stddev:11.01 (15.0%) stddev-sans-first:11.26 20:22:27 INFO - PROCESS | 1664 | Values: 79.0 74.0 57.0 89.0 73.0 73.0 58.0 66.0 81.0 57.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:85.00 stddev:15.22 (17.9%) stddev-sans-first:15.72 20:22:27 INFO - PROCESS | 1664 | Values: 83.0 87.0 96.0 98.0 105.0 100.0 96.0 70.0 73.0 121.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:1.35 (1.6%) stddev-sans-first:1.33 20:22:27 INFO - PROCESS | 1664 | Values: 88.0 86.0 87.0 86.0 88.0 85.0 88.0 84.0 87.0 87.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:108.10 Median:93.00 stddev:23.92 (25.7%) stddev-sans-first:24.82 20:22:27 INFO - PROCESS | 1664 | Values: 94.0 93.0 94.0 93.0 93.0 115.0 152.0 151.0 93.0 103.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.23 (1.4%) stddev-sans-first:1.27 20:22:27 INFO - PROCESS | 1664 | Values: 84.0 86.0 85.0 84.0 84.0 87.0 85.0 83.0 86.0 84.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.20 (2.3%) stddev-sans-first:1.20 20:22:27 INFO - PROCESS | 1664 | Values: 53.0 51.0 51.0 50.0 52.0 54.0 52.0 53.0 51.0 52.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.00 stddev:2.98 (4.1%) stddev-sans-first:2.12 20:22:27 INFO - PROCESS | 1664 | Values: 66.0 75.0 74.0 77.0 72.0 70.0 72.0 71.0 73.0 73.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:83.00 stddev:3.03 (3.7%) stddev-sans-first:3.20 20:22:27 INFO - PROCESS | 1664 | Values: 83.0 84.0 84.0 81.0 81.0 83.0 79.0 76.0 83.0 87.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:145.00 stddev:34.70 (23.9%) stddev-sans-first:36.13 20:22:27 INFO - PROCESS | 1664 | Values: 136.0 134.0 137.0 138.0 135.0 154.0 180.0 138.0 245.0 152.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:165.50 stddev:25.28 (15.3%) stddev-sans-first:26.45 20:22:27 INFO - PROCESS | 1664 | Values: 164.0 167.0 222.0 225.0 164.0 169.0 163.0 161.0 162.0 161.0 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.50 stddev:1.45 (2.2%) stddev-sans-first:1.36 20:22:27 INFO - PROCESS | 1664 | Values: 64.0 64.0 64.0 66.0 67.0 68.0 67.0 67.0 66.0 66.0 20:22:27 INFO - PROCESS | 1664 | -------- Summary: end -------- 20:22:27 INFO - PROCESS | 1664 | 20:22:27 INFO - TEST-INFO | 1664: exit 0 20:22:27 INFO - TEST-OK | kraken | took 59500ms 20:22:27 INFO - SUITE-END | took 1175s 20:22:28 INFO - Completed test suite (00:19:36) 20:22:28 INFO - javascript benchmark 20:22:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2933.3106719920816, 3037.756979441645, 3426.5888093428794, 3440.6559344065595, 3444.1389652586854, 5036.3200967869325, 5703.68629725366, 5747.741351639176, 5879.147523609074, 5895.646261224324, 5745.827625171251, 5846.473817356437, 5855.590108692382, 5866.853328666785, 5894.764209431628, 5251.55361794248, 5257.23770053342, 5306.495882891119, 5352.892942141174, 5396.973015134919, 5400.702961337125, 5449.427810079944, 5461.726913654321, 5469.097598896197, 5489.615726899118, 5244.318238628973, 5310.62825602208, 5314.893702125936, 5362.544183744387, 5378.058839703036, 4210.473690788652, 5272.209168624699, 5284.524392804666, 5307.973460132714, 5333.306670132897, 5292.57659387252, 5303.973480132613, 5316.0, 5328.280682107905, 5401.3248343957, 5705.315362156526, 5840.3575606683235, 5899.085641725539, 5922.230110085704, 5943.583949123563, 6254.249490061222, 6300.5589608727405, 6343.080253363282, 6361.872762544769, 6366.71349789256, 6288.811335659938, 6314.968425157891, 6317.305096439388, 6322.71547780351, 6326.5255105867245, 5832.416758324219, 6038.849028774272, 6091.817245482643, 6093.025115981466, 6093.969530152365, 6249.937500624936, 6261.874762504815, 6309.400606942333, 6313.9368606314265, 6371.840703982438, 6229.657368844757, 6307.684615769193, 6310.495160387158, 6315.273743519554, 6342.460890824332, 6238.688065596702, 6314.684265786692, 6337.081123237105, 6355.491560675135, 6371.96814015927, 6262.780802671976, 6322.589031712924, 6340.873182536324, 6360.427561519486, 6394.456471199907, 6350.936490635035, 6352.65060421672, 6368.458681012074, 6375.553711240262, 6419.133416988647, 4959.876003099951, 5006.148954677714, 5097.974510127426, 5102.08162530748, 5146.794128234828, 4547.613452856478, 4555.589996900295, 4604.493505714368, 4608.792604332813, 4622.583967442946, 5943.791967281124, 5945.078512830518, 5949.851253718692, 5951.553633477506, 6013.30846952606, 6196.287426945869, 6232.376762323732, 6244.056894500217, 6265.561410701207, 6315.5263355247625, 5000.74996250186, 5867.970660146672, 5938.138969849348, 5938.8812223755285, 5963.940360596425, 5539.169124631338, 5549.889002219933, 5554.750036248379, 5560.471755183251, 5560.805371811967, 6223.813285601349, 6326.525510586816, 6378.362163783585, 6391.872162556723, 6392.041193820871, 6274.937250627435, 6298.370162983665, 6307.558470907176, 6316.115743795965, 6319.24169099711, 6437.388448097423, 6443.355664433707, 6444.420002199825, 6446.774362897276, 6447.2263328400895, 6105.914670190187, 6223.097650840512, 6261.279952805486, 6299.338569450325, 6338.366163383809, 6279.152314437492, 6312.652804095819, 6318.0, 6333.113364128933, 6365.809025729235, 6191.628502289697, 6282.560220784501, 6286.5599408041, 6289.213848268966, 6295.527835412454, 4973.577245933991, 4975.50244975514, 6194.163787888758, 6223.159873417212, 6280.937190628218, 6128.8774224516155, 6140.232470941132, 6140.846478838065, 6143.723532440961, 6161.4146656067605, 6050.062240352752, 6277.089821975879, 6277.937220627735, 6278.623282603058, 6282.780102696383, 6022.759089636541, 6082.422169893853, 6111.470193407285, 6126.754929802936, 6152.323244443197], "value": 5794.67933119604, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.8124490826126, 504.4123596010648, 513.1737901977815, 521.1219095823537, 523.001067960196, 31473.842630786843, 41954.0, 42339.364909526346, 42452.78773606136, 42456.0, 44695.0, 44955.32567011497, 44970.0, 45125.54874451263, 45212.547874521246, 8613.019005628708, 9680.177184939272, 9858.556364963593, 9867.802643947116, 9891.060349266809, 8643.956780216122, 11825.231359961575, 11978.221415607957, 12016.158868879182, 12364.938175309157, 11955.163138580303, 12004.039676825876, 12019.879801201963, 12023.21849079807, 12069.698257543589, 8750.34372422071, 11669.416529173508, 11819.172657913907, 12079.818802717966, 12090.274583525017, 10002.699919002442, 11681.707957301136, 11864.52541898323, 11947.581834635834, 11949.880501194964, 33958.83020584906, 42342.0, 42408.57591424077, 42496.3625545617, 42581.57418425807, 3088.922776930572, 3579.83890724918, 3684.705223582107, 3878.7478813877005, 3935.704822138351, 32159.839200803846, 32775.836120819244, 33077.00768976934, 33126.503102453484, 33137.3372532548, 3090.4900691385897, 3146.921326966821, 3440.587129544446, 3450.551428314328, 3451.775634583744, 520342.3982880099, 520500.3974980101, 523116.3844180755, 523851.0, 524850.9999999962, 65104.34895650983, 65266.347336526975, 65755.34244657587, 65760.684786304, 65932.34067659358, 34995.0, 67513.32486675169, 68527.0, 68688.0, 68696.65651671709, 16087.034777913363, 17030.57423564421, 17050.658986820195, 17058.402955896483, 17112.657746844994, 11684.88315116838, 11866.821997670042, 12897.968162546973, 12900.258465107278, 13087.541936032194, 15576.766348504781, 16344.591385215464, 19497.60169128117, 19516.70724939127, 20058.39824805258, 6999.860002800017, 7669.5398276103615, 7878.881816772753, 8026.678932842621, 8037.196280372033, 718.6299055986171, 988.7972965542046, 1045.6706137566641, 1047.779966207105, 1071.2982996137541, 25945.351366215997, 26310.736892631212, 26958.460830783668, 27677.540307461568, 27739.167824965283, 1041.390786389955, 1075.8063548561333, 1096.9122470202365, 1114.1309778372881, 1124.8425220469142, 14868.851311486964, 14965.850341496664, 15002.24988750558, 15155.242237888282, 15223.0, 676965.0, 679573.0, 679718.0, 684772.0, 686048.0, 60179.699101504215, 62304.68847655733, 62366.75266494645, 62583.37416625866, 62607.37392626107, 1263.2988691276246, 1278.7186818899827, 1371.3760239091189, 1408.901376903607, 1452.0924422236103, 25222.847144106985, 26361.60457593176, 26635.33411664724, 27657.446851062465, 27817.0, 52522.0, 53165.99999999845, 53214.4678553225, 53304.0, 53547.92904141973, 514108.4294578653, 524476.0, 526185.0, 528254.0, 533627.0, 23815.0, 23870.403239919142, 23923.40191495157, 25091.0, 26945.326366840203, 40985.38521922114, 41340.06343844104, 43205.56794432141, 51167.0, 53144.0, 40712.79643601881, 41720.58279417167, 41896.95257618584, 42107.789461052496, 42603.5739642612, 39245.60754392534, 40647.1870562593, 41037.79481102695, 42063.36904946367, 42172.0, 39326.213475730896, 39468.40797387984, 39709.0, 39930.60069399269, 40315.798421007705, 34351.0, 35846.82076589705, 36050.27899442049, 36093.63906360903, 36404.45393319155, 17780.288788448834, 21067.789322107197, 21901.0, 22138.225162119248, 22331.77668223297], "value": 20506.454000274825, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.25633937748836, 379.96580307772297, 387.1849756263066, 387.46723255523636, 413.2272650144231, 15544.145072021034, 16526.586835329108, 20164.49588760278, 20505.589888202227, 20513.794862051374, 15908.52274431766, 19511.90244048778, 20183.49541261469, 20200.39398818034, 20280.695789563168, 4111.588841115879, 5788.42115788421, 5855.180274761516, 5888.882222355551, 5896.262967129109, 6261.467775239087, 6668.76659316924, 6669.766558170465, 6671.466282697397, 6671.733130674791, 5464.836054918359, 6558.278589355167, 6597.0, 6690.7323707051655, 6695.598264104145, 6398.520110991648, 6613.470922326226, 6648.900266496006, 6649.567778094409, 6653.268140504565, 5747.885042299131, 5784.1902133701315, 5784.3926387729525, 5788.565857560658, 5790.305163380379, 24183.395415114763, 24448.266552003468, 24691.50616987668, 24791.5041699165, 24796.876015619808, 2264.682944387787, 2495.6880389951257, 2538.9876880984953, 2906.346072133774, 3248.1067706380936, 14775.335109920079, 15305.770413443808, 15308.693826123526, 15799.684006319809, 16105.677886442205, 1807.2951548895942, 1816.2916462579606, 1891.4042076745918, 1919.7120431935182, 1925.0, 104045.0, 113132.8473571924, 113696.0, 115028.6734229617, 117648.0, 11102.555897764161, 11478.94260528692, 11497.425128743529, 11557.479913403917, 11717.88282117185, 13940.302984850718, 14008.649783755487, 14034.578962631138, 14036.368363423671, 14052.805511531635, 8475.32197424217, 8497.235248827636, 8502.78743031429, 8544.017437994586, 8620.267277281502, 8612.78468038304, 8615.956920215358, 8618.864699129794, 8718.782030449163, 8742.344324175712, 4444.000099977544, 4460.598546130799, 4466.977665111719, 4483.2826747720865, 4524.524924882906, 1755.1044871538707, 1889.8110188980993, 1939.527102943758, 1948.756405449319, 1968.6259610674213, 704.7920863345325, 724.6014691919423, 775.4068137874531, 873.5763154869883, 983.5082458770614, 20580.588388232452, 20969.370918872457, 21644.4588885276, 21664.34634212064, 21772.56454870894, 909.077286554148, 925.9629614815332, 976.6972238606055, 976.8925418203992, 997.680742162501, 10135.645252416278, 11039.006489415991, 11189.72025699364, 11481.36852473122, 11601.245919015237, 509152.0, 528744.0, 530506.0, 530689.0, 553832.0, 121229.39385303017, 121471.78528214782, 122622.0, 123194.0, 127126.0, 980.1668581705493, 996.3523709588825, 1009.3489699144158, 1010.3230835340339, 1012.8581998520214, 18301.63396732031, 18702.251909923452, 18722.531936701693, 18823.34118305853, 18967.810321896606, 54925.45074549203, 56611.43388566226, 57151.0, 57257.42742572688, 57334.7133264331, 73010.0, 74594.44872012566, 79534.60232699031, 82865.0, 82979.58510207411, 8027.237412445808, 8040.276375126266, 8126.878096828434, 8147.14454982242, 8365.62354694028, 12159.148859579744, 12738.87261127377, 12766.42551085222, 13052.282124483245, 13151.671208219872, 11057.834132487858, 11151.72120696989, 11404.82892756626, 11421.086313094766, 11428.657140285804, 11109.222354435113, 11156.832647510457, 11198.664040078811, 11449.313041217554, 11484.540618375171, 10876.652420236494, 11491.54033838637, 11499.195056345976, 11585.6524304271, 11719.88280117222, 7999.200079991954, 8170.591470426574, 8185.0996390398195, 8202.015758108826, 8365.372597055124, 5413.350397952271, 7124.643767811693, 7342.8898566520475, 11127.443627818739, 11147.721306967067], "value": 9653.439275883964, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1313.3564553368851, 1576.771368151618, 1678.16091954023, 1854.9443516694505, 1868.7944326124125, 8481.575921203937, 8665.740027799176, 8825.205731484166, 8846.601902914368, 8856.557172141383, 7848.529088254709, 8072.313853322475, 8480.872786908201, 8722.738317850459, 8732.607032683529, 2358.9646155307682, 2387.1525608409024, 2389.486260454002, 2392.4138586046356, 2393.0786647140803, 2128.797764212405, 2347.753485883983, 2348.1077190667525, 2396.664466974625, 2398.052769156191, 1940.7282980382759, 2153.536989547255, 2298.425393651587, 2367.7987371073477, 2368.005437716163, 2344.0740907341674, 2360.4688944987324, 2369.822263330257, 2372.3001714494267, 2373.3236027732187, 2342.2153578551206, 2356.905723771047, 2358.0, 2365.6451532270125, 2370.751071137523, 8679.132086791333, 8796.516191609458, 8806.691765788166, 8820.0, 8829.735107946772, 5242.9213561796605, 5403.13549832025, 5406.02691515527, 5407.5133238008375, 5408.59435542332, 3517.8416971236356, 3606.765560238576, 3658.8353524091212, 3668.1196512836727, 3671.7796932184156, 1072.1797824664138, 1086.1528008153653, 1093.1691914145226, 1110.566878917223, 1111.183280288987, 11697.23967942081, 11788.941055294754, 11798.823017654742, 11799.94100029503, 11808.881911180864, 3574.873914716856, 3853.9421908671393, 4755.690880092783, 4758.619310455155, 4774.331593576902, 10024.79950400988, 10056.0, 10060.496975151213, 10077.54651040705, 10093.899061009295, 5360.65155764874, 5366.409694933554, 5376.542993845567, 5377.838664840061, 5381.838544843583, 4796.496367881392, 4834.395700537433, 4913.6069114471475, 4915.631327650441, 4919.139150648693, 3922.921541569153, 3946.3488524393156, 3992.84028638857, 4027.4160246764054, 4029.0330320722815, 1184.7156682396162, 1226.4297101847683, 1229.3115855121162, 1265.563789704833, 1275.7831168701157, 689.2315068698465, 872.842888280103, 923.3628796130648, 942.5146049784366, 959.2805395953035, 5887.499562537147, 5897.439743224387, 5971.731272092767, 6044.703012855087, 6121.418465245794, 826.277007618334, 909.1953621045291, 930.1489137439303, 941.4163218804248, 979.2117345536911, 3436.1753179236803, 3919.9608003920166, 3964.345882929342, 4062.0251139726843, 4065.0, 10351.223658225663, 10479.633212837514, 10637.9468102659, 10645.893541064645, 10646.627368042082, 9099.772505687279, 9802.852957205647, 9815.067568580973, 10082.445465499468, 10528.578856845641, 7424.83892292964, 7519.360854327443, 7874.645640946057, 8223.835523289621, 8394.454360466672, 5543.113101903838, 5777.480026797609, 5997.970010150096, 6179.783707570214, 6270.216222972129, 9196.862047069166, 9732.172765314745, 10102.747431314276, 10116.393016419039, 10123.493825308558, 10356.741081473023, 10394.324368915995, 10399.96000399954, 10403.271770976262, 10415.94792026035, 7387.630618469163, 7397.704091836266, 7469.663865125974, 7475.588842613491, 7492.925070749223, 7987.16134805837, 8162.591870406337, 8184.713535026245, 8213.917860821315, 8237.093919668712, 7266.2007179211305, 7481.7755467336065, 7489.325960663567, 7634.923650763643, 7640.617969101411, 7365.373943214887, 7365.631718414165, 7418.295261950106, 7492.925070749223, 7513.8872916907385, 6584.637844918576, 6843.213030501556, 6850.0409942607075, 6898.413634840894, 6904.827379315558, 4913.778879950482, 4933.9506604933495, 4960.603151747924, 4962.106820772296, 5026.070177017264, 5031.227969139343, 5071.492850714899, 5096.337476128079, 5180.870478237924, 5198.298229738937], "value": 4570.021844339371, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.55524379162625, 304.1332203220821, 311.2902582112782, 312.4828408983134, 316.6754077071001, 5341.009034940436, 5378.838634840956, 5403.540699040582, 5429.53848922842, 5436.646617969829, 5310.283111779909, 5310.681359118446, 5313.3092697949305, 5336.573074154068, 5338.946610533893, 3119.3293441909927, 3953.5255769307723, 4035.515738111431, 4071.620554939393, 4144.191882582898, 3368.7641865069454, 3467.6358982306874, 4025.0943537704075, 4085.5097388313297, 4091.672666186713, 3583.605803361628, 4068.613481719232, 4106.8973275668195, 4117.258893399187, 4139.751614903101, 3562.412201986669, 3728.5339332583426, 3751.3247615429214, 3952.4268980997595, 4102.220578090183, 2947.896823611163, 3559.181388280684, 3566.964330356689, 3573.356795776759, 3579.7315201359743, 5196.506331898464, 5200.401953775288, 5200.427952925175, 5220.634555581111, 5253.737313134367, 4443.733375997418, 4648.953510464886, 4657.836975705868, 4693.647976401783, 4707.6233901287815, 5560.86097847553, 5586.497215250647, 5620.4098569650005, 5632.633878797906, 5653.745581448845, 3454.808091208529, 3923.98038009811, 3930.862419815293, 3943.5859234780223, 3959.4852669152824, 5168.811173430095, 5231.999999999962, 5626.859328516779, 5730.111832665943, 5782.45066718661, 3155.684431556826, 3980.7810570418324, 3982.8605998789903, 4002.6197511236387, 4014.959850401517, 3397.4564069749217, 4053.148838743838, 4089.345704687236, 4098.282800509899, 4107.199096176217, 4440.557693268689, 4491.281394976853, 4680.3447517347595, 4707.929381059287, 4719.027012981799, 4478.955210447919, 4998.450170481244, 5567.635929197372, 5637.3235211774845, 5655.660660360391, 1079.1744315598573, 1310.4103153580927, 1365.7951307303786, 1368.7270838120444, 1389.2220356600337, 720.4128635162326, 853.8036251662153, 856.4090777363599, 870.7300736771494, 897.4166791585391, 3981.7810020448574, 4338.544452832471, 4569.54304569547, 4580.862574122715, 5061.342025536656, 5835.35811060783, 5847.619904706181, 5856.648601083949, 5859.619124756877, 5866.6186697864505, 4346.782660866944, 4432.711873728262, 4932.728699921467, 5299.44355842631, 5331.280277162534, 2565.781908537795, 2843.5876797864194, 3004.7596192304563, 3030.4848175809725, 3047.5828739636177, 4845.5881250094135, 5865.941340586625, 5889.970550147307, 5911.965406053923, 5961.046232602849, 5284.894302114013, 5645.9717701412055, 5717.456841600041, 5909.881802363929, 5955.642661440327, 5068.113080210948, 5142.97428512855, 5383.488568585978, 5565.387807341189, 5616.438356164433, 4805.447373551947, 5021.723805190603, 5663.799953026527, 5713.457221563945, 5839.854003649943, 4789.4971028041555, 4921.434035085867, 5084.906745049832, 5751.4536119068625, 5780.335261444988, 4901.460839307602, 4949.628777841748, 5673.517750991047, 5847.18737191472, 5879.794207202727, 3470.9521382384514, 3536.275063612005, 3726.4782930389224, 3886.3587508061983, 3896.941545876871, 5481.095619222783, 5812.622179558315, 5821.970890145522, 5862.772146584271, 5873.2658417697785, 4906.435759887659, 5334.453008627454, 5734.541236701138, 5771.567132465166, 5775.33583637887, 5046.788770694933, 5116.258142569232, 5235.267062611314, 5616.663000219836, 5630.971845140748, 5003.799848006184, 5070.340855688737, 5528.9723551381985, 5571.771291483277, 5572.609917305912, 5217.0870097733505, 5482.5888058394985, 5555.50000499957, 5634.859128521656, 5676.375598684224, 5452.318460192476, 5492.258545096361, 5511.04357952921, 5523.116301391758, 5524.226608274765], "value": 4060.831369676525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.7130719019583, 742.4246209187883, 779.1662920674877, 798.7044793426433, 824.9298809601186, 11328.886711132885, 15871.603209919758, 15911.283992220364, 15925.681486370266, 15929.52211433656, 13919.582412527616, 14344.067635603678, 14448.711025779477, 14510.129392236473, 14599.854001459982, 862.482510493705, 870.0342619692136, 870.277669534285, 870.3341943413294, 871.4901782457271, 714.3035540348171, 715.964201789911, 718.9209186989427, 729.3253740290237, 732.5458215906145, 744.2892038103598, 904.2856143646511, 933.3093510802025, 954.1222075690381, 954.2699834626517, 929.4609126706493, 930.9627614895396, 931.5249222896372, 931.6552875436098, 933.3000249812641, 549.5164255455214, 682.4540367705865, 683.852971611106, 684.736376495048, 689.0525527399825, 14559.199244041574, 14995.400183992737, 15016.174110423923, 15020.774688379683, 15031.699366012728, 14349.0, 14743.262836858114, 14796.48212312564, 14959.551213463614, 15011.924940375227, 11929.522819087313, 11961.880381196164, 11993.340366279852, 12003.819942700866, 12211.877881221162, 3259.0385836178384, 3285.3593549257903, 3327.633960264369, 3371.0, 3379.425497665403, 107207.0, 108470.0, 109343.45328273308, 109612.0, 109786.90213097847, 11122.0, 11259.324440533594, 11311.943440282746, 11513.481893314822, 11802.0, 22799.772002279766, 22805.88597057004, 22811.771882280966, 22886.0, 23304.65043024356, 7961.960190199012, 7996.360291176692, 8125.95937020323, 8241.95879020601, 8323.458975166595, 4420.425344705168, 4607.792649330788, 4627.629789616823, 4632.768361581908, 4745.83389581363, 3572.910677233038, 3723.888283351504, 3731.272401881607, 3734.9915522808687, 3735.1409175889153, 557.9665220086807, 650.6226388694546, 668.3750693101965, 690.2993461636443, 691.6230654293422, 601.879624075178, 676.7326905872153, 687.2337343861558, 691.993080069203, 704.7322017633369, 8859.0, 9386.624535018524, 9537.856932146024, 9573.712788616353, 9620.0, 567.7246535430313, 644.1883227133751, 672.8889733193968, 686.6086330791401, 690.1304356510833, 4499.235130027903, 4631.86519302773, 4632.907341853212, 4659.720416774937, 4660.46404663468, 125008.0, 125283.0, 127351.36324318504, 130498.0, 135487.6451235475, 135641.3217933904, 136802.31598841943, 137294.0, 138483.0, 141637.0, 72818.0, 83131.75302370469, 91039.54480227688, 104664.43003354956, 114492.0, 9780.266480013885, 9808.607655693977, 9831.655892043744, 9843.458609776531, 9918.504074796087, 60818.0, 63439.0, 63541.72916541736, 65204.0, 68225.0, 92034.0, 99424.50287748805, 99642.0, 100009.0, 101810.98189018015, 12582.559610413591, 12707.682307942376, 12760.6809829755, 12967.740645187232, 12983.805242921553, 14905.329260183535, 14978.400863965755, 15322.0, 15391.307391167647, 15630.45293414725, 13884.0, 13975.440982360591, 13984.844941762885, 14178.858211418166, 14513.201773902536, 13941.0, 13993.163828255801, 14314.355853986388, 14582.343794529059, 14744.926275368554, 13761.243131627456, 13880.583582492542, 14296.57110286693, 14349.856501434851, 14398.42406303736, 11169.832452513056, 11270.94364528172, 11282.548698051985, 11549.690181125121, 12029.578964736542, 10759.569617215224, 11266.493007814832, 11393.772124557298, 11485.138614603771, 11541.0], "value": 7878.205464831174, "unit": "score"}], "name": "dromaeo_css", "value": 7426.228642200712, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 112.0, 113.0, 111.0, 111.0, 111.0, 107.0, 111.0, 112.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 107.0, 108.0, 158.0, 105.0, 113.0, 156.0, 138.0, 181.0, 106.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 182.0, 183.0, 182.0, 183.0, 184.0, 181.0, 183.0, 183.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 57.0, 89.0, 73.0, 73.0, 58.0, 66.0, 81.0, 57.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 96.0, 98.0, 105.0, 100.0, 96.0, 70.0, 73.0, 121.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 86.0, 88.0, 85.0, 88.0, 84.0, 87.0, 87.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 115.0, 152.0, 151.0, 93.0, 103.0], "value": 108.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 84.0, 84.0, 87.0, 85.0, 83.0, 86.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 51.0, 50.0, 52.0, 54.0, 52.0, 53.0, 51.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 74.0, 77.0, 72.0, 70.0, 72.0, 71.0, 73.0, 73.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 81.0, 81.0, 83.0, 79.0, 76.0, 83.0, 87.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 137.0, 138.0, 135.0, 154.0, 180.0, 138.0, 245.0, 152.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 222.0, 225.0, 164.0, 169.0, 163.0, 161.0, 162.0, 161.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 66.0, 67.0, 68.0, 67.0, 67.0, 66.0, 66.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1470.4, "alertThreshold": 2.0}]} 20:22:28 INFO - Return code: 0 20:22:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:22:28 INFO - # TBPL SUCCESS # 20:22:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:22:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:22:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:22:28 INFO - 2016-07-19 20:22:28.356 screenresolution[1668:9788] starting screenresolution argv=screenresolution get 20:22:28 INFO - 2016-07-19 20:22:28.358 screenresolution[1668:9788] Display 0: 1600x1200x32@60 20:22:28 INFO - 2016-07-19 20:22:28.364 screenresolution[1669:9791] starting screenresolution argv=screenresolution list 20:22:28 INFO - Available Modes on Display 0 20:22:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:22:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:22:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:22:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:22:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:22:28 INFO - Intel Iris: 20:22:28 INFO - Chipset Model: Intel Iris 20:22:28 INFO - Type: GPU 20:22:28 INFO - Bus: Built-In 20:22:28 INFO - VRAM (Dynamic, Max): 1536 MB 20:22:28 INFO - Vendor: Intel (0x8086) 20:22:28 INFO - Device ID: 0x0a2e 20:22:28 INFO - Revision ID: 0x0009 20:22:28 INFO - Displays: 20:22:28 INFO - Display: 20:22:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:22:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:22:28 INFO - Display Serial Number: 1600x1200 60 20:22:28 INFO - Main Display: Yes 20:22:28 INFO - Mirror: Off 20:22:28 INFO - Online: Yes 20:22:28 INFO - Rotation: Supported 20:22:28 INFO - Return code: 0 20:22:28 INFO - Running post-action listener: _resource_record_post_action 20:22:28 INFO - ##### 20:22:28 INFO - ##### Finished run-tests step (success) 20:22:28 INFO - ##### 20:22:28 INFO - Running post-run listener: _resource_record_post_run 20:22:28 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 31374848; Write bytes: 704396800; Read time: 193; Write time: 2028 20:22:28 INFO - TinderboxPrint: CPU usage
53.4% 20:22:28 INFO - TinderboxPrint: I/O read bytes / time
31,374,848 / 193 20:22:28 INFO - TinderboxPrint: I/O write bytes / time
704,396,800 / 2,028 20:22:28 INFO - TinderboxPrint: CPU idle
2,258.1 (46.6%) 20:22:28 INFO - TinderboxPrint: CPU system
966.9 (20.0%) 20:22:28 INFO - TinderboxPrint: CPU user
1,616.6 (33.4%) 20:22:28 INFO - TinderboxPrint: Swap in / out
435,118,080 / 0 20:22:28 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403622400; Write bytes: 395982848; Read time: 29658; Write time: 866 20:22:28 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29310464; Write bytes: 292611584; Read time: 175; Write time: 1120 20:22:29 INFO - Running post-run listener: _upload_blobber_files 20:22:29 INFO - Blob upload gear active. 20:22:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:22:29 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 20:22:29 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'] 20:22:29 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 20:22:29 INFO - (blobuploader) - INFO - Open directory for files ... 20:22:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:22:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:22:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:22:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:22:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:22:30 INFO - (blobuploader) - INFO - Done attempting. 20:22:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:22:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:22:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:22:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:22:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:22:31 INFO - (blobuploader) - INFO - Done attempting. 20:22:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:22:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:22:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:22:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:22:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:22:34 INFO - (blobuploader) - INFO - Done attempting. 20:22:34 INFO - (blobuploader) - INFO - Iteration through files over. 20:22:34 INFO - Return code: 0 20:22:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:22:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:22:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f24fdee3d7c550ed5b57c426f07d1a69e7490b38e5fa6589658bea259cba86fcc605dfa317c9caacd35495c85396711606de200a75d6e31d2e92b3e6e852f9cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fe9c60c92fa7aca98b602e1a7e53a4f1aee638f6da81c1b5a972e70af67070e2b7326f247aa6a652ca2bb4069d9e8e8a743fa3cec0a047b77fcbbe370d88f67f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94334c8005d7cb7b4a24ca52ab5bb88a81ef9bc79b0911513302ceb668debff49d43694aba71f0505d4b04ef5774b3a65327f9d8b983713d1bb8fc1aafb5f365"} 20:22:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:22:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:22:34 INFO - Contents: 20:22:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f24fdee3d7c550ed5b57c426f07d1a69e7490b38e5fa6589658bea259cba86fcc605dfa317c9caacd35495c85396711606de200a75d6e31d2e92b3e6e852f9cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fe9c60c92fa7aca98b602e1a7e53a4f1aee638f6da81c1b5a972e70af67070e2b7326f247aa6a652ca2bb4069d9e8e8a743fa3cec0a047b77fcbbe370d88f67f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94334c8005d7cb7b4a24ca52ab5bb88a81ef9bc79b0911513302ceb668debff49d43694aba71f0505d4b04ef5774b3a65327f9d8b983713d1bb8fc1aafb5f365"} 20:22:34 INFO - Running post-run listener: copy_logs_to_upload_dir 20:22:34 INFO - Copying logs to upload dir... 20:22:34 INFO - mkdir: /builds/slave/test/build/upload/logs 20:22:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.551032 ========= master_lag: 1.23 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-07-19 20:22:35.835825) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 20:22:35.838951) ========= 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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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/f24fdee3d7c550ed5b57c426f07d1a69e7490b38e5fa6589658bea259cba86fcc605dfa317c9caacd35495c85396711606de200a75d6e31d2e92b3e6e852f9cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fe9c60c92fa7aca98b602e1a7e53a4f1aee638f6da81c1b5a972e70af67070e2b7326f247aa6a652ca2bb4069d9e8e8a743fa3cec0a047b77fcbbe370d88f67f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94334c8005d7cb7b4a24ca52ab5bb88a81ef9bc79b0911513302ceb668debff49d43694aba71f0505d4b04ef5774b3a65327f9d8b983713d1bb8fc1aafb5f365"} build_url:https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010795 build_url: 'https://queue.taskcluster.net/v1/task/LYvxBkYeSly_vWsA1VN1jg/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/f24fdee3d7c550ed5b57c426f07d1a69e7490b38e5fa6589658bea259cba86fcc605dfa317c9caacd35495c85396711606de200a75d6e31d2e92b3e6e852f9cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fe9c60c92fa7aca98b602e1a7e53a4f1aee638f6da81c1b5a972e70af67070e2b7326f247aa6a652ca2bb4069d9e8e8a743fa3cec0a047b77fcbbe370d88f67f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/94334c8005d7cb7b4a24ca52ab5bb88a81ef9bc79b0911513302ceb668debff49d43694aba71f0505d4b04ef5774b3a65327f9d8b983713d1bb8fc1aafb5f365"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 20:22:35.875621) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:22:35.875974) ========= 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.62OAsxcCqe/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.bJ9ecPCdCB/Listeners TMPDIR=/var/folders/s_/1z479xxn5mnd2jsf1r01_wr800000w/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.005053 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 20:22:35.925283) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 20:22:35.928670) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 20:22:35.929078) ========= ========= Total master_lag: 1.37 =========