builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0155 starttime: 1468296618.67 results: success (0) buildid: 20160711201325 builduid: 5604e4b1882d4a0e8d2321dc7267771d revision: 9b1032ae8fac9bec0f8232ad6594dfe1467ef1f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.675034) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.675473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.675764) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.702493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.702847) ========= 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.3DLB20tE0s/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.Vg515ZAij6/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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-11 21:10:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.5M=0.001s 2016-07-11 21:10:18 (11.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089022 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.814117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.814423) ========= 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.3DLB20tE0s/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.Vg515ZAij6/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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.027857 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:18.864542) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 21:10:18.864899) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9b1032ae8fac9bec0f8232ad6594dfe1467ef1f6 --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 9b1032ae8fac9bec0f8232ad6594dfe1467ef1f6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3DLB20tE0s/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.Vg515ZAij6/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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-11 21:10:19,297 truncating revision to first 12 chars 2016-07-11 21:10:19,297 Setting DEBUG logging. 2016-07-11 21:10:19,297 attempt 1/10 2016-07-11 21:10:19,297 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9b1032ae8fac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 21:10:20,038 unpacking tar archive at: autoland-9b1032ae8fac/testing/mozharness/ program finished with exit code 0 elapsedTime=0.991696 ========= master_lag: 0.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 21:10:20.234159) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:20.234460) ========= script_repo_revision: 9b1032ae8fac9bec0f8232ad6594dfe1467ef1f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:20.234876) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:20.235140) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 21:10:20.253363) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-11 21:10:20.253637) ========= /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.3DLB20tE0s/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.Vg515ZAij6/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:10:20 INFO - MultiFileLogger online at 20160711 21:10:20 in /builds/slave/test 21:10:20 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 21:10:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:10:20 INFO - {'append_to_log': False, 21:10:20 INFO - 'base_work_dir': '/builds/slave/test', 21:10:20 INFO - 'blob_upload_branch': 'Autoland', 21:10:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:10:20 INFO - 'branch': 'Autoland', 21:10:20 INFO - 'buildbot_json_path': 'buildprops.json', 21:10:20 INFO - 'config_files': ('talos/mac_config.py',), 21:10:20 INFO - 'default_actions': ('clobber', 21:10:20 INFO - 'read-buildbot-config', 21:10:20 INFO - 'download-and-extract', 21:10:20 INFO - 'populate-webroot', 21:10:20 INFO - 'create-virtualenv', 21:10:20 INFO - 'install', 21:10:20 INFO - 'run-tests'), 21:10:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:10:20 INFO - 'download_minidump_stackwalk': True, 21:10:20 INFO - 'download_symbols': 'ondemand', 21:10:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:10:20 INFO - 'tooltool.py': '/tools/tooltool.py', 21:10:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:10:20 INFO - '/tools/misc-python/virtualenv.py')}, 21:10:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:10:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:10:20 INFO - 'installer_path': 'installer.exe', 21:10:20 INFO - 'log_level': 'info', 21:10:20 INFO - 'log_name': 'talos', 21:10:20 INFO - 'log_to_console': True, 21:10:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:10:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:10:20 INFO - 'opt_config_files': (), 21:10:20 INFO - 'pip_index': False, 21:10:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:10:20 INFO - 'cmd': ('bash', 21:10:20 INFO - '-c', 21:10:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:10:20 INFO - 'enabled': True, 21:10:20 INFO - 'halt_on_failure': False, 21:10:20 INFO - 'name': 'check_screen_resolution'},), 21:10:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:10:20 INFO - 'cmd': ('bash', 21:10:20 INFO - '-c', 21:10:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:10:20 INFO - 'enabled': True, 21:10:20 INFO - 'halt_on_failure': False, 21:10:20 INFO - 'name': 'check_screen_resolution'},), 21:10:20 INFO - 'run_cmd_checks_enabled': True, 21:10:20 INFO - 'sps_profile': False, 21:10:20 INFO - 'sps_profile_interval': 0, 21:10:20 INFO - 'suite': 'dromaeojs', 21:10:20 INFO - 'system_bits': '32', 21:10:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:10:20 INFO - 'title': 't-yosemite-r7-0155', 21:10:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:10:20 INFO - 'use_talos_json': True, 21:10:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:10:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:10:20 INFO - 'work_dir': 'build'} 21:10:20 INFO - ##### 21:10:20 INFO - ##### Running clobber step. 21:10:20 INFO - ##### 21:10:20 INFO - Running pre-action listener: _resource_record_pre_action 21:10:20 INFO - Running main action method: clobber 21:10:20 INFO - rmtree: /builds/slave/test/build 21:10:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:10:22 INFO - Running post-action listener: _resource_record_post_action 21:10:22 INFO - ##### 21:10:22 INFO - ##### Finished clobber step (success) 21:10:22 INFO - ##### 21:10:22 INFO - ##### 21:10:22 INFO - ##### Running read-buildbot-config step. 21:10:22 INFO - ##### 21:10:22 INFO - Running pre-action listener: _resource_record_pre_action 21:10:22 INFO - Running main action method: read_buildbot_config 21:10:22 INFO - Using buildbot properties: 21:10:22 INFO - { 21:10:22 INFO - "project": "", 21:10:22 INFO - "product": "firefox", 21:10:22 INFO - "script_repo_revision": "production", 21:10:22 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 21:10:22 INFO - "repository": "", 21:10:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 21:10:22 INFO - "buildid": "20160711201325", 21:10:22 INFO - "pgo_build": "False", 21:10:22 INFO - "basedir": "/builds/slave/test", 21:10:22 INFO - "buildnumber": 51, 21:10:22 INFO - "slavename": "t-yosemite-r7-0155", 21:10:22 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 21:10:22 INFO - "platform": "yosemite_r7", 21:10:22 INFO - "scheduler": "tests-autoland-macosx64-talos", 21:10:22 INFO - "branch": "autoland", 21:10:22 INFO - "repo_path": "integration/autoland", 21:10:22 INFO - "revision": "9b1032ae8fac9bec0f8232ad6594dfe1467ef1f6", 21:10:22 INFO - "stage_platform": "macosx64", 21:10:22 INFO - "builduid": "5604e4b1882d4a0e8d2321dc7267771d", 21:10:22 INFO - "slavebuilddir": "test" 21:10:22 INFO - } 21:10:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 21:10:22 INFO - Running post-action listener: _resource_record_post_action 21:10:22 INFO - ##### 21:10:22 INFO - ##### Finished read-buildbot-config step (success) 21:10:22 INFO - ##### 21:10:22 INFO - ##### 21:10:22 INFO - ##### Running download-and-extract step. 21:10:22 INFO - ##### 21:10:22 INFO - Running pre-action listener: _resource_record_pre_action 21:10:22 INFO - Running main action method: download_and_extract 21:10:22 INFO - mkdir: /builds/slave/test/build/tests 21:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:10:22 INFO - https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:10:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 21:10:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 21:10:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:23 INFO - Downloaded 1199 bytes. 21:10:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 21:10:23 INFO - Using the following test package requirements: 21:10:23 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 21:10:23 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 21:10:23 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 21:10:23 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'jsshell-mac.zip'], 21:10:23 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 21:10:23 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 21:10:23 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 21:10:23 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 21:10:23 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 21:10:23 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 21:10:23 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 21:10:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:10: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')]} 21:10:23 INFO - https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:10:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:10:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:10:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:24 INFO - Downloaded 18772183 bytes. 21:10:24 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'] 21:10:24 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 21:10:28 INFO - Return code: 0 21:10:28 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 21:10:28 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')]} 21:10:28 INFO - https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:10:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:10:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 21:10:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:29 INFO - Downloaded 18772183 bytes. 21:10:29 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'] 21:10:29 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 21:10:32 INFO - Return code: 0 21:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:10:32 INFO - https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:10:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 21:10:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 21:10:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:33 INFO - Downloaded 14403333 bytes. 21:10:33 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'] 21:10:33 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 21:10:34 INFO - Return code: 0 21:10:34 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')]} 21:10:34 INFO - https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:10:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:10:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:10:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 21:10:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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 21:10:37 INFO - Downloaded 112462230 bytes. 21:10:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:10:37 INFO - mkdir: /builds/slave/test/properties 21:10:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:10:37 INFO - Writing to file /builds/slave/test/properties/build_url 21:10:37 INFO - Contents: 21:10:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 21:10:38 INFO - Running post-action listener: _resource_record_post_action 21:10:38 INFO - Running post-action listener: set_extra_try_arguments 21:10:38 INFO - ##### 21:10:38 INFO - ##### Finished download-and-extract step (success) 21:10:38 INFO - ##### 21:10:38 INFO - ##### 21:10:38 INFO - ##### Running populate-webroot step. 21:10:38 INFO - ##### 21:10:38 INFO - Running pre-action listener: _resource_record_pre_action 21:10:38 INFO - Running main action method: populate_webroot 21:10:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:10:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:10:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:10:38 INFO - '--tppagecycles', 21:10:38 INFO - '7'], 21:10:38 INFO - 'tests': ['tsvgm']}}, 21:10:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:10:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:10:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:10:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:10:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:10:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:10:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['damp', 'tps']}, 21:10:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'tests': ['damp', 'tps']}, 21:10:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['dromaeo_dom']}, 21:10:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:10:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['basic_compositor_video']}, 21:10:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:10:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['a11yr', 21:10:38 INFO - 'ts_paint', 21:10:38 INFO - 'tpaint', 21:10:38 INFO - 'sessionrestore', 21:10:38 INFO - 'sessionrestore_no_auto_restore', 21:10:38 INFO - 'tabpaint']}, 21:10:38 INFO - 'other-e10s': {'tests': ['a11yr', 21:10:38 INFO - 'ts_paint', 21:10:38 INFO - 'tpaint', 21:10:38 INFO - 'sessionrestore', 21:10:38 INFO - 'sessionrestore_no_auto_restore', 21:10:38 INFO - 'tabpaint']}, 21:10:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:10:38 INFO - 'ts_paint', 21:10:38 INFO - 'tpaint', 21:10:38 INFO - 'sessionrestore', 21:10:38 INFO - 'sessionrestore_no_auto_restore', 21:10:38 INFO - 'tabpaint']}, 21:10:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:10:38 INFO - 'ts_paint', 21:10:38 INFO - 'tpaint', 21:10:38 INFO - 'sessionrestore', 21:10:38 INFO - 'sessionrestore_no_auto_restore', 21:10:38 INFO - 'tabpaint']}, 21:10:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['a11yr', 21:10:38 INFO - 'ts_paint', 21:10:38 INFO - 'tpaint', 21:10:38 INFO - 'sessionrestore', 21:10:38 INFO - 'sessionrestore_no_auto_restore', 21:10:38 INFO - 'tabpaint']}, 21:10:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['a11yr', 21:10:38 INFO - 'ts_paint', 21:10:38 INFO - 'tpaint', 21:10:38 INFO - 'sessionrestore', 21:10:38 INFO - 'sessionrestore_no_auto_restore', 21:10:38 INFO - 'tabpaint']}, 21:10:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['tsvgx', 21:10:38 INFO - 'tsvgr_opacity', 21:10:38 INFO - 'tart', 21:10:38 INFO - 'tscrollx', 21:10:38 INFO - 'cart']}, 21:10:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:10:38 INFO - 'tsvgr_opacity', 21:10:38 INFO - 'tart', 21:10:38 INFO - 'tscrollx', 21:10:38 INFO - 'cart']}, 21:10:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'talos_options': ['--disable-e10s'], 21:10:38 INFO - 'tests': ['tp5o']}, 21:10:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'tests': ['tp5o']}, 21:10:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'talos_options': ['--disable-e10s', 21:10:38 INFO - '--xperf_path', 21:10:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:10:38 INFO - 'tests': ['tp5n']}, 21:10:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:10:38 INFO - 'talos_options': ['--xperf_path', 21:10:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:10:38 INFO - 'tests': ['tp5n']}}, 21:10:38 INFO - 'talos.zip': {'path': '', 21:10:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:10:38 INFO - Running post-action listener: _resource_record_post_action 21:10:38 INFO - ##### 21:10:38 INFO - ##### Finished populate-webroot step (success) 21:10:38 INFO - ##### 21:10:38 INFO - ##### 21:10:38 INFO - ##### Running create-virtualenv step. 21:10:38 INFO - ##### 21:10:38 INFO - Running pre-action listener: _resource_record_pre_action 21:10:38 INFO - Running main action method: create_virtualenv 21:10:38 INFO - Creating virtualenv /builds/slave/test/build/venv 21:10:38 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 21:10:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:10:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:10:39 INFO - Using real prefix '/tools/python27' 21:10:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:10:39 INFO - Installing distribute.............................................................................................................................................................................................done. 21:10:42 INFO - Installing pip.................done. 21:10:42 INFO - Return code: 0 21:10:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:10: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')]} 21:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10: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')]} 21:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:42 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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:10:42 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 21:10:42 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 21:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:42 INFO - 'HOME': '/Users/cltbld', 21:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:42 INFO - 'LOGNAME': 'cltbld', 21:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:42 INFO - 'MOZ_NO_REMOTE': '1', 21:10:42 INFO - 'NO_EM_RESTART': '1', 21:10:42 INFO - 'PAGER': '/bin/cat', 21:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:42 INFO - 'PWD': '/builds/slave/test', 21:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:42 INFO - 'SHELL': '/bin/bash', 21:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:10:42 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:42 INFO - 'USER': 'cltbld', 21:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:42 INFO - 'XPC_FLAGS': '0x0', 21:10:42 INFO - 'XPC_SERVICE_NAME': '0', 21:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:42 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 21:10:42 INFO - Cleaning up... 21:10:42 INFO - Return code: 0 21:10:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:10: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')]} 21:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10: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')]} 21:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:42 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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:10:42 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 21:10:42 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 21:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:42 INFO - 'HOME': '/Users/cltbld', 21:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:42 INFO - 'LOGNAME': 'cltbld', 21:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:42 INFO - 'MOZ_NO_REMOTE': '1', 21:10:42 INFO - 'NO_EM_RESTART': '1', 21:10:42 INFO - 'PAGER': '/bin/cat', 21:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:42 INFO - 'PWD': '/builds/slave/test', 21:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:42 INFO - 'SHELL': '/bin/bash', 21:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:10:42 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:42 INFO - 'USER': 'cltbld', 21:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:42 INFO - 'XPC_FLAGS': '0x0', 21:10:42 INFO - 'XPC_SERVICE_NAME': '0', 21:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:43 INFO - Downloading/unpacking psutil>=3.1.1 21:10:43 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 21:10:43 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 21:10:43 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 21:10:43 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 21:10:43 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 21:10:43 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 21:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:10:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:10:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:10:45 INFO - Installing collected packages: psutil 21:10:45 INFO - Running setup.py install for psutil 21:10:45 INFO - building 'psutil._psutil_osx' extension 21:10:45 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 21:10:46 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 21:10:46 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 21:10:46 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 21:10:46 INFO - building 'psutil._psutil_posix' extension 21:10:46 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 21:10:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:10:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:10:46 INFO - ^ 21:10:46 INFO - 1 warning generated. 21:10:46 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 21:10:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:10:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:10:46 INFO - Successfully installed psutil 21:10:46 INFO - Cleaning up... 21:10:47 INFO - Return code: 0 21:10:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:10:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:10:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:47 INFO - 'HOME': '/Users/cltbld', 21:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:47 INFO - 'LOGNAME': 'cltbld', 21:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:47 INFO - 'MOZ_NO_REMOTE': '1', 21:10:47 INFO - 'NO_EM_RESTART': '1', 21:10:47 INFO - 'PAGER': '/bin/cat', 21:10:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:47 INFO - 'PWD': '/builds/slave/test', 21:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:47 INFO - 'SHELL': '/bin/bash', 21:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:10:47 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:47 INFO - 'USER': 'cltbld', 21:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:47 INFO - 'XPC_FLAGS': '0x0', 21:10:47 INFO - 'XPC_SERVICE_NAME': '0', 21:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:10:47 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 21:10:47 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 21:10:47 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 21:10:47 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 21:10:47 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 21:10:47 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 21:10:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:10:49 INFO - Installing collected packages: mozsystemmonitor 21:10:49 INFO - Running setup.py install for mozsystemmonitor 21:10:50 INFO - Successfully installed mozsystemmonitor 21:10:50 INFO - Cleaning up... 21:10:50 INFO - Return code: 0 21:10:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:10: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')]} 21:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10: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')]} 21:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10: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', '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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:10: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:10: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 jsonschema==2.5.1 21:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:50 INFO - 'HOME': '/Users/cltbld', 21:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:50 INFO - 'LOGNAME': 'cltbld', 21:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:50 INFO - 'MOZ_NO_REMOTE': '1', 21:10:50 INFO - 'NO_EM_RESTART': '1', 21:10:50 INFO - 'PAGER': '/bin/cat', 21:10:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:50 INFO - 'PWD': '/builds/slave/test', 21:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:50 INFO - 'SHELL': '/bin/bash', 21:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:10:50 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:50 INFO - 'USER': 'cltbld', 21:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:50 INFO - 'XPC_FLAGS': '0x0', 21:10:50 INFO - 'XPC_SERVICE_NAME': '0', 21:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:50 INFO - Downloading/unpacking jsonschema==2.5.1 21:10:50 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 21:10:50 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 21:10:50 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 21:10:50 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 21:10:50 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 21:10:50 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 21:10:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:10:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:53 INFO - Downloading functools32-3.2.3-2.tar.gz 21:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:10:53 INFO - warning: no files found matching '*.txt' 21:10:53 INFO - no previously-included directories found matching 'build' 21:10:53 INFO - no previously-included directories found matching 'dist' 21:10:53 INFO - no previously-included directories found matching '.git*' 21:10:53 INFO - Installing collected packages: jsonschema, functools32 21:10:53 INFO - Running setup.py install for functools32 21:10:53 INFO - warning: no files found matching '*.txt' 21:10:53 INFO - no previously-included directories found matching 'build' 21:10:53 INFO - no previously-included directories found matching 'dist' 21:10:53 INFO - no previously-included directories found matching '.git*' 21:10:53 INFO - Successfully installed jsonschema functools32 21:10:53 INFO - Cleaning up... 21:10:53 INFO - Return code: 0 21:10:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:53 INFO - 'HOME': '/Users/cltbld', 21:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:53 INFO - 'LOGNAME': 'cltbld', 21:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:53 INFO - 'MOZ_NO_REMOTE': '1', 21:10:53 INFO - 'NO_EM_RESTART': '1', 21:10:53 INFO - 'PAGER': '/bin/cat', 21:10:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:53 INFO - 'PWD': '/builds/slave/test', 21:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:53 INFO - 'SHELL': '/bin/bash', 21:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:10:53 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:53 INFO - 'USER': 'cltbld', 21:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:53 INFO - 'XPC_FLAGS': '0x0', 21:10:53 INFO - 'XPC_SERVICE_NAME': '0', 21:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:53 INFO - Downloading/unpacking blobuploader==1.2.4 21:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:56 INFO - Downloading blobuploader-1.2.4.tar.gz 21:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:10:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:10:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:10:57 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 21:10:57 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 21:10:57 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 21:10:57 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 21:10:57 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 21:10:57 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 21:10:57 INFO - Downloading docopt-0.6.1.tar.gz 21:10:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:10:57 INFO - Installing collected packages: blobuploader, requests, docopt 21:10:57 INFO - Running setup.py install for blobuploader 21:10:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:10:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:10:57 INFO - Running setup.py install for requests 21:10:58 INFO - Running setup.py install for docopt 21:10:58 INFO - Successfully installed blobuploader requests docopt 21:10:58 INFO - Cleaning up... 21:10:58 INFO - Return code: 0 21:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:10: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')]} 21:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10: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')]} 21:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:58 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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:10:58 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 21:10:58 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 21:10:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:10:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:58 INFO - 'HOME': '/Users/cltbld', 21:10:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:58 INFO - 'LOGNAME': 'cltbld', 21:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:58 INFO - 'MOZ_NO_REMOTE': '1', 21:10:58 INFO - 'NO_EM_RESTART': '1', 21:10:58 INFO - 'PAGER': '/bin/cat', 21:10:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:58 INFO - 'PWD': '/builds/slave/test', 21:10:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:58 INFO - 'SHELL': '/bin/bash', 21:10:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:10:58 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:10:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:58 INFO - 'USER': 'cltbld', 21:10:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:58 INFO - 'XPC_FLAGS': '0x0', 21:10:58 INFO - 'XPC_SERVICE_NAME': '0', 21:10:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:10:58 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-z5Z8rU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:10:58 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-8hyyfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:10:58 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-xOJs6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Bre1hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-unqaMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-VcwZ4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-2xezpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-kMHt8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-4qTFlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-96YZ_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-NhXnfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-PKsDc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:10:59 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Dn1cYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:11:00 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-cTtZNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:00 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-j66EoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:11:00 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-NR1Hs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:11:00 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-l32mI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:11:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:11:00 INFO - Running setup.py install for manifestparser 21:11:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:11:00 INFO - Running setup.py install for mozcrash 21:11:00 INFO - Running setup.py install for mozdebug 21:11:00 INFO - Running setup.py install for mozdevice 21:11:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Running setup.py install for mozfile 21:11:01 INFO - Running setup.py install for mozhttpd 21:11:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Running setup.py install for mozinfo 21:11:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Running setup.py install for mozInstall 21:11:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Running setup.py install for mozleak 21:11:01 INFO - Running setup.py install for mozlog 21:11:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:11:01 INFO - Running setup.py install for moznetwork 21:11:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:11:02 INFO - Running setup.py install for mozprocess 21:11:02 INFO - Running setup.py install for mozprofile 21:11:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:11:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:11:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:11:02 INFO - Running setup.py install for mozrunner 21:11:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:11:02 INFO - Running setup.py install for mozscreenshot 21:11:02 INFO - Running setup.py install for moztest 21:11:02 INFO - Running setup.py install for mozversion 21:11:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:11:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:11:03 INFO - Cleaning up... 21:11:03 INFO - Return code: 0 21:11:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:11:03 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')]} 21:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:03 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')]} 21:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:03 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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:11:03 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 21:11:03 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 21:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:03 INFO - 'HOME': '/Users/cltbld', 21:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:03 INFO - 'LOGNAME': 'cltbld', 21:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:03 INFO - 'MOZ_NO_REMOTE': '1', 21:11:03 INFO - 'NO_EM_RESTART': '1', 21:11:03 INFO - 'PAGER': '/bin/cat', 21:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:03 INFO - 'PWD': '/builds/slave/test', 21:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:03 INFO - 'SHELL': '/bin/bash', 21:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:11:03 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:03 INFO - 'USER': 'cltbld', 21:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:03 INFO - 'XPC_FLAGS': '0x0', 21:11:03 INFO - 'XPC_SERVICE_NAME': '0', 21:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:11:03 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-riLsXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:11:03 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)) 21:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:11:03 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-MIQIHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:11:03 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)) 21:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:11:03 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-XKEQNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:11:03 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)) 21:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:11:03 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-WIDxeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:11:03 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)) 21:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:11:03 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-RoS7s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:11:03 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)) 21:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:11:03 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-eRAWEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:11:03 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)) 21:11:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:11:03 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-zgxu7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-19gUXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-mrMkUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-5YgRfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-4qkSBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-zkFkNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-VHikYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-czRERC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-0sHco5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:11:04 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)) 21:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:11:04 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-bjjhRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:11:05 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)) 21:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:11:05 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-GcSYwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:11:05 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)) 21:11:05 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)) 21:11:05 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)) 21:11:05 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)) 21:11:05 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)) 21:11:05 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)) 21:11:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:11: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 21:11: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 21:11: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 21:11: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 21:11: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 21:11: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 21:11:07 INFO - Downloading blessings-1.6.tar.gz 21:11:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:11:07 INFO - Installing collected packages: blessings 21:11:07 INFO - Running setup.py install for blessings 21:11:07 INFO - Successfully installed blessings 21:11:07 INFO - Cleaning up... 21:11:07 INFO - Return code: 0 21:11:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:11:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:11:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:11:08 INFO - Reading from file tmpfile_stdout 21:11:08 INFO - Current package versions: 21:11:08 INFO - blessings == 1.6 21:11:08 INFO - blobuploader == 1.2.4 21:11:08 INFO - docopt == 0.6.1 21:11:08 INFO - functools32 == 3.2.3-2 21:11:08 INFO - jsonschema == 2.5.1 21:11:08 INFO - manifestparser == 1.1 21:11:08 INFO - mozInstall == 1.12 21:11:08 INFO - mozcrash == 0.17 21:11:08 INFO - mozdebug == 0.1 21:11:08 INFO - mozdevice == 0.48 21:11:08 INFO - mozfile == 1.2 21:11:08 INFO - mozhttpd == 0.7 21:11:08 INFO - mozinfo == 0.9 21:11:08 INFO - mozleak == 0.1 21:11:08 INFO - mozlog == 3.2 21:11:08 INFO - moznetwork == 0.27 21:11:08 INFO - mozprocess == 0.23 21:11:08 INFO - mozprofile == 0.28 21:11:08 INFO - mozrunner == 6.12 21:11:08 INFO - mozscreenshot == 0.1 21:11:08 INFO - mozsystemmonitor == 0.3 21:11:08 INFO - moztest == 0.7 21:11:08 INFO - mozversion == 1.4 21:11:08 INFO - psutil == 3.1.1 21:11:08 INFO - requests == 1.2.3 21:11:08 INFO - wsgiref == 0.1.2 21:11:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:11:08 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')]} 21:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:08 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')]} 21:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:08 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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:11:08 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 21:11:08 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 21:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:08 INFO - 'HOME': '/Users/cltbld', 21:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:08 INFO - 'LOGNAME': 'cltbld', 21:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:08 INFO - 'MOZ_NO_REMOTE': '1', 21:11:08 INFO - 'NO_EM_RESTART': '1', 21:11:08 INFO - 'PAGER': '/bin/cat', 21:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:08 INFO - 'PWD': '/builds/slave/test', 21:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:08 INFO - 'SHELL': '/bin/bash', 21:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:11:08 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:08 INFO - 'USER': 'cltbld', 21:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:08 INFO - 'XPC_FLAGS': '0x0', 21:11:08 INFO - 'XPC_SERVICE_NAME': '0', 21:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 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)) 21:11:08 INFO - Cleaning up... 21:11:08 INFO - Return code: 0 21:11:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:11:08 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')]} 21:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:08 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')]} 21:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:11:08 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 0x10aa3ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd363db67c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, '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.3DLB20tE0s/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.Vg515ZAij6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 21:11:08 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 21:11:08 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 21:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:08 INFO - 'HOME': '/Users/cltbld', 21:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:08 INFO - 'LOGNAME': 'cltbld', 21:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:08 INFO - 'MOZ_NO_REMOTE': '1', 21:11:08 INFO - 'NO_EM_RESTART': '1', 21:11:08 INFO - 'PAGER': '/bin/cat', 21:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:08 INFO - 'PWD': '/builds/slave/test', 21:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:08 INFO - 'SHELL': '/bin/bash', 21:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:11:08 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:08 INFO - 'USER': 'cltbld', 21:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:08 INFO - 'XPC_FLAGS': '0x0', 21:11:08 INFO - 'XPC_SERVICE_NAME': '0', 21:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:11:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:11:08 INFO - Cleaning up... 21:11:08 INFO - Return code: 0 21:11:08 INFO - Running post-action listener: _resource_record_post_action 21:11:08 INFO - Running post-action listener: _start_resource_monitoring 21:11:08 INFO - Starting resource monitoring. 21:11:08 INFO - ##### 21:11:08 INFO - ##### Finished create-virtualenv step (success) 21:11:08 INFO - ##### 21:11:08 INFO - ##### 21:11:08 INFO - ##### Running install step. 21:11:08 INFO - ##### 21:11:08 INFO - Running pre-action listener: _resource_record_pre_action 21:11:08 INFO - Running main action method: install 21:11:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:11:08 INFO - Reading from file tmpfile_stdout 21:11:08 INFO - Detecting whether we're running mozinstall >=1.0... 21:11:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:11:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:11:09 INFO - Reading from file tmpfile_stdout 21:11:09 INFO - Output received: 21:11:09 INFO - Usage: mozinstall [options] installer 21:11:09 INFO - Options: 21:11:09 INFO - -h, --help show this help message and exit 21:11:09 INFO - -d DEST, --destination=DEST 21:11:09 INFO - Directory to install application into. [default: 21:11:09 INFO - "/builds/slave/test"] 21:11:09 INFO - --app=APP Application being installed. [default: firefox] 21:11:09 INFO - mkdir: /builds/slave/test/build/application 21:11:09 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'] 21:11:09 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 21:11:43 INFO - Reading from file tmpfile_stdout 21:11:43 INFO - Output received: 21:11:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:11:43 INFO - Running post-action listener: _resource_record_post_action 21:11:43 INFO - ##### 21:11:43 INFO - ##### Finished install step (success) 21:11:43 INFO - ##### 21:11:43 INFO - ##### 21:11:43 INFO - ##### Running run-tests step. 21:11:43 INFO - ##### 21:11:43 INFO - Running pre-action listener: _resource_record_pre_action 21:11:43 INFO - Running main action method: run_tests 21:11:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:11:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:11:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:11:43 INFO - 2016-07-11 21:11:43.617 screenresolution[1632:7525] starting screenresolution argv=screenresolution get 21:11:43 INFO - 2016-07-11 21:11:43.620 screenresolution[1632:7525] Display 0: 1600x1200x32@60 21:11:43 INFO - 2016-07-11 21:11:43.626 screenresolution[1633:7527] starting screenresolution argv=screenresolution list 21:11:43 INFO - Available Modes on Display 0 21:11:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:11:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:11:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:11:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:11:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:11:43 INFO - Intel Iris: 21:11:43 INFO - Chipset Model: Intel Iris 21:11:43 INFO - Type: GPU 21:11:43 INFO - Bus: Built-In 21:11:43 INFO - VRAM (Dynamic, Max): 1536 MB 21:11:43 INFO - Vendor: Intel (0x8086) 21:11:43 INFO - Device ID: 0x0a2e 21:11:43 INFO - Revision ID: 0x0009 21:11:43 INFO - Displays: 21:11:43 INFO - Display: 21:11:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:11:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:11:43 INFO - Display Serial Number: 1600x1200 60 21:11:43 INFO - Main Display: Yes 21:11:43 INFO - Mirror: Off 21:11:43 INFO - Online: Yes 21:11:43 INFO - Rotation: Supported 21:11:43 INFO - Return code: 0 21:11:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:11:43 INFO - Python 2.7.3 21:11:43 INFO - Return code: 0 21:11:43 INFO - grabbing minidump binary from tooltool 21:11: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')]} 21:11:43 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 0x10a99fd30>, '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 0x10a704db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a997b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:11:43 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 21:11:43 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 21:11:43 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 21:11:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:11:43 INFO - Return code: 0 21:11:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:11:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:11:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:11:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:11:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:11:43 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/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0155', '--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 21:11:43 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/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0155 --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 21:11:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3DLB20tE0s/Render', 21:11:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:43 INFO - 'HOME': '/Users/cltbld', 21:11:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:43 INFO - 'LOGNAME': 'cltbld', 21:11:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:11:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:11:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:43 INFO - 'MOZ_NO_REMOTE': '1', 21:11:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:11:43 INFO - 'NO_EM_RESTART': '1', 21:11:43 INFO - 'PAGER': '/bin/cat', 21:11:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:43 INFO - 'PWD': '/builds/slave/test', 21:11:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:11:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:43 INFO - 'SHELL': '/bin/bash', 21:11:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vg515ZAij6/Listeners', 21:11:43 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 21:11:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:43 INFO - 'USER': 'cltbld', 21:11:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:43 INFO - 'XPC_FLAGS': '0x0', 21:11:43 INFO - 'XPC_SERVICE_NAME': '0', 21:11:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:43 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/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0155', '--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 21:11:44 INFO - mozversion application_buildid: 20160711201325 21:11:44 INFO - mozversion application_changeset: 9b1032ae8fac9bec0f8232ad6594dfe1467ef1f6 21:11:44 INFO - mozversion application_display_name: Nightly 21:11:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:11:44 INFO - mozversion application_name: Firefox 21:11:44 INFO - mozversion application_remotingname: firefox 21:11:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:11:44 INFO - mozversion application_vendor: Mozilla 21:11:44 INFO - mozversion application_version: 50.0a1 21:11:44 INFO - mozversion platform_buildid: 20160711201325 21:11:44 INFO - mozversion platform_changeset: 9b1032ae8fac9bec0f8232ad6594dfe1467ef1f6 21:11:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:11:44 INFO - mozversion platform_version: 50.0a1 21:11:44 INFO - using testdate: 1468296704 21:11:44 INFO - actual date: 1468296704 21:11:44 INFO - starting webserver on 'localhost:49271' 21:11:44 INFO - SUITE-START | Running 2 tests 21:11:44 INFO - TEST-START | dromaeo_css 21:11:44 INFO - operating with platform_type : mac_ 21:11:44 INFO - Initialising browser for dromaeo_css test... 21:11:44 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmp1fnXK6/profile http://localhost:49271/getInfo.html) 21:11:46 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 21:11:46 INFO - PROCESS | 1643 | colorDepth:24 21:11:46 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 21:11:46 INFO - PROCESS | 1643 | __metrics 21:11:46 INFO - PROCESS | 1643 | 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" 21:11:46 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:11:47 INFO - TEST-INFO | 1643: exit 0 21:11:47 INFO - Browser initialized. 21:11:47 INFO - Running cycle 1/1 for dromaeo_css test... 21:11:47 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmp1fnXK6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:11:59 INFO - PROCESS | 1645 | RSS: Main: 243646464 21:11:59 INFO - PROCESS | 1645 | 21:12:50 INFO - PROCESS | 1645 | 1468296770849 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] 21:15:01 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 21:15:02 INFO - PROCESS | 1645 | RSS: Main: 406863872 21:15:02 INFO - PROCESS | 1645 | 21:18:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 21:18:06 INFO - PROCESS | 1645 | RSS: Main: 376397824 21:18:06 INFO - PROCESS | 1645 | 21:21:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 21:21:09 INFO - PROCESS | 1645 | RSS: Main: 375578624 21:21:09 INFO - PROCESS | 1645 | 21:24:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 21:24:12 INFO - PROCESS | 1645 | RSS: Main: 374505472 21:24:12 INFO - PROCESS | 1645 | 21:27:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 21:27:16 INFO - PROCESS | 1645 | RSS: Main: 344739840 21:27:16 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 21:30:19 INFO - PROCESS | 1645 | RSS: Main: 423403520 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | __start_tp_report 21:30:19 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 21:30:19 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 21:30:19 INFO - PROCESS | 1645 | |i|pagename|runs| 21:30:19 INFO - PROCESS | 1645 | |0;dojo.html;2749.780017598592;2776.1810265971535;3323.069540528652;3334.6331903490595;3373.6795004474566;5512;5527.695976721278;5593.356763972144;5602.32772067352;5621.269234999455;5578.9163162552595;5609.046462101443;5638.323401191863;5645.15322701595;5646.378898321192;5097.3373461449955;5118.053160165363;5167.121589329823;5186.377634683843;5202.089634313998;5128.53843154116;5217.087009773293;5221.817236396727;5230.712310822903;5235.188545775411;5097.388313402378;5109.744512774365;5130.153524668425;5130.820421285256;5222.5038621331105;5191.221316802491;5207.817726379596;5221.6083793715625;5231.424543300234;5283.86790330241;4569.908601827978;4802.687825291345;5039.77321020555;5140.203268493352;5143.151380022292;5518.889622207574;5532.059549876531;5565.833025009256;5596.608237423382;5611.438856114356;6107.235399641211;6114.388561143895;6115.755369785248;6117.510599152057;6143.846403839895;5903.49820265278;6010.429009244115;6092.573519853612;6198.721057552421;6221.844453888643;5641.746121424546;5692;5704.22992895962;5708.372079071299;5708.628939119027;6262.6868656566985;6289.40250676185;6301.960176570815;6327;6331.841703957346;5821.243238379068;5872.618279811799;5926.377730338339;5951.256092988377;6023.66869822164;6118.984682626245;6189.473894718909;6197.96901015492;6202.875942481125;6205.348438413992;6089.543284253698;6109;6119.931479141598;6123.38766123393;6134.40551407239;5669.659820410789;5697.23087383198;5728.312602487645;6151.72317245725;6167.506599472032;4650.000249946278;4706.458757242893;4708.458527269339;4716.764161791897;4719.457262414798;4296.3555466680245;4310.913781724348;4329.372241025097;4332.371806088099;4341.240282950471;5444.292242008513;5625.240592519957;5656.830295091154;5682.948654498931;5748.798792042258;6169;6229.875402491924;6247.375262473807;6248.125262463259;6262;5668.285279223053;5673.177389278531;5675.318961724537;5680.602357834912;5712.885742285214;5265.605079619044;5310.336207974004;5325.760340784674;5341.5192632662465;5341.839744807662;6365.204349456318;6372.55392122547;6377.3303803100935;6378.808635741028;6387.840303992437;6235.594686345373;6253.3121356651645;6264.34224406449;6264.843378915381;6270.968645156745;6064.272287325549;6104.8779024420155;6105.053716673922;6180.907286390798;6359.554831161774;5751.827445176651;5761.740721667619;5766.423357664368;5767.826965191051;5770.769169233184;5368.489993450616;5617.915731264116;5838.1826544282985;5844.824655260349;5858.912116318344;5697.800576979786;5756.366799651951;5796.62321949072;5802.390748971297;5827.737751801094;5263.868403289795;5717.399673034438;5863.237779088862;5891.558133140117;6041.486473649613;5789.45000224978;5795.797147099831;5804.448577385142;5812.883742325214;5868.38381969887;5953.7023148843255;5992.43071908168;6006.249218847644;6019.939800602113;6042.546808989256;5651.971740141273;5702.34423041339;5763.106718458478;5817.331006934257;5846.853828654318 21:30:19 INFO - PROCESS | 1645 | |1;ext.html;464.3707775963569;489.2954146029717;525.4692760312086;526.208056874404;541.9675518137946;24905.62641560378;40167.79916100419;41081.79459102701;41183.38224926629;41253.381199282034;42194.156116877646;42478;42516.36225456621;42517.7874110629;42561.36157957625;8528.829423411558;9520.523973801317;9680.128788409042;9706.902930970706;9708.029197080306;8464.06895241523;8655.826883462327;8661.653533858638;8671;11450;11603.361815100166;11840.644780656594;11877.940610296937;11882.346470944094;11892.464839082262;11725;11770.2349347293;11793.94103029488;11794.469248883821;11795.764084718257;8480.279176270025;11393.943030284878;11585.942070289595;11706.063514918786;11792.587259445878;35367.115822104395;40195.39706904399;40442.59557404418;40788.796056019826;40933.59066409327;3458.394780913355;3571.3035957988204;3729.440583912408;3899.1315932058556;3937.0944682722848;30396.93610723636;31167.06498805017;31964;32184.195395115075;32278.838605807057;3250.366178595175;3282.1630484226607;3287.4575695010294;3309.8179600121985;3394.2193295542165;537078;538188;538939;539826;540036;61939;62326.68836655788;62330.37669623246;62374.37625623686;62393;65378.01932971009;65394;65403.01895471572;65490.67254663696;65764.34235657677;16368.83631163697;16571.00573965566;16588.50234492967;16590.50228493147;16616.58458538522;12570.43433045497;12591.87408125907;12604.558840440724;12607.054470914718;12609.684757881127;16099.839001609835;16364.427245046605;16384.26270817816;16726.330946761995;17836.08008389369;7036.683349249296;8384.455010424303;8387;8406.831863362699;8453.915460845436;914.3964983111115;941.5245301122889;986.0878687214298;1072.587053984206;1076.4617691154422;30228.24429389283;30329.848350758104;30850.845745771127;31056.22359440987;31095.378092438477;1054.0987455725244;1063.6702622187147;1078.266778590566;1107.534835369147;1123.07346439188;15077.472288469851;15134.621634459227;15269.428243856195;15286.923565382102;15372.30824612895;722911;728761;728861;729797;732211;48231.03537929222;48783;55212.17181742277;55396.16905746417;57286.7135664319;1421.289355322339;1442.4086124688827;1449.6883170118474;1456.2427537680346;1460.5034288342015;30036.948706795156;30104.397912042074;30482.695173047985;31149;31171;42602.573974261104;49626;50502;50574;50621;687025;691412;692557;709526;916952;22500.549989000454;24872.626910595995;25640;25770.35574110662;25950.961961522084;45086.549134508234;48140.759296203294;48755.51244487651;49804.75097624489;52711.20933186082;40313.96075490564;40395.1920961585;40892.79553602213;40914;41932.37101443542;37253.068673283386;40121.99695007648;40617.78146655605;40839.7958010208;41408.37887431631;38120.23759524739;38432.423513647875;38888.80555597204;38892.2221555573;39074.804625976685;30866.84566577153;33489.49765753564;34211.31577368489;34523.82738086394;35306.47040294449;21518.8924055385;25934.74065259323;26108.30296030752;26275;26479.808408621273 21:30:19 INFO - PROCESS | 1645 | |2;jquery.html;357.35319072478796;373.2963364058749;383.76590279929246;385.8919502539288;402.4828095896771;15473;15752.448664296751;19814.306499272527;20503.17987280509;20576.38270851873;20206.090725917333;20646.690299645517;20962.895185524052;20989.055492502837;21129.894350528226;4758.857234282959;5228.529432351097;5407.324084489439;5408.648437851547;5433.130699088147;4230.048239146183;4245.405643209953;4315.654747620199;4324.308110702288;4337.804798784062;3741.38267185914;4247.362895565659;4276.486821581399;4306.440162778835;4325.848595299165;4829.734364609945;5935.821925342247;5939.881202375971;5948.37542058082;5954.047352423613;5044.218146187347;5243.6067294952645;5270.8155214567305;5274.55166310864;5281.0758117329315;25508.97964081453;25586.104486343076;25659.74340256592;26052.739472605223;26102.608460873103;2033.4001469566365;2226.1874415838265;2304.331743794298;2818.0136952066614;3008.7292143706954;8032.357411407073;9651.600517924931;10139.746506337327;10512.21158413122;10550.419726915017;1848.9167987440462;1857.9535511612182;1941.5340318323642;1948.581055073152;1957.0410498855601;116511.83488165095;116642.83357166404;119769;121939.78060219462;122499.77500224973;11596.94201528987;11688;11688.18182727201;11718.531258749725;11895.762084758257;14246.074005189837;14431.783523247159;14557.41770329175;14579;14586;7140.964560138751;7203.96398018017;8580;8631.223189912813;8656.13438656142;7108.395786358217;8572.271356934605;8577.614007369682;8578.399512034222;8713.997890242577;4567.566081222279;4600.470945841271;4641.350210970467;4687.5781179693995;4712.175369310357;1676.522191175509;1768.7789026371704;1905.6093500832296;1921.193098898466;1950.0542237015036;728.9343959043712;946.3990366117565;963.3738070254251;993.036754348281;1032.2206733915846;20246.595068098555;21141.26005589797;21171.8941405292;21371.57256854854;21371.89314053451;956.3305686019814;965.1313817564248;1026.609888242478;1036.1451802263164;1058.4972138234432;11006.559737610405;11293.94353028246;11299;11411.600593979338;11443.713907152387;592723;600602;600863;603190;608396;95388.52305738427;118883;119810.40094799644;123930.38034809768;134912.32543837218;962.7159987803615;977.7115750853516;985.7584891701579;1016.6797458800454;1017.0998666180484;18733.531661708566;19074.80925190786;19120.904395477934;19199.52001199981;19234.71147932754;42562.7231183065;47775.76112119417;52199.47800522098;52341.21488177757;52454;65865;73777;75054;75203;85815;8050.355971522381;8125.634346454305;8222.794430139294;8229.917700822914;8317.209865062809;11706.531738730355;12856.935715321364;12915.870841291468;12945.158564693265;13099.2140471572;11004.834927476255;11134.33194008362;11310.377929213972;11485.425728713364;11491.080713543064;10979.451027448436;11119.610813621484;11138.443077846237;11309.943450282695;11378.487968041623;10850.45747712627;11451.828222576834;11726.765464690488;11745.941270293593;11817.468213930566;8160.8367832641825;8207.589620518831;8209.589520524069;8269.586520673822;8402.831943360976;8823.955880220557;10598.20513461478;11388.886111138783;11413.31520108763;11413.714657133305 21:30:19 INFO - PROCESS | 1645 | |3;mootools.html;1548.674778296558;1578.021626591513;1778.2797966823432;1922.0870086708806;1923.4422017614893;8559.700410485635;8783.824323513525;8981.326400519954;8988.280937524998;8990.955045224764;7537.773866784005;7725.266099720531;8456;8707.65169393225;8804.163604457566;2333.9299821005357;2363.61000434928;2364.798992085675;2366.7633236676365;2371.976280237193;2329.860208387502;2340.1224540797202;2384.3800611840875;2389.0921449849034;2389.713234411873;1981.1679094780227;2036.1041141897592;2076.9376918692465;2141.250562303197;2151.4728891421623;2268;2366.3847399676206;2367.171489978511;2370.9051637934463;2373.0151986925407;2061.5258490547258;2377.5244951009868;2405.819563532742;2406.8074554035634;2415.770501802326;8766;8820.470771753651;8838.60226289812;8848;8848.911510884873;5183.740812959337;5340.065488539529;5340.599455040849;5345.652532585408;5345.946540534584;3496.17839807645;3581.3374525712566;3649.5620525537106;3661.0481274868725;3666.2300916807235;1056.973575660607;1095.7205912492343;1099.9945000275027;1107.850440190572;1114.9777004459947;11511.48198331077;11691.824622630667;11729.120315976335;11740.530378784924;11817.290962542274;4620.630349572026;4715.74063426508;4830;4842.370491836109;4860.3195552622665;10066.345687530287;10198;10200.693979180636;10208.234382421348;10211.846822297672;5355.625106242525;5435.673859568438;5436.429174936654;5437.918431223535;5443.428440013741;3959.3467077932396;4856.63575231852;4915.459299477055;4933.753312334369;4956.35567376246;3798.1264309209037;3821.3694740367528;3877.65101140893;3909.6285852843935;3915.1190982029384;1224.20426722631;1243.2354102227066;1245.5516014234868;1259.0871618077003;1261.6971926737701;856.9828603427896;864.3992425264381;890.2966656341417;899.7120921305119;982.1553464020936;5959.165716799652;6126.326104128542;6136.570440069152;6143.96928015366;6279.4332828941015;927.0345327815529;966.3622009473713;980.8185485685171;994.1003391930315;1007.2445665750687;3906.746061505993;3917.5886531914307;4054.7769872656695;4102.118044620421;4108.239975604522;10003;10730.19523535738;10746.73133171677;10761.946190268998;10764.3541387517;10395.532201050972;10538.315009524356;10676.145908327315;10694.091002264895;10699.94650026745;8174.3051840593025;8295.792605184797;8330;8339;8349.8330033399;5875.911861322169;5878.764849406147;5918.00885722058;6059.818205453844;6120.846978825565;8758.60586273632;9756.170725488413;10062;10185.236107291834;10210.948945255523;10328.845067324146;10365.06714395708;10379.84430233532;10388.168946484111;10398.064174224053;7083.539569927938;7231.059962204879;7236.059312289369;7238.23998480173;7240.515694282764;8018;8171.673133074848;8228.711995080383;8248.876266856121;8253.380996425412;7560.206178351414;7641.7707468776025;7641.7707468776025;7661.233876612294;7782.92217077822;7506.849863002819;7520.623968801428;7585.355244804033;7622.733204337822;7659.88510172359;6996.650167491503;7141.714331426685;7148.213696493485;7154.2130365658695;7154.284571543012;4936.3089167515855;5014.297998280316;5090.974545127251;5099.107656160231;5222.373315202048;5088.2876397305145;5111.080005599028;5133.307003554472;5150.690958542499;5353.197020446964 21:30:19 INFO - PROCESS | 1645 | |4;prototype.html;272.8812966359632;287.2918256497735;306.4728666693288;306.68258352605056;313.6910143508644;4181.958180418195;4338.4793824741;4972.751362431882;4992.550670439659;5100.923486147706;4410.426644536214;5014.197728363462;5059.671121377117;5085.898282034357;5089.541941225289;3569.7501174917766;4088;4098.9590104099025;4103.281925662997;4115.8765237042935;3292.489664102068;3551.875684351048;3759.5488541375075;4046.4132700758373;4129.2154490646835;3328.6338502764675;3683.2449347883753;3762.2475504898994;3958.7624742515395;4020.1758639478835;3463.6709512596262;3508.438649816017;3884.611538846107;3892.3188442022533;4030.2141082488633;2898.318895059659;3050.7559395248327;3466.2374277658873;3496.335696217736;3519.4544845548726;3854.1713531590563;4885.780139893714;4911.709154866248;5010.248462730583;5210.296609957645;4240.809163587646;4321.395004699345;4515.706479078882;4580.267157254823;4581.977090114561;4626.86119416418;5110.310108135392;5553.278073850414;5582.7766889324375;5630.774769009235;3079.4303053935096;3825.598312177237;3843.8078096095364;3845.3847384418636;3845.9230815383535;4648.767561621906;5240.947590524046;5535.533083732814;5741.8564535886935;5761.020626493464;3851.0372406898277;3866.2935944838728;3866.6713329367317;3878.301905657009;3906.980465097656;3468.6878180963836;3476.0627269956844;3916.2167566486246;3970.6227908349238;3972.0467087898696;4406.986393129599;4540.864783804049;4573.565511276423;4591.265397536445;4604.792784324646;4178.4957415330455;4345.695801293879;4871.244957031666;4981.850544483671;5375.951689420525;1034.446571084466;1211.5093387178206;1264.8482182138202;1282.903117834257;1339.557945877858;811.6266517401943;880.5421180985851;903.4895284164558;906.8821053263033;906.931980101495;5153.6907785532985;5154.793808247703;5163.380394352702;5184.9740751296;5212.869678258074;5330.813421530228;5595.468430499096;5642;5666.178404131378;5672;4833.371661683958;5086.516780905808;5145.4854514549;5174.412793603198;5182.97408512955;2165.859219150782;2296.312210525263;2502.749725027519;2519.7480251974657;2520.8235423520177;3903.5706072332023;4551.590356867898;4853.78655336166;4898.975505122452;4903.779329930162;3839.6753120173494;4186.581341865789;4272.743635381887;4320.790178749955;4930.482299358516;4462.509123996293;4866.367372241586;4873.415190177201;4895.188780151367;4938.715933857223;3964.6431821136234;4024.4383720407063;4078.0239266714716;4267.018585725238;4391;4361.411209486679;4611.469680986729;4628.074385123056;4663.836765713184;4739.549742774431;4168.666506679519;4853;4891.9755401222765;4900.730459824601;4950.722667572957;2820.661520617539;3075.185075954829;3268.885589004469;3308.586426696663;3309.7848639838335;4074.3277359235394;4262.786860657016;4331.198728235287;4725.9054818904115;5365;4934;5457.008192009967;5488.463230295523;5598.776048958159;5635.098384258663;4487.349334346567;5199.4020687621405;5551.197429760627;5588.748506317307;5608.439156084359;5452.555072905762;5463.699496527729;5467.617266791286;5473.507384335429;5494.642848214853;5188.403333616682;5578.856334451509;5581.5255703265675;5599.628091117702;5600.187972744011;4758.4765675776325;5414.862878795497;5449.400565937823;5487.75305111279;5498.890022199614 21:30:19 INFO - PROCESS | 1645 | |5;yui.html;631.7409861956597;785.2973231214825;807.5962018990505;808.7614153824621;847.4533925617975;15617.297221625025;16563;16572.75140872888;16580.751288730662;16596.419125330616;12816.743665126693;14260.71478570428;14934.327955242014;15138.16740079298;15140.24298785062;883.3948745109589;883.8453270677638;892.9687460938868;894.2890402130322;895.5477483870669;697.713937285713;718.3140101203338;720.1933834105809;720.2437440687283;722.1911459165742;824.6599276176214;903.105925134119;947.0434860790582;949.8052899155656;952.9094736000069;931.860220966857;933.6078846884325;935.7894473743353;936.3492372800906;938.0244545672492;534.636447215893;551.7131091832265;686.1423220973783;688.0745397440435;688.2669956496316;12325.260484370967;12423.378831058411;12607.243565386014;12627.116101872873;15502.30239639219;14750.934869118331;15033.624159395993;15159.696806063926;15181.089134651957;15369.769453458095;11715.7071073223;11719.597482516063;11726.413679316;11965.341906195155;12021.459034343476;3870.5355357357016;3878.45701601776;3937.4684417603544;3954.8615798446917;4108.198901214264;98778;106269.46865265701;106474.46762766215;108468;108565;9250.907490925005;10210.591576336863;11180.776384472265;11268.661940141808;11942.582009629621;19686.212551498065;22892.88553557255;24062.63906041411;24090.51818963611;24112.51774964491;7933.880991785128;7979.3616510679;8015.451472718512;8243.505389676639;8255.834883302421;4348.695591308578;4365.956340436618;4388.341748737715;4487.640988720895;4630.791614377294;3544.663256990582;3654.506641603403;3661.3409586274192;3662.7252956028406;3664.76179048361;566.5863919338823;668.0747165176169;670.1890712238246;673.3401266758603;673.5083389125965;559.4014404587134;567.7189791053455;570.9628874123251;573.4924591736343;614.8647297594522;8848.646054157762;9020.14308640678;9155.72532824003;9301.488418136933;9405.905940940504;485.94897535758946;499.16888380845484;620.2897682153908;633.2685747961112;650.0996120373317;4432.889177770581;4450.3991961085485;4474.910501789946;4487.192305384997;4578.839740609063;96020;96659.51670241603;143933.28033359765;151824;154606;117885;124104.45112165567;132247;135772.32113839567;137794.3110284442;109212.4539377298;113473;113540;114192.42903785428;115890;7478;8635.913640863762;8693.04376518595;8812.383133180616;8905.777355566162;55815.720921395136;58057;60116.39883601108;60179.69910150596;65898;67517;67797;68545;79174;80392.59803700945;10675.626353077916;13822.170669759847;13921.164730116225;14026.509072182425;14256.392416072607;14493.927530362702;14629;14753.778693319395;15308.464203752816;15638.84361156374;13241.47034118663;14209.928950355183;14375.640608984859;14399.208043557705;14792.11247325164;13837.04190763676;14044.227567483886;14468.493602723855;14517.056391334529;14788.186649734367;13245.933770331087;13372.997025222952;13954.23251721165;14317.427302907767;14610.634734131732;11281.32312061279;11368.033717134136;11423.942880285545;11564.537418503165;11627.883721163018;11198.608048718255;11431.085513158761;11540.24988375753;11769;11776.057915366922 21:30:19 INFO - PROCESS | 1645 | __end_tp_report 21:30:19 INFO - PROCESS | 1645 | __start_cc_report 21:30:19 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1518 21:30:19 INFO - PROCESS | 1645 | __end_cc_report 21:30:19 INFO - PROCESS | 1645 | __startTimestamp1468297819962__endTimestamp 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | ------- Summary: start ------- 21:30:19 INFO - PROCESS | 1645 | Number of tests: 6 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5608.83 Median:5717.40 stddev:655.47 (11.5%) stddev-sans-first:617.91 21:30:19 INFO - PROCESS | 1645 | Values: 2749.8 2776.2 3323.1 3334.6 3373.7 5512.0 5527.7 5593.4 5602.3 5621.3 5578.9 5609.0 5638.3 5645.2 5646.4 5097.3 5118.1 5167.1 5186.4 5202.1 5128.5 5217.1 5221.8 5230.7 5235.2 5097.4 5109.7 5130.2 5130.8 5222.5 5191.2 5207.8 5221.6 5231.4 5283.9 4569.9 4802.7 5039.8 5140.2 5143.2 5518.9 5532.1 5565.8 5596.6 5611.4 6107.2 6114.4 6115.8 6117.5 6143.8 5903.5 6010.4 6092.6 6198.7 6221.8 5641.7 5692.0 5704.2 5708.4 5708.6 6262.7 6289.4 6302.0 6327.0 6331.8 5821.2 5872.6 5926.4 5951.3 6023.7 6119.0 6189.5 6198.0 6202.9 6205.3 6089.5 6109.0 6119.9 6123.4 6134.4 5669.7 5697.2 5728.3 6151.7 6167.5 4650.0 4706.5 4708.5 4716.8 4719.5 4296.4 4310.9 4329.4 4332.4 4341.2 5444.3 5625.2 5656.8 5682.9 5748.8 6169.0 6229.9 6247.4 6248.1 6262.0 5668.3 5673.2 5675.3 5680.6 5712.9 5265.6 5310.3 5325.8 5341.5 5341.8 6365.2 6372.6 6377.3 6378.8 6387.8 6235.6 6253.3 6264.3 6264.8 6271.0 6064.3 6104.9 6105.1 6180.9 6359.6 5751.8 5761.7 5766.4 5767.8 5770.8 5368.5 5617.9 5838.2 5844.8 5858.9 5697.8 5756.4 5796.6 5802.4 5827.7 5263.9 5717.4 5863.2 5891.6 6041.5 5789.5 5795.8 5804.4 5812.9 5868.4 5953.7 5992.4 6006.2 6019.9 6042.5 5652.0 5702.3 5763.1 5817.3 5846.9 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:78744.85 Median:38890.51 stddev:181927.10 (467.8%) stddev-sans-first:182342.52 21:30:19 INFO - PROCESS | 1645 | Values: 464.4 489.3 525.5 526.2 542.0 24905.6 40167.8 41081.8 41183.4 41253.4 42194.2 42478.0 42516.4 42517.8 42561.4 8528.8 9520.5 9680.1 9706.9 9708.0 8464.1 8655.8 8661.7 8671.0 11450.0 11603.4 11840.6 11877.9 11882.3 11892.5 11725.0 11770.2 11793.9 11794.5 11795.8 8480.3 11393.9 11585.9 11706.1 11792.6 35367.1 40195.4 40442.6 40788.8 40933.6 3458.4 3571.3 3729.4 3899.1 3937.1 30396.9 31167.1 31964.0 32184.2 32278.8 3250.4 3282.2 3287.5 3309.8 3394.2 537078.0 538188.0 538939.0 539826.0 540036.0 61939.0 62326.7 62330.4 62374.4 62393.0 65378.0 65394.0 65403.0 65490.7 65764.3 16368.8 16571.0 16588.5 16590.5 16616.6 12570.4 12591.9 12604.6 12607.1 12609.7 16099.8 16364.4 16384.3 16726.3 17836.1 7036.7 8384.5 8387.0 8406.8 8453.9 914.4 941.5 986.1 1072.6 1076.5 30228.2 30329.8 30850.8 31056.2 31095.4 1054.1 1063.7 1078.3 1107.5 1123.1 15077.5 15134.6 15269.4 15286.9 15372.3 722911.0 728761.0 728861.0 729797.0 732211.0 48231.0 48783.0 55212.2 55396.2 57286.7 1421.3 1442.4 1449.7 1456.2 1460.5 30036.9 30104.4 30482.7 31149.0 31171.0 42602.6 49626.0 50502.0 50574.0 50621.0 687025.0 691412.0 692557.0 709526.0 916952.0 22500.5 24872.6 25640.0 25770.4 25951.0 45086.5 48140.8 48755.5 49804.8 52711.2 40314.0 40395.2 40892.8 40914.0 41932.4 37253.1 40122.0 40617.8 40839.8 41408.4 38120.2 38432.4 38888.8 38892.2 39074.8 30866.8 33489.5 34211.3 34523.8 35306.5 21518.9 25934.7 26108.3 26275.0 26479.8 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34591.05 Median:25547.54 stddev:100127.05 (391.9%) stddev-sans-first:100374.94 21:30:19 INFO - PROCESS | 1645 | Values: 357.4 373.3 383.8 385.9 402.5 15473.0 15752.4 19814.3 20503.2 20576.4 20206.1 20646.7 20962.9 20989.1 21129.9 4758.9 5228.5 5407.3 5408.6 5433.1 4230.0 4245.4 4315.7 4324.3 4337.8 3741.4 4247.4 4276.5 4306.4 4325.8 4829.7 5935.8 5939.9 5948.4 5954.0 5044.2 5243.6 5270.8 5274.6 5281.1 25509.0 25586.1 25659.7 26052.7 26102.6 2033.4 2226.2 2304.3 2818.0 3008.7 8032.4 9651.6 10139.7 10512.2 10550.4 1848.9 1858.0 1941.5 1948.6 1957.0 116511.8 116642.8 119769.0 121939.8 122499.8 11596.9 11688.0 11688.2 11718.5 11895.8 14246.1 14431.8 14557.4 14579.0 14586.0 7141.0 7204.0 8580.0 8631.2 8656.1 7108.4 8572.3 8577.6 8578.4 8714.0 4567.6 4600.5 4641.4 4687.6 4712.2 1676.5 1768.8 1905.6 1921.2 1950.1 728.9 946.4 963.4 993.0 1032.2 20246.6 21141.3 21171.9 21371.6 21371.9 956.3 965.1 1026.6 1036.1 1058.5 11006.6 11293.9 11299.0 11411.6 11443.7 592723.0 600602.0 600863.0 603190.0 608396.0 95388.5 118883.0 119810.4 123930.4 134912.3 962.7 977.7 985.8 1016.7 1017.1 18733.5 19074.8 19120.9 19199.5 19234.7 42562.7 47775.8 52199.5 52341.2 52454.0 65865.0 73777.0 75054.0 75203.0 85815.0 8050.4 8125.6 8222.8 8229.9 8317.2 11706.5 12856.9 12915.9 12945.2 13099.2 11004.8 11134.3 11310.4 11485.4 11491.1 10979.5 11119.6 11138.4 11309.9 11378.5 10850.5 11451.8 11726.8 11745.9 11817.5 8160.8 8207.6 8209.6 8269.6 8402.8 8824.0 10598.2 11388.9 11413.3 11413.7 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5742.64 Median:4887.89 stddev:3205.69 (65.6%) stddev-sans-first:3199.19 21:30:19 INFO - PROCESS | 1645 | Values: 1548.7 1578.0 1778.3 1922.1 1923.4 8559.7 8783.8 8981.3 8988.3 8991.0 7537.8 7725.3 8456.0 8707.7 8804.2 2333.9 2363.6 2364.8 2366.8 2372.0 2329.9 2340.1 2384.4 2389.1 2389.7 1981.2 2036.1 2076.9 2141.3 2151.5 2268.0 2366.4 2367.2 2370.9 2373.0 2061.5 2377.5 2405.8 2406.8 2415.8 8766.0 8820.5 8838.6 8848.0 8848.9 5183.7 5340.1 5340.6 5345.7 5345.9 3496.2 3581.3 3649.6 3661.0 3666.2 1057.0 1095.7 1100.0 1107.9 1115.0 11511.5 11691.8 11729.1 11740.5 11817.3 4620.6 4715.7 4830.0 4842.4 4860.3 10066.3 10198.0 10200.7 10208.2 10211.8 5355.6 5435.7 5436.4 5437.9 5443.4 3959.3 4856.6 4915.5 4933.8 4956.4 3798.1 3821.4 3877.7 3909.6 3915.1 1224.2 1243.2 1245.6 1259.1 1261.7 857.0 864.4 890.3 899.7 982.2 5959.2 6126.3 6136.6 6144.0 6279.4 927.0 966.4 980.8 994.1 1007.2 3906.7 3917.6 4054.8 4102.1 4108.2 10003.0 10730.2 10746.7 10761.9 10764.4 10395.5 10538.3 10676.1 10694.1 10699.9 8174.3 8295.8 8330.0 8339.0 8349.8 5875.9 5878.8 5918.0 6059.8 6120.8 8758.6 9756.2 10062.0 10185.2 10210.9 10328.8 10365.1 10379.8 10388.2 10398.1 7083.5 7231.1 7236.1 7238.2 7240.5 8018.0 8171.7 8228.7 8248.9 8253.4 7560.2 7641.8 7641.8 7661.2 7782.9 7506.8 7520.6 7585.4 7622.7 7659.9 6996.7 7141.7 7148.2 7154.2 7154.3 4936.3 5014.3 5091.0 5099.1 5222.4 5088.3 5111.1 5133.3 5150.7 5353.2 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4203.45 Median:4581.12 stddev:1297.45 (28.3%) stddev-sans-first:1267.10 21:30:19 INFO - PROCESS | 1645 | Values: 272.9 287.3 306.5 306.7 313.7 4182.0 4338.5 4972.8 4992.6 5100.9 4410.4 5014.2 5059.7 5085.9 5089.5 3569.8 4088.0 4099.0 4103.3 4115.9 3292.5 3551.9 3759.5 4046.4 4129.2 3328.6 3683.2 3762.2 3958.8 4020.2 3463.7 3508.4 3884.6 3892.3 4030.2 2898.3 3050.8 3466.2 3496.3 3519.5 3854.2 4885.8 4911.7 5010.2 5210.3 4240.8 4321.4 4515.7 4580.3 4582.0 4626.9 5110.3 5553.3 5582.8 5630.8 3079.4 3825.6 3843.8 3845.4 3845.9 4648.8 5240.9 5535.5 5741.9 5761.0 3851.0 3866.3 3866.7 3878.3 3907.0 3468.7 3476.1 3916.2 3970.6 3972.0 4407.0 4540.9 4573.6 4591.3 4604.8 4178.5 4345.7 4871.2 4981.9 5376.0 1034.4 1211.5 1264.8 1282.9 1339.6 811.6 880.5 903.5 906.9 906.9 5153.7 5154.8 5163.4 5185.0 5212.9 5330.8 5595.5 5642.0 5666.2 5672.0 4833.4 5086.5 5145.5 5174.4 5183.0 2165.9 2296.3 2502.7 2519.7 2520.8 3903.6 4551.6 4853.8 4899.0 4903.8 3839.7 4186.6 4272.7 4320.8 4930.5 4462.5 4866.4 4873.4 4895.2 4938.7 3964.6 4024.4 4078.0 4267.0 4391.0 4361.4 4611.5 4628.1 4663.8 4739.5 4168.7 4853.0 4892.0 4900.7 4950.7 2820.7 3075.2 3268.9 3308.6 3309.8 4074.3 4262.8 4331.2 4725.9 5365.0 4934.0 5457.0 5488.5 5598.8 5635.1 4487.3 5199.4 5551.2 5588.7 5608.4 5452.6 5463.7 5467.6 5473.5 5494.6 5188.4 5578.9 5581.5 5599.6 5600.2 4758.5 5414.9 5449.4 5487.8 5498.9 21:30:19 INFO - PROCESS | 1645 | 21:30:19 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:23808.54 Median:3767.65 stddev:37321.05 (990.6%) stddev-sans-first:37385.17 21:30:19 INFO - PROCESS | 1645 | Values: 631.7 785.3 807.6 808.8 847.5 15617.3 16563.0 16572.8 16580.8 16596.4 12816.7 14260.7 14934.3 15138.2 15140.2 883.4 883.8 893.0 894.3 895.5 697.7 718.3 720.2 720.2 722.2 824.7 903.1 947.0 949.8 952.9 931.9 933.6 935.8 936.3 938.0 534.6 551.7 686.1 688.1 688.3 12325.3 12423.4 12607.2 12627.1 15502.3 14750.9 15033.6 15159.7 15181.1 15369.8 11715.7 11719.6 11726.4 11965.3 12021.5 3870.5 3878.5 3937.5 3954.9 4108.2 98778.0 106269.5 106474.5 108468.0 108565.0 9250.9 10210.6 11180.8 11268.7 11942.6 19686.2 22892.9 24062.6 24090.5 24112.5 7933.9 7979.4 8015.5 8243.5 8255.8 4348.7 4366.0 4388.3 4487.6 4630.8 3544.7 3654.5 3661.3 3662.7 3664.8 566.6 668.1 670.2 673.3 673.5 559.4 567.7 571.0 573.5 614.9 8848.6 9020.1 9155.7 9301.5 9405.9 485.9 499.2 620.3 633.3 650.1 4432.9 4450.4 4474.9 4487.2 4578.8 96020.0 96659.5 143933.3 151824.0 154606.0 117885.0 124104.5 132247.0 135772.3 137794.3 109212.5 113473.0 113540.0 114192.4 115890.0 7478.0 8635.9 8693.0 8812.4 8905.8 55815.7 58057.0 60116.4 60179.7 65898.0 67517.0 67797.0 68545.0 79174.0 80392.6 10675.6 13822.2 13921.2 14026.5 14256.4 14493.9 14629.0 14753.8 15308.5 15638.8 13241.5 14209.9 14375.6 14399.2 14792.1 13837.0 14044.2 14468.5 14517.1 14788.2 13245.9 13373.0 13954.2 14317.4 14610.6 11281.3 11368.0 11423.9 11564.5 11627.9 11198.6 11431.1 11540.2 11769.0 11776.1 21:30:19 INFO - PROCESS | 1645 | -------- Summary: end -------- 21:30:19 INFO - PROCESS | 1645 | 21:30:20 INFO - TEST-INFO | 1645: exit 0 21:30:20 INFO - TEST-OK | dromaeo_css | took 1116346ms 21:30:20 INFO - TEST-START | kraken 21:30:20 INFO - operating with platform_type : mac_ 21:30:20 INFO - Initialising browser for kraken test... 21:30:20 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmp9sx3zO/profile http://localhost:49271/getInfo.html) 21:30:22 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 21:30:22 INFO - PROCESS | 1655 | colorDepth:24 21:30:22 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 21:30:22 INFO - PROCESS | 1655 | __metrics 21:30:22 INFO - PROCESS | 1655 | 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" 21:30:22 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:30:23 INFO - TEST-INFO | 1655: exit 0 21:30:23 INFO - Browser initialized. 21:30:23 INFO - Running cycle 1/1 for kraken test... 21:30:23 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmp9sx3zO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:30:35 INFO - PROCESS | 1656 | RSS: Main: 243822592 21:30:35 INFO - PROCESS | 1656 | 21:30:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 21:30:39 INFO - PROCESS | 1656 | RSS: Main: 294334464 21:30:39 INFO - PROCESS | 1656 | 21:30:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 21:30:42 INFO - PROCESS | 1656 | RSS: Main: 304107520 21:30:42 INFO - PROCESS | 1656 | 21:30:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 21:30:46 INFO - PROCESS | 1656 | RSS: Main: 386117632 21:30:46 INFO - PROCESS | 1656 | 21:30:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 21:30:49 INFO - PROCESS | 1656 | RSS: Main: 290594816 21:30:49 INFO - PROCESS | 1656 | 21:30:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:30:51 INFO - PROCESS | 1656 | RSS: Main: 293838848 21:30:51 INFO - PROCESS | 1656 | 21:30:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 21:30:55 INFO - PROCESS | 1656 | RSS: Main: 330084352 21:30:55 INFO - PROCESS | 1656 | 21:30:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 21:30:58 INFO - PROCESS | 1656 | RSS: Main: 330915840 21:30:58 INFO - PROCESS | 1656 | 21:31:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 21:31:02 INFO - PROCESS | 1656 | RSS: Main: 329166848 21:31:02 INFO - PROCESS | 1656 | 21:31:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:31:04 INFO - PROCESS | 1656 | RSS: Main: 311463936 21:31:04 INFO - PROCESS | 1656 | 21:31:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:31:06 INFO - PROCESS | 1656 | RSS: Main: 318427136 21:31:06 INFO - PROCESS | 1656 | 21:31:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:31:09 INFO - PROCESS | 1656 | RSS: Main: 318304256 21:31:09 INFO - PROCESS | 1656 | 21:31:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:31:13 INFO - PROCESS | 1656 | RSS: Main: 326815744 21:31:13 INFO - PROCESS | 1656 | 21:31:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:31:16 INFO - PROCESS | 1656 | RSS: Main: 329908224 21:31:16 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 21:31:18 INFO - PROCESS | 1656 | RSS: Main: 373248000 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | __start_tp_report 21:31:18 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 21:31:18 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 21:31:18 INFO - PROCESS | 1656 | |i|pagename|runs| 21:31:18 INFO - PROCESS | 1656 | |0;ai-astar;100;112;115;112;113;112;111;112;112;112 21:31:18 INFO - PROCESS | 1656 | |1;audio-beat-detection;166;107;106;159;107;105;130;108;139;106 21:31:18 INFO - PROCESS | 1656 | |2;audio-dft;185;182;184;235;260;183;182;185;249;183 21:31:18 INFO - PROCESS | 1656 | |3;audio-fft;77;72;57;107;58;57;62;60;98;57 21:31:18 INFO - PROCESS | 1656 | |4;audio-oscillator;82;90;92;70;72;67;97;70;68;102 21:31:18 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;90;83;89;85;86;84;93;129;115;85 21:31:18 INFO - PROCESS | 1656 | |6;imaging-darkroom;94;97;95;94;93;94;93;94;94;93 21:31:18 INFO - PROCESS | 1656 | |7;imaging-desaturate;86;88;86;85;85;86;86;83;87;86 21:31:18 INFO - PROCESS | 1656 | |8;json-parse-financial;56;54;54;55;55;55;54;54;55;54 21:31:18 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;66;66;78;79;72;70;71;71;71;72 21:31:18 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;86;81;81;85;84;81;83;83;85;84 21:31:18 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;137;139;137;138;132;139;145;145;233;136 21:31:18 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;162;162;159;162;161;161;162;161;164;161 21:31:18 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;66;68;66;66;66;65;65;65;64;64 21:31:18 INFO - PROCESS | 1656 | __end_tp_report 21:31:18 INFO - PROCESS | 1656 | __start_cc_report 21:31:18 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1996 21:31:18 INFO - PROCESS | 1656 | __end_cc_report 21:31:18 INFO - PROCESS | 1656 | __startTimestamp1468297878498__endTimestamp 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | ------- Summary: start ------- 21:31:18 INFO - PROCESS | 1656 | Number of tests: 14 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:4.04 (3.6%) stddev-sans-first:1.12 21:31:18 INFO - PROCESS | 1656 | Values: 100.0 112.0 115.0 112.0 113.0 112.0 111.0 112.0 112.0 112.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:119.00 stddev:23.78 (20.0%) stddev-sans-first:19.56 21:31:18 INFO - PROCESS | 1656 | Values: 166.0 107.0 106.0 159.0 107.0 105.0 130.0 108.0 139.0 106.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:202.80 Median:185.00 stddev:31.76 (17.2%) stddev-sans-first:33.03 21:31:18 INFO - PROCESS | 1656 | Values: 185.0 182.0 184.0 235.0 260.0 183.0 182.0 185.0 249.0 183.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:70.50 Median:61.00 stddev:18.31 (30.0%) stddev-sans-first:19.27 21:31:18 INFO - PROCESS | 1656 | Values: 77.0 72.0 57.0 107.0 58.0 57.0 62.0 60.0 98.0 57.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:77.00 stddev:13.28 (17.3%) stddev-sans-first:14.08 21:31:18 INFO - PROCESS | 1656 | Values: 82.0 90.0 92.0 70.0 72.0 67.0 97.0 70.0 68.0 102.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:93.90 Median:85.50 stddev:15.47 (18.1%) stddev-sans-first:16.35 21:31:18 INFO - PROCESS | 1656 | Values: 90.0 83.0 89.0 85.0 86.0 84.0 93.0 129.0 115.0 85.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:1.20 (1.3%) stddev-sans-first:1.27 21:31:18 INFO - PROCESS | 1656 | Values: 94.0 97.0 95.0 94.0 93.0 94.0 93.0 94.0 94.0 93.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:1.32 (1.5%) stddev-sans-first:1.39 21:31:18 INFO - PROCESS | 1656 | Values: 86.0 88.0 86.0 85.0 85.0 86.0 86.0 83.0 87.0 86.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.70 (1.3%) stddev-sans-first:0.53 21:31:18 INFO - PROCESS | 1656 | Values: 56.0 54.0 54.0 55.0 55.0 55.0 54.0 54.0 55.0 54.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:71.50 stddev:4.25 (5.9%) stddev-sans-first:3.99 21:31:18 INFO - PROCESS | 1656 | Values: 66.0 66.0 78.0 79.0 72.0 70.0 71.0 71.0 71.0 72.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:84.00 stddev:1.83 (2.2%) stddev-sans-first:1.66 21:31:18 INFO - PROCESS | 1656 | Values: 86.0 81.0 81.0 85.0 84.0 81.0 83.0 83.0 85.0 84.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:139.00 stddev:30.09 (21.6%) stddev-sans-first:31.64 21:31:18 INFO - PROCESS | 1656 | Values: 137.0 139.0 137.0 138.0 132.0 139.0 145.0 145.0 233.0 136.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.00 stddev:1.27 (0.8%) stddev-sans-first:1.33 21:31:18 INFO - PROCESS | 1656 | Values: 162.0 162.0 159.0 162.0 161.0 161.0 162.0 161.0 164.0 161.0 21:31:18 INFO - PROCESS | 1656 | 21:31:18 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.18 (1.8%) stddev-sans-first:1.24 21:31:18 INFO - PROCESS | 1656 | Values: 66.0 68.0 66.0 66.0 66.0 65.0 65.0 65.0 64.0 64.0 21:31:18 INFO - PROCESS | 1656 | -------- Summary: end -------- 21:31:18 INFO - PROCESS | 1656 | 21:31:19 INFO - TEST-INFO | 1656: exit 0 21:31:19 INFO - TEST-OK | kraken | took 58765ms 21:31:19 INFO - SUITE-END | took 1175s 21:31:19 INFO - Completed test suite (00:19:35) 21:31:19 INFO - javascript benchmark 21:31:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2749.780017598592, 2776.1810265971535, 3323.069540528652, 3334.6331903490595, 3373.6795004474566, 5512.0, 5527.695976721278, 5593.356763972144, 5602.32772067352, 5621.269234999455, 5578.9163162552595, 5609.046462101443, 5638.323401191863, 5645.15322701595, 5646.378898321192, 5097.3373461449955, 5118.053160165363, 5167.121589329823, 5186.377634683843, 5202.089634313998, 5128.53843154116, 5217.087009773293, 5221.817236396727, 5230.712310822903, 5235.188545775411, 5097.388313402378, 5109.744512774365, 5130.153524668425, 5130.820421285256, 5222.5038621331105, 5191.221316802491, 5207.817726379596, 5221.6083793715625, 5231.424543300234, 5283.86790330241, 4569.908601827978, 4802.687825291345, 5039.77321020555, 5140.203268493352, 5143.151380022292, 5518.889622207574, 5532.059549876531, 5565.833025009256, 5596.608237423382, 5611.438856114356, 6107.235399641211, 6114.388561143895, 6115.755369785248, 6117.510599152057, 6143.846403839895, 5903.49820265278, 6010.429009244115, 6092.573519853612, 6198.721057552421, 6221.844453888643, 5641.746121424546, 5692.0, 5704.22992895962, 5708.372079071299, 5708.628939119027, 6262.6868656566985, 6289.40250676185, 6301.960176570815, 6327.0, 6331.841703957346, 5821.243238379068, 5872.618279811799, 5926.377730338339, 5951.256092988377, 6023.66869822164, 6118.984682626245, 6189.473894718909, 6197.96901015492, 6202.875942481125, 6205.348438413992, 6089.543284253698, 6109.0, 6119.931479141598, 6123.38766123393, 6134.40551407239, 5669.659820410789, 5697.23087383198, 5728.312602487645, 6151.72317245725, 6167.506599472032, 4650.000249946278, 4706.458757242893, 4708.458527269339, 4716.764161791897, 4719.457262414798, 4296.3555466680245, 4310.913781724348, 4329.372241025097, 4332.371806088099, 4341.240282950471, 5444.292242008513, 5625.240592519957, 5656.830295091154, 5682.948654498931, 5748.798792042258, 6169.0, 6229.875402491924, 6247.375262473807, 6248.125262463259, 6262.0, 5668.285279223053, 5673.177389278531, 5675.318961724537, 5680.602357834912, 5712.885742285214, 5265.605079619044, 5310.336207974004, 5325.760340784674, 5341.5192632662465, 5341.839744807662, 6365.204349456318, 6372.55392122547, 6377.3303803100935, 6378.808635741028, 6387.840303992437, 6235.594686345373, 6253.3121356651645, 6264.34224406449, 6264.843378915381, 6270.968645156745, 6064.272287325549, 6104.8779024420155, 6105.053716673922, 6180.907286390798, 6359.554831161774, 5751.827445176651, 5761.740721667619, 5766.423357664368, 5767.826965191051, 5770.769169233184, 5368.489993450616, 5617.915731264116, 5838.1826544282985, 5844.824655260349, 5858.912116318344, 5697.800576979786, 5756.366799651951, 5796.62321949072, 5802.390748971297, 5827.737751801094, 5263.868403289795, 5717.399673034438, 5863.237779088862, 5891.558133140117, 6041.486473649613, 5789.45000224978, 5795.797147099831, 5804.448577385142, 5812.883742325214, 5868.38381969887, 5953.7023148843255, 5992.43071908168, 6006.249218847644, 6019.939800602113, 6042.546808989256, 5651.971740141273, 5702.34423041339, 5763.106718458478, 5817.331006934257, 5846.853828654318], "value": 5563.60556084418, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.3707775963569, 489.2954146029717, 525.4692760312086, 526.208056874404, 541.9675518137946, 24905.62641560378, 40167.79916100419, 41081.79459102701, 41183.38224926629, 41253.381199282034, 42194.156116877646, 42478.0, 42516.36225456621, 42517.7874110629, 42561.36157957625, 8528.829423411558, 9520.523973801317, 9680.128788409042, 9706.902930970706, 9708.029197080306, 8464.06895241523, 8655.826883462327, 8661.653533858638, 8671.0, 11450.0, 11603.361815100166, 11840.644780656594, 11877.940610296937, 11882.346470944094, 11892.464839082262, 11725.0, 11770.2349347293, 11793.94103029488, 11794.469248883821, 11795.764084718257, 8480.279176270025, 11393.943030284878, 11585.942070289595, 11706.063514918786, 11792.587259445878, 35367.115822104395, 40195.39706904399, 40442.59557404418, 40788.796056019826, 40933.59066409327, 3458.394780913355, 3571.3035957988204, 3729.440583912408, 3899.1315932058556, 3937.0944682722848, 30396.93610723636, 31167.06498805017, 31964.0, 32184.195395115075, 32278.838605807057, 3250.366178595175, 3282.1630484226607, 3287.4575695010294, 3309.8179600121985, 3394.2193295542165, 537078.0, 538188.0, 538939.0, 539826.0, 540036.0, 61939.0, 62326.68836655788, 62330.37669623246, 62374.37625623686, 62393.0, 65378.01932971009, 65394.0, 65403.01895471572, 65490.67254663696, 65764.34235657677, 16368.83631163697, 16571.00573965566, 16588.50234492967, 16590.50228493147, 16616.58458538522, 12570.43433045497, 12591.87408125907, 12604.558840440724, 12607.054470914718, 12609.684757881127, 16099.839001609835, 16364.427245046605, 16384.26270817816, 16726.330946761995, 17836.08008389369, 7036.683349249296, 8384.455010424303, 8387.0, 8406.831863362699, 8453.915460845436, 914.3964983111115, 941.5245301122889, 986.0878687214298, 1072.587053984206, 1076.4617691154422, 30228.24429389283, 30329.848350758104, 30850.845745771127, 31056.22359440987, 31095.378092438477, 1054.0987455725244, 1063.6702622187147, 1078.266778590566, 1107.534835369147, 1123.07346439188, 15077.472288469851, 15134.621634459227, 15269.428243856195, 15286.923565382102, 15372.30824612895, 722911.0, 728761.0, 728861.0, 729797.0, 732211.0, 48231.03537929222, 48783.0, 55212.17181742277, 55396.16905746417, 57286.7135664319, 1421.289355322339, 1442.4086124688827, 1449.6883170118474, 1456.2427537680346, 1460.5034288342015, 30036.948706795156, 30104.397912042074, 30482.695173047985, 31149.0, 31171.0, 42602.573974261104, 49626.0, 50502.0, 50574.0, 50621.0, 687025.0, 691412.0, 692557.0, 709526.0, 916952.0, 22500.549989000454, 24872.626910595995, 25640.0, 25770.35574110662, 25950.961961522084, 45086.549134508234, 48140.759296203294, 48755.51244487651, 49804.75097624489, 52711.20933186082, 40313.96075490564, 40395.1920961585, 40892.79553602213, 40914.0, 41932.37101443542, 37253.068673283386, 40121.99695007648, 40617.78146655605, 40839.7958010208, 41408.37887431631, 38120.23759524739, 38432.423513647875, 38888.80555597204, 38892.2221555573, 39074.804625976685, 30866.84566577153, 33489.49765753564, 34211.31577368489, 34523.82738086394, 35306.47040294449, 21518.8924055385, 25934.74065259323, 26108.30296030752, 26275.0, 26479.808408621273], "value": 20637.301303969896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.35319072478796, 373.2963364058749, 383.76590279929246, 385.8919502539288, 402.4828095896771, 15473.0, 15752.448664296751, 19814.306499272527, 20503.17987280509, 20576.38270851873, 20206.090725917333, 20646.690299645517, 20962.895185524052, 20989.055492502837, 21129.894350528226, 4758.857234282959, 5228.529432351097, 5407.324084489439, 5408.648437851547, 5433.130699088147, 4230.048239146183, 4245.405643209953, 4315.654747620199, 4324.308110702288, 4337.804798784062, 3741.38267185914, 4247.362895565659, 4276.486821581399, 4306.440162778835, 4325.848595299165, 4829.734364609945, 5935.821925342247, 5939.881202375971, 5948.37542058082, 5954.047352423613, 5044.218146187347, 5243.6067294952645, 5270.8155214567305, 5274.55166310864, 5281.0758117329315, 25508.97964081453, 25586.104486343076, 25659.74340256592, 26052.739472605223, 26102.608460873103, 2033.4001469566365, 2226.1874415838265, 2304.331743794298, 2818.0136952066614, 3008.7292143706954, 8032.357411407073, 9651.600517924931, 10139.746506337327, 10512.21158413122, 10550.419726915017, 1848.9167987440462, 1857.9535511612182, 1941.5340318323642, 1948.581055073152, 1957.0410498855601, 116511.83488165095, 116642.83357166404, 119769.0, 121939.78060219462, 122499.77500224973, 11596.94201528987, 11688.0, 11688.18182727201, 11718.531258749725, 11895.762084758257, 14246.074005189837, 14431.783523247159, 14557.41770329175, 14579.0, 14586.0, 7140.964560138751, 7203.96398018017, 8580.0, 8631.223189912813, 8656.13438656142, 7108.395786358217, 8572.271356934605, 8577.614007369682, 8578.399512034222, 8713.997890242577, 4567.566081222279, 4600.470945841271, 4641.350210970467, 4687.5781179693995, 4712.175369310357, 1676.522191175509, 1768.7789026371704, 1905.6093500832296, 1921.193098898466, 1950.0542237015036, 728.9343959043712, 946.3990366117565, 963.3738070254251, 993.036754348281, 1032.2206733915846, 20246.595068098555, 21141.26005589797, 21171.8941405292, 21371.57256854854, 21371.89314053451, 956.3305686019814, 965.1313817564248, 1026.609888242478, 1036.1451802263164, 1058.4972138234432, 11006.559737610405, 11293.94353028246, 11299.0, 11411.600593979338, 11443.713907152387, 592723.0, 600602.0, 600863.0, 603190.0, 608396.0, 95388.52305738427, 118883.0, 119810.40094799644, 123930.38034809768, 134912.32543837218, 962.7159987803615, 977.7115750853516, 985.7584891701579, 1016.6797458800454, 1017.0998666180484, 18733.531661708566, 19074.80925190786, 19120.904395477934, 19199.52001199981, 19234.71147932754, 42562.7231183065, 47775.76112119417, 52199.47800522098, 52341.21488177757, 52454.0, 65865.0, 73777.0, 75054.0, 75203.0, 85815.0, 8050.355971522381, 8125.634346454305, 8222.794430139294, 8229.917700822914, 8317.209865062809, 11706.531738730355, 12856.935715321364, 12915.870841291468, 12945.158564693265, 13099.2140471572, 11004.834927476255, 11134.33194008362, 11310.377929213972, 11485.425728713364, 11491.080713543064, 10979.451027448436, 11119.610813621484, 11138.443077846237, 11309.943450282695, 11378.487968041623, 10850.45747712627, 11451.828222576834, 11726.765464690488, 11745.941270293593, 11817.468213930566, 8160.8367832641825, 8207.589620518831, 8209.589520524069, 8269.586520673822, 8402.831943360976, 8823.955880220557, 10598.20513461478, 11388.886111138783, 11413.31520108763, 11413.714657133305], "value": 9340.35797658179, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1548.674778296558, 1578.021626591513, 1778.2797966823432, 1922.0870086708806, 1923.4422017614893, 8559.700410485635, 8783.824323513525, 8981.326400519954, 8988.280937524998, 8990.955045224764, 7537.773866784005, 7725.266099720531, 8456.0, 8707.65169393225, 8804.163604457566, 2333.9299821005357, 2363.61000434928, 2364.798992085675, 2366.7633236676365, 2371.976280237193, 2329.860208387502, 2340.1224540797202, 2384.3800611840875, 2389.0921449849034, 2389.713234411873, 1981.1679094780227, 2036.1041141897592, 2076.9376918692465, 2141.250562303197, 2151.4728891421623, 2268.0, 2366.3847399676206, 2367.171489978511, 2370.9051637934463, 2373.0151986925407, 2061.5258490547258, 2377.5244951009868, 2405.819563532742, 2406.8074554035634, 2415.770501802326, 8766.0, 8820.470771753651, 8838.60226289812, 8848.0, 8848.911510884873, 5183.740812959337, 5340.065488539529, 5340.599455040849, 5345.652532585408, 5345.946540534584, 3496.17839807645, 3581.3374525712566, 3649.5620525537106, 3661.0481274868725, 3666.2300916807235, 1056.973575660607, 1095.7205912492343, 1099.9945000275027, 1107.850440190572, 1114.9777004459947, 11511.48198331077, 11691.824622630667, 11729.120315976335, 11740.530378784924, 11817.290962542274, 4620.630349572026, 4715.74063426508, 4830.0, 4842.370491836109, 4860.3195552622665, 10066.345687530287, 10198.0, 10200.693979180636, 10208.234382421348, 10211.846822297672, 5355.625106242525, 5435.673859568438, 5436.429174936654, 5437.918431223535, 5443.428440013741, 3959.3467077932396, 4856.63575231852, 4915.459299477055, 4933.753312334369, 4956.35567376246, 3798.1264309209037, 3821.3694740367528, 3877.65101140893, 3909.6285852843935, 3915.1190982029384, 1224.20426722631, 1243.2354102227066, 1245.5516014234868, 1259.0871618077003, 1261.6971926737701, 856.9828603427896, 864.3992425264381, 890.2966656341417, 899.7120921305119, 982.1553464020936, 5959.165716799652, 6126.326104128542, 6136.570440069152, 6143.96928015366, 6279.4332828941015, 927.0345327815529, 966.3622009473713, 980.8185485685171, 994.1003391930315, 1007.2445665750687, 3906.746061505993, 3917.5886531914307, 4054.7769872656695, 4102.118044620421, 4108.239975604522, 10003.0, 10730.19523535738, 10746.73133171677, 10761.946190268998, 10764.3541387517, 10395.532201050972, 10538.315009524356, 10676.145908327315, 10694.091002264895, 10699.94650026745, 8174.3051840593025, 8295.792605184797, 8330.0, 8339.0, 8349.8330033399, 5875.911861322169, 5878.764849406147, 5918.00885722058, 6059.818205453844, 6120.846978825565, 8758.60586273632, 9756.170725488413, 10062.0, 10185.236107291834, 10210.948945255523, 10328.845067324146, 10365.06714395708, 10379.84430233532, 10388.168946484111, 10398.064174224053, 7083.539569927938, 7231.059962204879, 7236.059312289369, 7238.23998480173, 7240.515694282764, 8018.0, 8171.673133074848, 8228.711995080383, 8248.876266856121, 8253.380996425412, 7560.206178351414, 7641.7707468776025, 7641.7707468776025, 7661.233876612294, 7782.92217077822, 7506.849863002819, 7520.623968801428, 7585.355244804033, 7622.733204337822, 7659.88510172359, 6996.650167491503, 7141.714331426685, 7148.213696493485, 7154.2130365658695, 7154.284571543012, 4936.3089167515855, 5014.297998280316, 5090.974545127251, 5099.107656160231, 5222.373315202048, 5088.2876397305145, 5111.080005599028, 5133.307003554472, 5150.690958542499, 5353.197020446964], "value": 4617.098075412643, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.8812966359632, 287.2918256497735, 306.4728666693288, 306.68258352605056, 313.6910143508644, 4181.958180418195, 4338.4793824741, 4972.751362431882, 4992.550670439659, 5100.923486147706, 4410.426644536214, 5014.197728363462, 5059.671121377117, 5085.898282034357, 5089.541941225289, 3569.7501174917766, 4088.0, 4098.9590104099025, 4103.281925662997, 4115.8765237042935, 3292.489664102068, 3551.875684351048, 3759.5488541375075, 4046.4132700758373, 4129.2154490646835, 3328.6338502764675, 3683.2449347883753, 3762.2475504898994, 3958.7624742515395, 4020.1758639478835, 3463.6709512596262, 3508.438649816017, 3884.611538846107, 3892.3188442022533, 4030.2141082488633, 2898.318895059659, 3050.7559395248327, 3466.2374277658873, 3496.335696217736, 3519.4544845548726, 3854.1713531590563, 4885.780139893714, 4911.709154866248, 5010.248462730583, 5210.296609957645, 4240.809163587646, 4321.395004699345, 4515.706479078882, 4580.267157254823, 4581.977090114561, 4626.86119416418, 5110.310108135392, 5553.278073850414, 5582.7766889324375, 5630.774769009235, 3079.4303053935096, 3825.598312177237, 3843.8078096095364, 3845.3847384418636, 3845.9230815383535, 4648.767561621906, 5240.947590524046, 5535.533083732814, 5741.8564535886935, 5761.020626493464, 3851.0372406898277, 3866.2935944838728, 3866.6713329367317, 3878.301905657009, 3906.980465097656, 3468.6878180963836, 3476.0627269956844, 3916.2167566486246, 3970.6227908349238, 3972.0467087898696, 4406.986393129599, 4540.864783804049, 4573.565511276423, 4591.265397536445, 4604.792784324646, 4178.4957415330455, 4345.695801293879, 4871.244957031666, 4981.850544483671, 5375.951689420525, 1034.446571084466, 1211.5093387178206, 1264.8482182138202, 1282.903117834257, 1339.557945877858, 811.6266517401943, 880.5421180985851, 903.4895284164558, 906.8821053263033, 906.931980101495, 5153.6907785532985, 5154.793808247703, 5163.380394352702, 5184.9740751296, 5212.869678258074, 5330.813421530228, 5595.468430499096, 5642.0, 5666.178404131378, 5672.0, 4833.371661683958, 5086.516780905808, 5145.4854514549, 5174.412793603198, 5182.97408512955, 2165.859219150782, 2296.312210525263, 2502.749725027519, 2519.7480251974657, 2520.8235423520177, 3903.5706072332023, 4551.590356867898, 4853.78655336166, 4898.975505122452, 4903.779329930162, 3839.6753120173494, 4186.581341865789, 4272.743635381887, 4320.790178749955, 4930.482299358516, 4462.509123996293, 4866.367372241586, 4873.415190177201, 4895.188780151367, 4938.715933857223, 3964.6431821136234, 4024.4383720407063, 4078.0239266714716, 4267.018585725238, 4391.0, 4361.411209486679, 4611.469680986729, 4628.074385123056, 4663.836765713184, 4739.549742774431, 4168.666506679519, 4853.0, 4891.9755401222765, 4900.730459824601, 4950.722667572957, 2820.661520617539, 3075.185075954829, 3268.885589004469, 3308.586426696663, 3309.7848639838335, 4074.3277359235394, 4262.786860657016, 4331.198728235287, 4725.9054818904115, 5365.0, 4934.0, 5457.008192009967, 5488.463230295523, 5598.776048958159, 5635.098384258663, 4487.349334346567, 5199.4020687621405, 5551.197429760627, 5588.748506317307, 5608.439156084359, 5452.555072905762, 5463.699496527729, 5467.617266791286, 5473.507384335429, 5494.642848214853, 5188.403333616682, 5578.856334451509, 5581.5255703265675, 5599.628091117702, 5600.187972744011, 4758.4765675776325, 5414.862878795497, 5449.400565937823, 5487.75305111279, 5498.890022199614], "value": 3799.536401052669, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.7409861956597, 785.2973231214825, 807.5962018990505, 808.7614153824621, 847.4533925617975, 15617.297221625025, 16563.0, 16572.75140872888, 16580.751288730662, 16596.419125330616, 12816.743665126693, 14260.71478570428, 14934.327955242014, 15138.16740079298, 15140.24298785062, 883.3948745109589, 883.8453270677638, 892.9687460938868, 894.2890402130322, 895.5477483870669, 697.713937285713, 718.3140101203338, 720.1933834105809, 720.2437440687283, 722.1911459165742, 824.6599276176214, 903.105925134119, 947.0434860790582, 949.8052899155656, 952.9094736000069, 931.860220966857, 933.6078846884325, 935.7894473743353, 936.3492372800906, 938.0244545672492, 534.636447215893, 551.7131091832265, 686.1423220973783, 688.0745397440435, 688.2669956496316, 12325.260484370967, 12423.378831058411, 12607.243565386014, 12627.116101872873, 15502.30239639219, 14750.934869118331, 15033.624159395993, 15159.696806063926, 15181.089134651957, 15369.769453458095, 11715.7071073223, 11719.597482516063, 11726.413679316, 11965.341906195155, 12021.459034343476, 3870.5355357357016, 3878.45701601776, 3937.4684417603544, 3954.8615798446917, 4108.198901214264, 98778.0, 106269.46865265701, 106474.46762766215, 108468.0, 108565.0, 9250.907490925005, 10210.591576336863, 11180.776384472265, 11268.661940141808, 11942.582009629621, 19686.212551498065, 22892.88553557255, 24062.63906041411, 24090.51818963611, 24112.51774964491, 7933.880991785128, 7979.3616510679, 8015.451472718512, 8243.505389676639, 8255.834883302421, 4348.695591308578, 4365.956340436618, 4388.341748737715, 4487.640988720895, 4630.791614377294, 3544.663256990582, 3654.506641603403, 3661.3409586274192, 3662.7252956028406, 3664.76179048361, 566.5863919338823, 668.0747165176169, 670.1890712238246, 673.3401266758603, 673.5083389125965, 559.4014404587134, 567.7189791053455, 570.9628874123251, 573.4924591736343, 614.8647297594522, 8848.646054157762, 9020.14308640678, 9155.72532824003, 9301.488418136933, 9405.905940940504, 485.94897535758946, 499.16888380845484, 620.2897682153908, 633.2685747961112, 650.0996120373317, 4432.889177770581, 4450.3991961085485, 4474.910501789946, 4487.192305384997, 4578.839740609063, 96020.0, 96659.51670241603, 143933.28033359765, 151824.0, 154606.0, 117885.0, 124104.45112165567, 132247.0, 135772.32113839567, 137794.3110284442, 109212.4539377298, 113473.0, 113540.0, 114192.42903785428, 115890.0, 7478.0, 8635.913640863762, 8693.04376518595, 8812.383133180616, 8905.777355566162, 55815.720921395136, 58057.0, 60116.39883601108, 60179.69910150596, 65898.0, 67517.0, 67797.0, 68545.0, 79174.0, 80392.59803700945, 10675.626353077916, 13822.170669759847, 13921.164730116225, 14026.509072182425, 14256.392416072607, 14493.927530362702, 14629.0, 14753.778693319395, 15308.464203752816, 15638.84361156374, 13241.47034118663, 14209.928950355183, 14375.640608984859, 14399.208043557705, 14792.11247325164, 13837.04190763676, 14044.227567483886, 14468.493602723855, 14517.056391334529, 14788.186649734367, 13245.933770331087, 13372.997025222952, 13954.23251721165, 14317.427302907767, 14610.634734131732, 11281.32312061279, 11368.033717134136, 11423.942880285545, 11564.537418503165, 11627.883721163018, 11198.608048718255, 11431.085513158761, 11540.24988375753, 11769.0, 11776.057915366922], "value": 7728.80213801217, "unit": "score"}], "name": "dromaeo_css", "value": 7251.690237838804, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 115.0, 112.0, 113.0, 112.0, 111.0, 112.0, 112.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 107.0, 106.0, 159.0, 107.0, 105.0, 130.0, 108.0, 139.0, 106.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 184.0, 235.0, 260.0, 183.0, 182.0, 185.0, 249.0, 183.0], "value": 202.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 57.0, 107.0, 58.0, 57.0, 62.0, 60.0, 98.0, 57.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 92.0, 70.0, 72.0, 67.0, 97.0, 70.0, 68.0, 102.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 83.0, 89.0, 85.0, 86.0, 84.0, 93.0, 129.0, 115.0, 85.0], "value": 93.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 95.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 85.0, 85.0, 86.0, 86.0, 83.0, 87.0, 86.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0, 54.0, 55.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 78.0, 79.0, 72.0, 70.0, 71.0, 71.0, 71.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 81.0, 85.0, 84.0, 81.0, 83.0, 83.0, 85.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 137.0, 138.0, 132.0, 139.0, 145.0, 145.0, 233.0, 136.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 159.0, 162.0, 161.0, 161.0, 162.0, 161.0, 164.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 66.0, 66.0, 65.0, 65.0, 65.0, 64.0, 64.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1447.1, "alertThreshold": 2.0}]} 21:31:19 INFO - Return code: 0 21:31:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:31:19 INFO - # TBPL SUCCESS # 21:31:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:31:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:31:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:31:19 INFO - 2016-07-11 21:31:19.820 screenresolution[1660:9732] starting screenresolution argv=screenresolution get 21:31:19 INFO - 2016-07-11 21:31:19.823 screenresolution[1660:9732] Display 0: 1600x1200x32@60 21:31:19 INFO - 2016-07-11 21:31:19.829 screenresolution[1661:9735] starting screenresolution argv=screenresolution list 21:31:19 INFO - Available Modes on Display 0 21:31:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:31:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:31:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:31:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:31:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:31:20 INFO - Intel Iris: 21:31:20 INFO - Chipset Model: Intel Iris 21:31:20 INFO - Type: GPU 21:31:20 INFO - Bus: Built-In 21:31:20 INFO - VRAM (Dynamic, Max): 1536 MB 21:31:20 INFO - Vendor: Intel (0x8086) 21:31:20 INFO - Device ID: 0x0a2e 21:31:20 INFO - Revision ID: 0x0009 21:31:20 INFO - Displays: 21:31:20 INFO - Display: 21:31:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:31:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:31:20 INFO - Display Serial Number: 1600x1200 60 21:31:20 INFO - Main Display: Yes 21:31:20 INFO - Mirror: Off 21:31:20 INFO - Online: Yes 21:31:20 INFO - Rotation: Supported 21:31:20 INFO - Return code: 0 21:31:20 INFO - Running post-action listener: _resource_record_post_action 21:31:20 INFO - ##### 21:31:20 INFO - ##### Finished run-tests step (success) 21:31:20 INFO - ##### 21:31:20 INFO - Running post-run listener: _resource_record_post_run 21:31:20 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36730368; Write bytes: 702352896; Read time: 385; Write time: 1855 21:31:20 INFO - TinderboxPrint: CPU usage
53.6% 21:31:20 INFO - TinderboxPrint: I/O read bytes / time
36,730,368 / 385 21:31:20 INFO - TinderboxPrint: I/O write bytes / time
702,352,896 / 1,855 21:31:20 INFO - TinderboxPrint: CPU idle
2,246.3 (46.4%) 21:31:20 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 21:31:20 INFO - TinderboxPrint: CPU user
1,626.5 (33.6%) 21:31:20 INFO - TinderboxPrint: Swap in / out
442,851,328 / 0 21:31:20 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389704192; Write bytes: 381822464; Read time: 29098; Write time: 723 21:31:20 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35776000; Write bytes: 293300224; Read time: 371; Write time: 1066 21:31:20 INFO - Running post-run listener: _upload_blobber_files 21:31:20 INFO - Blob upload gear active. 21:31:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:31:20 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 21:31:20 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'] 21:31:20 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 21:31:20 INFO - (blobuploader) - INFO - Open directory for files ... 21:31:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:31:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:31:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:31:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:31:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:31:21 INFO - (blobuploader) - INFO - Done attempting. 21:31:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:31:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:31:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:31:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:31:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:31:22 INFO - (blobuploader) - INFO - Done attempting. 21:31:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:31:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:31:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:31:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:31:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:31:25 INFO - (blobuploader) - INFO - Done attempting. 21:31:25 INFO - (blobuploader) - INFO - Iteration through files over. 21:31:25 INFO - Return code: 0 21:31:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:31:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:31:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c4578e50f23382fdc5b3941df9b38086009a04105b9a9e9295307f9d20cd75c02234b19eed462f48a57bfcee0845d2ba3dd877ad5d1e43079290e2a06f2f18fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10255628ece5c6afb7d1dc560543bac400d349a68abe70019272a064009b9a6e7e324e491d7315c8e0095e308386314869b6f50808a4d1914632f5791e0f852d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/826543caef267b11f74104f11f03442acea8f1e41808dcdcb867354b370e694b3bbe2ce75b47e85bbaf9bc187757cebfa6bc4d938c785f43fa80af732c0ec0ed"} 21:31:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:31:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:31:25 INFO - Contents: 21:31:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c4578e50f23382fdc5b3941df9b38086009a04105b9a9e9295307f9d20cd75c02234b19eed462f48a57bfcee0845d2ba3dd877ad5d1e43079290e2a06f2f18fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10255628ece5c6afb7d1dc560543bac400d349a68abe70019272a064009b9a6e7e324e491d7315c8e0095e308386314869b6f50808a4d1914632f5791e0f852d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/826543caef267b11f74104f11f03442acea8f1e41808dcdcb867354b370e694b3bbe2ce75b47e85bbaf9bc187757cebfa6bc4d938c785f43fa80af732c0ec0ed"} 21:31:25 INFO - Running post-run listener: copy_logs_to_upload_dir 21:31:25 INFO - Copying logs to upload dir... 21:31:25 INFO - mkdir: /builds/slave/test/build/upload/logs 21:31:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.801632 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-11 21:31:25.093530) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 21:31:25.097120) ========= 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.3DLB20tE0s/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.Vg515ZAij6/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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/c4578e50f23382fdc5b3941df9b38086009a04105b9a9e9295307f9d20cd75c02234b19eed462f48a57bfcee0845d2ba3dd877ad5d1e43079290e2a06f2f18fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10255628ece5c6afb7d1dc560543bac400d349a68abe70019272a064009b9a6e7e324e491d7315c8e0095e308386314869b6f50808a4d1914632f5791e0f852d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/826543caef267b11f74104f11f03442acea8f1e41808dcdcb867354b370e694b3bbe2ce75b47e85bbaf9bc187757cebfa6bc4d938c785f43fa80af732c0ec0ed"} build_url:https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011312 build_url: 'https://queue.taskcluster.net/v1/task/Ez1S61ByQKaLNvCn5Z6E1Q/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/c4578e50f23382fdc5b3941df9b38086009a04105b9a9e9295307f9d20cd75c02234b19eed462f48a57bfcee0845d2ba3dd877ad5d1e43079290e2a06f2f18fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10255628ece5c6afb7d1dc560543bac400d349a68abe70019272a064009b9a6e7e324e491d7315c8e0095e308386314869b6f50808a4d1914632f5791e0f852d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/826543caef267b11f74104f11f03442acea8f1e41808dcdcb867354b370e694b3bbe2ce75b47e85bbaf9bc187757cebfa6bc4d938c785f43fa80af732c0ec0ed"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 21:31:25.129752) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 21:31:25.130061) ========= 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.3DLB20tE0s/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.Vg515ZAij6/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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.005120 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 21:31:25.182381) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 21:31:25.183160) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 21:31:25.183721) ========= ========= Total master_lag: 0.53 =========