builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0116 starttime: 1463034076.29 results: success (0) buildid: 20160511211918 builduid: b5b007d81d4a4000989fa67bcd6e7e31 revision: 2fb177e17d81e069a02797c6234e8ed085cbc55c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 23:21:16.285753) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 23:21:16.286232) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 mins, 38 secs) (at 2016-05-11 23:21:16.286539) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 mins, 38 secs) (at 2016-05-11 23:22:55.174595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 23:22:55.177899) ========= 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.s96PKX6LwC/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.E6oPTfqATs/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-05-11 23:22:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.49M=0.001s 2016-05-11 23:22:55 (9.49 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114910 ========= master_lag: 1.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 23:22:56.306201) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 23:22:56.306540) ========= 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.s96PKX6LwC/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.E6oPTfqATs/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.025727 ========= master_lag: 0.60 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 23:22:56.936022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2016-05-11 23:22:56.936400) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2fb177e17d81e069a02797c6234e8ed085cbc55c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2fb177e17d81e069a02797c6234e8ed085cbc55c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.s96PKX6LwC/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.E6oPTfqATs/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-05-11 23:22:57,071 truncating revision to first 12 chars 2016-05-11 23:22:57,071 Setting DEBUG logging. 2016-05-11 23:22:57,071 attempt 1/10 2016-05-11 23:22:57,071 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2fb177e17d81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 23:22:57,683 unpacking tar archive at: mozilla-aurora-2fb177e17d81/testing/mozharness/ program finished with exit code 0 elapsedTime=0.853142 ========= master_lag: 9.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2016-05-11 23:23:07.195095) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 23:23:07.195446) ========= script_repo_revision: 2fb177e17d81e069a02797c6234e8ed085cbc55c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 23:23:07.200079) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 20 secs) (at 2016-05-11 23:23:07.200478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 20 secs) (at 2016-05-11 23:23:27.341638) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-11 23:23:27.341913) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.s96PKX6LwC/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.E6oPTfqATs/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:23:28 INFO - MultiFileLogger online at 20160511 23:23:28 in /builds/slave/test 23:23:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:23:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:23:28 INFO - {'append_to_log': False, 23:23:28 INFO - 'base_work_dir': '/builds/slave/test', 23:23:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:23:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:23:28 INFO - 'branch': 'Mozilla-Aurora', 23:23:28 INFO - 'buildbot_json_path': 'buildprops.json', 23:23:28 INFO - 'config_files': ('talos/mac_config.py',), 23:23:28 INFO - 'default_actions': ('clobber', 23:23:28 INFO - 'read-buildbot-config', 23:23:28 INFO - 'download-and-extract', 23:23:28 INFO - 'populate-webroot', 23:23:28 INFO - 'create-virtualenv', 23:23:28 INFO - 'install', 23:23:28 INFO - 'run-tests'), 23:23:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:23:28 INFO - 'download_minidump_stackwalk': True, 23:23:28 INFO - 'download_symbols': 'ondemand', 23:23:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:23:28 INFO - 'tooltool.py': '/tools/tooltool.py', 23:23:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:23:28 INFO - '/tools/misc-python/virtualenv.py')}, 23:23:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:23:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:23:28 INFO - 'installer_path': 'installer.exe', 23:23:28 INFO - 'log_level': 'info', 23:23:28 INFO - 'log_name': 'talos', 23:23:28 INFO - 'log_to_console': True, 23:23:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:23:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:23:28 INFO - 'opt_config_files': (), 23:23:28 INFO - 'pip_index': False, 23:23:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:23:28 INFO - 'cmd': ('bash', 23:23:28 INFO - '-c', 23:23:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:23:28 INFO - 'enabled': True, 23:23:28 INFO - 'halt_on_failure': False, 23:23:28 INFO - 'name': 'check_screen_resolution'},), 23:23:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:23:28 INFO - 'cmd': ('bash', 23:23:28 INFO - '-c', 23:23:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:23:28 INFO - 'enabled': True, 23:23:28 INFO - 'halt_on_failure': False, 23:23:28 INFO - 'name': 'check_screen_resolution'},), 23:23:28 INFO - 'run_cmd_checks_enabled': True, 23:23:28 INFO - 'sps_profile': False, 23:23:28 INFO - 'sps_profile_interval': 0, 23:23:28 INFO - 'suite': 'dromaeojs', 23:23:28 INFO - 'system_bits': '32', 23:23:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:23:28 INFO - 'title': 't-yosemite-r7-0116', 23:23:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:23:28 INFO - 'use_talos_json': True, 23:23:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:23:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:23:28 INFO - 'work_dir': 'build'} 23:23:28 INFO - ##### 23:23:28 INFO - ##### Running clobber step. 23:23:28 INFO - ##### 23:23:28 INFO - Running pre-action listener: _resource_record_pre_action 23:23:28 INFO - Running main action method: clobber 23:23:28 INFO - rmtree: /builds/slave/test/build 23:23:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:23:29 INFO - Running post-action listener: _resource_record_post_action 23:23:29 INFO - ##### 23:23:29 INFO - ##### Running read-buildbot-config step. 23:23:29 INFO - ##### 23:23:29 INFO - Running pre-action listener: _resource_record_pre_action 23:23:29 INFO - Running main action method: read_buildbot_config 23:23:29 INFO - Using buildbot properties: 23:23:29 INFO - { 23:23:29 INFO - "project": "", 23:23:29 INFO - "product": "firefox", 23:23:29 INFO - "script_repo_revision": "production", 23:23:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 23:23:29 INFO - "repository": "", 23:23:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 23:23:29 INFO - "buildid": "20160511211918", 23:23:29 INFO - "pgo_build": "False", 23:23:29 INFO - "basedir": "/builds/slave/test", 23:23:29 INFO - "buildnumber": 339, 23:23:29 INFO - "slavename": "t-yosemite-r7-0116", 23:23:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:23:29 INFO - "platform": "yosemite_r7", 23:23:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:23:29 INFO - "branch": "mozilla-aurora", 23:23:29 INFO - "repo_path": "releases/mozilla-aurora", 23:23:29 INFO - "revision": "2fb177e17d81e069a02797c6234e8ed085cbc55c", 23:23:29 INFO - "stage_platform": "macosx64", 23:23:29 INFO - "builduid": "b5b007d81d4a4000989fa67bcd6e7e31", 23:23:29 INFO - "slavebuilddir": "test" 23:23:29 INFO - } 23:23:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 23:23:29 INFO - Running post-action listener: _resource_record_post_action 23:23:29 INFO - ##### 23:23:29 INFO - ##### Running download-and-extract step. 23:23:29 INFO - ##### 23:23:29 INFO - Running pre-action listener: _resource_record_pre_action 23:23:29 INFO - Running main action method: download_and_extract 23:23:29 INFO - mkdir: /builds/slave/test/build/tests 23:23:29 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')]} 23:23:29 INFO - https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:23:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:23:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:23:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 23:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 23:23:31 INFO - Downloaded 1199 bytes. 23:23:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 23:23:31 INFO - Using the following test package requirements: 23:23:31 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:23:31 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 23:23:31 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 23:23:31 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'jsshell-mac.zip'], 23:23:31 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 23:23:31 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:23:31 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 23:23:31 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 23:23:31 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 23:23:31 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:23:31 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 23:23:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:23:31 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')]} 23:23:31 INFO - https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:23:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:23:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:23:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:23:32 INFO - Downloaded 18089390 bytes. 23:23:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:23:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:23:35 INFO - Return code: 0 23:23:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 23:23:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:23:35 INFO - https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:23:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:23:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:23:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:23:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:23:36 INFO - Downloaded 18089390 bytes. 23:23:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:23:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:23:40 INFO - Return code: 0 23:23:40 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')]} 23:23:40 INFO - https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:23:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:23:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:23:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:23:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 23:23:41 INFO - Downloaded 10840145 bytes. 23:23:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:23:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 23:23:41 INFO - Return code: 0 23:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:23:41 INFO - https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 23:23:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:23:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:23:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 23:23:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 23:23:44 INFO - Downloaded 114120375 bytes. 23:23:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:23:44 INFO - mkdir: /builds/slave/test/properties 23:23:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:23:44 INFO - Writing to file /builds/slave/test/properties/build_url 23:23:44 INFO - Contents: 23:23:44 INFO - build_url:https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:23:46 INFO - Running post-action listener: _resource_record_post_action 23:23:46 INFO - Running post-action listener: set_extra_try_arguments 23:23:46 INFO - ##### 23:23:46 INFO - ##### Running populate-webroot step. 23:23:46 INFO - ##### 23:23:46 INFO - Running pre-action listener: _resource_record_pre_action 23:23:46 INFO - Running main action method: populate_webroot 23:23:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:23:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:23:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:23:46 INFO - '--tppagecycles', 23:23:46 INFO - '7'], 23:23:46 INFO - 'tests': ['tsvgm']}}, 23:23:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:23:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:23:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:23:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:23:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:23:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:23:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['damp', 'tps']}, 23:23:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'tests': ['damp', 'tps']}, 23:23:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['dromaeo_dom']}, 23:23:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:23:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['a11yr', 23:23:46 INFO - 'ts_paint', 23:23:46 INFO - 'tpaint', 23:23:46 INFO - 'sessionrestore', 23:23:46 INFO - 'sessionrestore_no_auto_restore', 23:23:46 INFO - 'tabpaint']}, 23:23:46 INFO - 'other-e10s': {'tests': ['a11yr', 23:23:46 INFO - 'ts_paint', 23:23:46 INFO - 'tpaint', 23:23:46 INFO - 'sessionrestore', 23:23:46 INFO - 'sessionrestore_no_auto_restore', 23:23:46 INFO - 'tabpaint']}, 23:23:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:23:46 INFO - 'ts_paint', 23:23:46 INFO - 'tpaint', 23:23:46 INFO - 'sessionrestore', 23:23:46 INFO - 'sessionrestore_no_auto_restore', 23:23:46 INFO - 'tabpaint']}, 23:23:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:23:46 INFO - 'ts_paint', 23:23:46 INFO - 'tpaint', 23:23:46 INFO - 'sessionrestore', 23:23:46 INFO - 'sessionrestore_no_auto_restore', 23:23:46 INFO - 'tabpaint']}, 23:23:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['a11yr', 23:23:46 INFO - 'ts_paint', 23:23:46 INFO - 'tpaint', 23:23:46 INFO - 'sessionrestore', 23:23:46 INFO - 'sessionrestore_no_auto_restore', 23:23:46 INFO - 'tabpaint']}, 23:23:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['a11yr', 23:23:46 INFO - 'ts_paint', 23:23:46 INFO - 'tpaint', 23:23:46 INFO - 'sessionrestore', 23:23:46 INFO - 'sessionrestore_no_auto_restore', 23:23:46 INFO - 'tabpaint']}, 23:23:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['tsvgx', 23:23:46 INFO - 'tsvgr_opacity', 23:23:46 INFO - 'tart', 23:23:46 INFO - 'tscrollx', 23:23:46 INFO - 'cart']}, 23:23:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:23:46 INFO - 'tsvgr_opacity', 23:23:46 INFO - 'tart', 23:23:46 INFO - 'tscrollx', 23:23:46 INFO - 'cart']}, 23:23:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'talos_options': ['--disable-e10s'], 23:23:46 INFO - 'tests': ['tp5o']}, 23:23:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'tests': ['tp5o']}, 23:23:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'talos_options': ['--disable-e10s', 23:23:46 INFO - '--xperf_path', 23:23:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:23:46 INFO - 'tests': ['tp5n']}, 23:23:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:23:46 INFO - 'talos_options': ['--xperf_path', 23:23:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:23:46 INFO - 'tests': ['tp5n']}}, 23:23:46 INFO - 'talos.zip': {'path': '', 23:23:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:23:46 INFO - Running post-action listener: _resource_record_post_action 23:23:46 INFO - ##### 23:23:46 INFO - ##### Running create-virtualenv step. 23:23:46 INFO - ##### 23:23:46 INFO - Running pre-action listener: _resource_record_pre_action 23:23:46 INFO - Running main action method: create_virtualenv 23:23:46 INFO - Creating virtualenv /builds/slave/test/build/venv 23:23:46 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 23:23:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:23:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:23:46 INFO - Using real prefix '/tools/python27' 23:23:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:23:47 INFO - Installing distribute.............................................................................................................................................................................................done. 23:23:49 INFO - Installing pip.................done. 23:23:49 INFO - Return code: 0 23:23:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:23:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:23:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:49 INFO - 'HOME': '/Users/cltbld', 23:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:49 INFO - 'LOGNAME': 'cltbld', 23:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:49 INFO - 'MOZ_NO_REMOTE': '1', 23:23:49 INFO - 'NO_EM_RESTART': '1', 23:23:49 INFO - 'PAGER': '/bin/cat', 23:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:49 INFO - 'PWD': '/builds/slave/test', 23:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:49 INFO - 'SHELL': '/bin/bash', 23:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:23:49 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:49 INFO - 'USER': 'cltbld', 23:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:49 INFO - 'XPC_FLAGS': '0x0', 23:23:49 INFO - 'XPC_SERVICE_NAME': '0', 23:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:50 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 23:23:50 INFO - Cleaning up... 23:23:50 INFO - Return code: 0 23:23:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:23: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')]} 23:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23: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')]} 23:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:23: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', 'psutil>=0.7.1'] in /builds/slave/test/build 23:23: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 psutil>=0.7.1 23:23:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:23:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:50 INFO - 'HOME': '/Users/cltbld', 23:23:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:50 INFO - 'LOGNAME': 'cltbld', 23:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:50 INFO - 'MOZ_NO_REMOTE': '1', 23:23:50 INFO - 'NO_EM_RESTART': '1', 23:23:50 INFO - 'PAGER': '/bin/cat', 23:23:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:50 INFO - 'PWD': '/builds/slave/test', 23:23:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:50 INFO - 'SHELL': '/bin/bash', 23:23:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:23:50 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:23:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:50 INFO - 'USER': 'cltbld', 23:23:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:50 INFO - 'XPC_FLAGS': '0x0', 23:23:50 INFO - 'XPC_SERVICE_NAME': '0', 23:23:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:50 INFO - Downloading/unpacking psutil>=0.7.1 23:23: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 23:23: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 23:23: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 23:23: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 23:23: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 23:23: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 23:23:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:23:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:23:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:23:53 INFO - Installing collected packages: psutil 23:23:53 INFO - Running setup.py install for psutil 23:23:53 INFO - building 'psutil._psutil_osx' extension 23:23:53 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 23:23:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:23:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:23:54 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 23:23:54 INFO - building 'psutil._psutil_posix' extension 23:23:54 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 23:23:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:23:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:23:54 INFO - ^ 23:23:54 INFO - 1 warning generated. 23:23:54 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 23:23:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:23:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:23:54 INFO - Successfully installed psutil 23:23:54 INFO - Cleaning up... 23:23:55 INFO - Return code: 0 23:23:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:23:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:55 INFO - 'HOME': '/Users/cltbld', 23:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:55 INFO - 'LOGNAME': 'cltbld', 23:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:55 INFO - 'MOZ_NO_REMOTE': '1', 23:23:55 INFO - 'NO_EM_RESTART': '1', 23:23:55 INFO - 'PAGER': '/bin/cat', 23:23:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:55 INFO - 'PWD': '/builds/slave/test', 23:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:55 INFO - 'SHELL': '/bin/bash', 23:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:23:55 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:55 INFO - 'USER': 'cltbld', 23:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:55 INFO - 'XPC_FLAGS': '0x0', 23:23:55 INFO - 'XPC_SERVICE_NAME': '0', 23:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:23:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:23:57 INFO - Installing collected packages: mozsystemmonitor 23:23:57 INFO - Running setup.py install for mozsystemmonitor 23:23:57 INFO - Successfully installed mozsystemmonitor 23:23:57 INFO - Cleaning up... 23:23:58 INFO - Return code: 0 23:23:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:23: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')]} 23:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:23:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23: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')]} 23:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:23:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:23:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:23:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:23:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:23:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:23:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:23:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:23:58 INFO - 'HOME': '/Users/cltbld', 23:23:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:23:58 INFO - 'LOGNAME': 'cltbld', 23:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:23:58 INFO - 'MOZ_NO_REMOTE': '1', 23:23:58 INFO - 'NO_EM_RESTART': '1', 23:23:58 INFO - 'PAGER': '/bin/cat', 23:23:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:23:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:23:58 INFO - 'PWD': '/builds/slave/test', 23:23:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:23:58 INFO - 'SHELL': '/bin/bash', 23:23:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:23:58 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:23:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:23:58 INFO - 'USER': 'cltbld', 23:23:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:23:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:23:58 INFO - 'XPC_FLAGS': '0x0', 23:23:58 INFO - 'XPC_SERVICE_NAME': '0', 23:23:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:23:58 INFO - Downloading/unpacking blobuploader==1.2.4 23:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:23:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:23:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:00 INFO - Downloading blobuploader-1.2.4.tar.gz 23:24:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:24:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:24:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:01 INFO - Downloading docopt-0.6.1.tar.gz 23:24:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:24:01 INFO - Installing collected packages: blobuploader, requests, docopt 23:24:01 INFO - Running setup.py install for blobuploader 23:24:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:24:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:24:02 INFO - Running setup.py install for requests 23:24:02 INFO - Running setup.py install for docopt 23:24:02 INFO - Successfully installed blobuploader requests docopt 23:24:02 INFO - Cleaning up... 23:24:02 INFO - Return code: 0 23:24:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:24:02 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')]} 23:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:02 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')]} 23:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:02 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 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:24:02 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 23:24:02 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 23:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:24:02 INFO - 'HOME': '/Users/cltbld', 23:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:24:02 INFO - 'LOGNAME': 'cltbld', 23:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:02 INFO - 'MOZ_NO_REMOTE': '1', 23:24:02 INFO - 'NO_EM_RESTART': '1', 23:24:02 INFO - 'PAGER': '/bin/cat', 23:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:02 INFO - 'PWD': '/builds/slave/test', 23:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:24:02 INFO - 'SHELL': '/bin/bash', 23:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:24:02 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:24:02 INFO - 'USER': 'cltbld', 23:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:02 INFO - 'XPC_FLAGS': '0x0', 23:24:02 INFO - 'XPC_SERVICE_NAME': '0', 23:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:24:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ab5U1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ZpSIGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-3LCCuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Zb1qNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-42sLh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-YgS5_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-7Y9Qgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-qs9PhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:24:03 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-pUlvAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-qicOTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-9DhQOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-7WoOTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-k96SbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-7VSUp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-KfKoZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-DBk3cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:24:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:24:04 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-pKjUW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:24:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:24:04 INFO - Running setup.py install for manifestparser 23:24:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:24:05 INFO - Running setup.py install for mozcrash 23:24:05 INFO - Running setup.py install for mozdebug 23:24:05 INFO - Running setup.py install for mozdevice 23:24:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:24:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:24:05 INFO - Running setup.py install for mozfile 23:24:05 INFO - Running setup.py install for mozhttpd 23:24:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:24:05 INFO - Running setup.py install for mozinfo 23:24:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:24:05 INFO - Running setup.py install for mozInstall 23:24:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Running setup.py install for mozleak 23:24:06 INFO - Running setup.py install for mozlog 23:24:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Running setup.py install for moznetwork 23:24:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Running setup.py install for mozprocess 23:24:06 INFO - Running setup.py install for mozprofile 23:24:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:24:06 INFO - Running setup.py install for mozrunner 23:24:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:24:07 INFO - Running setup.py install for mozscreenshot 23:24:07 INFO - Running setup.py install for moztest 23:24:07 INFO - Running setup.py install for mozversion 23:24:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:24:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:24:07 INFO - Cleaning up... 23:24:07 INFO - Return code: 0 23:24:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:24:07 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')]} 23:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:07 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')]} 23:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:07 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 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:24:07 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 23:24:07 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 23:24:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:24:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:24:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:24:07 INFO - 'HOME': '/Users/cltbld', 23:24:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:24:07 INFO - 'LOGNAME': 'cltbld', 23:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:07 INFO - 'MOZ_NO_REMOTE': '1', 23:24:07 INFO - 'NO_EM_RESTART': '1', 23:24:07 INFO - 'PAGER': '/bin/cat', 23:24:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:07 INFO - 'PWD': '/builds/slave/test', 23:24:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:24:07 INFO - 'SHELL': '/bin/bash', 23:24:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:24:07 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:24:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:24:07 INFO - 'USER': 'cltbld', 23:24:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:24:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:07 INFO - 'XPC_FLAGS': '0x0', 23:24:07 INFO - 'XPC_SERVICE_NAME': '0', 23:24:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:24:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-8ZzRBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:24:07 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)) 23:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:24:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-VbUMG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:24:07 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)) 23:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:24:07 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-c8elOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-oaAFtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-k422CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-impzRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-0ZfGvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-4RTDbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-64SIj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-MYLUjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:24:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-xsMx4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:24:08 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)) 23:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:24:08 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-a1oPHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:24:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 23:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:24:09 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ZgZPrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:24:09 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)) 23:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:24:09 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-0f_nIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:24:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 23:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:24:09 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-q4cmPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:24:09 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)) 23:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:24:09 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-F4qDJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:24:09 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)) 23:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:24:09 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-RqoqnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:24:09 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)) 23:24:09 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)) 23:24:09 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)) 23:24:09 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)) 23:24:09 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)) 23:24:09 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)) 23:24:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:24:09 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 23:24:09 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 23:24:09 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 23:24:09 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 23:24:09 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 23:24:09 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 23:24:11 INFO - Downloading blessings-1.6.tar.gz 23:24:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:24:12 INFO - Installing collected packages: blessings 23:24:12 INFO - Running setup.py install for blessings 23:24:12 INFO - Successfully installed blessings 23:24:12 INFO - Cleaning up... 23:24:12 INFO - Return code: 0 23:24:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:24:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:24:12 INFO - Reading from file tmpfile_stdout 23:24:12 INFO - Current package versions: 23:24:12 INFO - blessings == 1.6 23:24:12 INFO - blobuploader == 1.2.4 23:24:12 INFO - docopt == 0.6.1 23:24:12 INFO - manifestparser == 1.1 23:24:12 INFO - mozInstall == 1.12 23:24:12 INFO - mozcrash == 0.17 23:24:12 INFO - mozdebug == 0.1 23:24:12 INFO - mozdevice == 0.48 23:24:12 INFO - mozfile == 1.2 23:24:12 INFO - mozhttpd == 0.7 23:24:12 INFO - mozinfo == 0.9 23:24:12 INFO - mozleak == 0.1 23:24:12 INFO - mozlog == 3.1 23:24:12 INFO - moznetwork == 0.27 23:24:12 INFO - mozprocess == 0.22 23:24:12 INFO - mozprofile == 0.28 23:24:12 INFO - mozrunner == 6.11 23:24:12 INFO - mozscreenshot == 0.1 23:24:12 INFO - mozsystemmonitor == 0.0 23:24:12 INFO - moztest == 0.7 23:24:12 INFO - mozversion == 1.4 23:24:12 INFO - psutil == 3.1.1 23:24:12 INFO - requests == 1.2.3 23:24:12 INFO - wsgiref == 0.1.2 23:24:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:24:12 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')]} 23:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:12 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')]} 23:24:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:12 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 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:24:12 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 23:24:12 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 23:24:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:24:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:24:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:24:12 INFO - 'HOME': '/Users/cltbld', 23:24:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:24:12 INFO - 'LOGNAME': 'cltbld', 23:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:12 INFO - 'MOZ_NO_REMOTE': '1', 23:24:12 INFO - 'NO_EM_RESTART': '1', 23:24:12 INFO - 'PAGER': '/bin/cat', 23:24:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:12 INFO - 'PWD': '/builds/slave/test', 23:24:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:24:12 INFO - 'SHELL': '/bin/bash', 23:24:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:24:12 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:24:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:24:12 INFO - 'USER': 'cltbld', 23:24:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:24:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:12 INFO - 'XPC_FLAGS': '0x0', 23:24:12 INFO - 'XPC_SERVICE_NAME': '0', 23:24:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:24:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 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)) 23:24:12 INFO - Cleaning up... 23:24:12 INFO - Return code: 0 23:24:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:24:12 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')]} 23:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:12 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')]} 23:24:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:12 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 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:24:12 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 23:24:12 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 23:24:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:24:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:24:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:24:12 INFO - 'HOME': '/Users/cltbld', 23:24:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:24:12 INFO - 'LOGNAME': 'cltbld', 23:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:12 INFO - 'MOZ_NO_REMOTE': '1', 23:24:12 INFO - 'NO_EM_RESTART': '1', 23:24:12 INFO - 'PAGER': '/bin/cat', 23:24:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:12 INFO - 'PWD': '/builds/slave/test', 23:24:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:24:12 INFO - 'SHELL': '/bin/bash', 23:24:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:24:12 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:24:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:24:12 INFO - 'USER': 'cltbld', 23:24:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:24:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:12 INFO - 'XPC_FLAGS': '0x0', 23:24:12 INFO - 'XPC_SERVICE_NAME': '0', 23:24:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:13 INFO - Downloading/unpacking jsonschema 23:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:24:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:24:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:24:15 INFO - Downloading/unpacking functools32 (from jsonschema) 23:24:15 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 23:24:15 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 23:24:15 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 23:24:15 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 23:24:15 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 23:24:15 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 23:24:15 INFO - Downloading functools32-3.2.3-2.tar.gz 23:24:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:24:15 INFO - warning: no files found matching '*.txt' 23:24:15 INFO - no previously-included directories found matching 'build' 23:24:15 INFO - no previously-included directories found matching 'dist' 23:24:15 INFO - no previously-included directories found matching '.git*' 23:24:15 INFO - Installing collected packages: jsonschema, functools32 23:24:15 INFO - Running setup.py install for functools32 23:24:16 INFO - warning: no files found matching '*.txt' 23:24:16 INFO - no previously-included directories found matching 'build' 23:24:16 INFO - no previously-included directories found matching 'dist' 23:24:16 INFO - no previously-included directories found matching '.git*' 23:24:16 INFO - Successfully installed jsonschema functools32 23:24:16 INFO - Cleaning up... 23:24:16 INFO - Return code: 0 23:24:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:24:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:24:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:24:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:24:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108682a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108692420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8909ca1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, '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.s96PKX6LwC/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.E6oPTfqATs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 23:24:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:24:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:24:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:24:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:24:16 INFO - 'HOME': '/Users/cltbld', 23:24:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:24:16 INFO - 'LOGNAME': 'cltbld', 23:24:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:16 INFO - 'MOZ_NO_REMOTE': '1', 23:24:16 INFO - 'NO_EM_RESTART': '1', 23:24:16 INFO - 'PAGER': '/bin/cat', 23:24:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:24:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:16 INFO - 'PWD': '/builds/slave/test', 23:24:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:24:16 INFO - 'SHELL': '/bin/bash', 23:24:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:24:16 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:24:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:24:16 INFO - 'USER': 'cltbld', 23:24:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:24:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:24:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:16 INFO - 'XPC_FLAGS': '0x0', 23:24:16 INFO - 'XPC_SERVICE_NAME': '0', 23:24:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:24:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:24:16 INFO - Downloading/unpacking flake8 23:24:16 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 23:24:16 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 23:24:16 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 23:24:16 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 23:24:16 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 23:24:16 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 23:24:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:24:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:24:18 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 23:24:18 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 23:24:18 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 23:24:18 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 23:24:18 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 23:24:18 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 23:24:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:24:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:24:19 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 23:24:19 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 23:24:19 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 23:24:19 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 23:24:19 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 23:24:19 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 23:24:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:24:19 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 23:24:19 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 23:24:19 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 23:24:19 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 23:24:19 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 23:24:19 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 23:24:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:24:20 INFO - no previously-included directories found matching 'docs/_build' 23:24:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:24:20 INFO - Running setup.py install for pep8 23:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:24:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:24:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:24:20 INFO - no previously-included directories found matching 'docs/_build' 23:24:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:24:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:24:20 INFO - Cleaning up... 23:24:20 INFO - Return code: 0 23:24:20 INFO - Running post-action listener: _resource_record_post_action 23:24:20 INFO - Running post-action listener: _start_resource_monitoring 23:24:20 INFO - Starting resource monitoring. 23:24:20 INFO - ##### 23:24:20 INFO - ##### Running install step. 23:24:20 INFO - ##### 23:24:20 INFO - Running pre-action listener: _resource_record_pre_action 23:24:20 INFO - Running main action method: install 23:24:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:24:20 INFO - Reading from file tmpfile_stdout 23:24:20 INFO - Detecting whether we're running mozinstall >=1.0... 23:24:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:24:20 INFO - Reading from file tmpfile_stdout 23:24:20 INFO - Output received: 23:24:20 INFO - Usage: mozinstall [options] installer 23:24:20 INFO - Options: 23:24:20 INFO - -h, --help show this help message and exit 23:24:20 INFO - -d DEST, --destination=DEST 23:24:20 INFO - Directory to install application into. [default: 23:24:20 INFO - "/builds/slave/test"] 23:24:20 INFO - --app=APP Application being installed. [default: firefox] 23:24:20 INFO - mkdir: /builds/slave/test/build/application 23:24:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 23:24:52 INFO - Reading from file tmpfile_stdout 23:24:52 INFO - Output received: 23:24:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:24:52 INFO - Running post-action listener: _resource_record_post_action 23:24:52 INFO - ##### 23:24:52 INFO - ##### Running run-tests step. 23:24:52 INFO - ##### 23:24:52 INFO - Running pre-action listener: _resource_record_pre_action 23:24:52 INFO - Running main action method: run_tests 23:24:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:24:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:24:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:24:52 INFO - 2016-05-11 23:24:52.951 screenresolution[1652:9551] starting screenresolution argv=screenresolution get 23:24:52 INFO - 2016-05-11 23:24:52.954 screenresolution[1652:9551] Display 0: 1600x1200x32@60 23:24:52 INFO - 2016-05-11 23:24:52.959 screenresolution[1653:9554] starting screenresolution argv=screenresolution list 23:24:52 INFO - Available Modes on Display 0 23:24:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:24:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:24:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:24:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:24:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:24:53 INFO - Intel Iris: 23:24:53 INFO - Chipset Model: Intel Iris 23:24:53 INFO - Type: GPU 23:24:53 INFO - Bus: Built-In 23:24:53 INFO - VRAM (Dynamic, Max): 1536 MB 23:24:53 INFO - Vendor: Intel (0x8086) 23:24:53 INFO - Device ID: 0x0a2e 23:24:53 INFO - Revision ID: 0x0009 23:24:53 INFO - Displays: 23:24:53 INFO - Display: 23:24:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:24:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:24:53 INFO - Display Serial Number: 1600x1200 60 23:24:53 INFO - Main Display: Yes 23:24:53 INFO - Mirror: Off 23:24:53 INFO - Online: Yes 23:24:53 INFO - Rotation: Supported 23:24:53 INFO - Return code: 0 23:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:24:53 INFO - Python 2.7.3 23:24:53 INFO - Return code: 0 23:24:53 INFO - grabbing minidump binary from tooltool 23:24: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')]} 23:24:53 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108521e00>, '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 0x10838adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10851cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:24:53 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 23:24:53 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 23:24:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:24:53 INFO - Return code: 0 23:24:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:24:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:24:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:24:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:24:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:24:54 INFO - Return code: 0 23:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 23:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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 23:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s96PKX6LwC/Render', 23:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:24:54 INFO - 'HOME': '/Users/cltbld', 23:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:24:54 INFO - 'LOGNAME': 'cltbld', 23:24:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:24:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:24:54 INFO - 'MOZ_NO_REMOTE': '1', 23:24:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:24:54 INFO - 'NO_EM_RESTART': '1', 23:24:54 INFO - 'PAGER': '/bin/cat', 23:24:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:24:54 INFO - 'PWD': '/builds/slave/test', 23:24:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:24:54 INFO - 'SHELL': '/bin/bash', 23:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E6oPTfqATs/Listeners', 23:24:54 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 23:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:24:54 INFO - 'USER': 'cltbld', 23:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:24:54 INFO - 'XPC_FLAGS': '0x0', 23:24:54 INFO - 'XPC_SERVICE_NAME': '0', 23:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:24:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 23:24:54 INFO - mozversion application_buildid: 20160511211918 23:24:54 INFO - mozversion application_changeset: 2fb177e17d81e069a02797c6234e8ed085cbc55c 23:24:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:24:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:24:54 INFO - mozversion application_name: Firefox 23:24:54 INFO - mozversion application_remotingname: firefox-dev 23:24:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:24:54 INFO - mozversion application_vendor: Mozilla 23:24:54 INFO - mozversion application_version: 48.0a2 23:24:54 INFO - mozversion platform_buildid: 20160511211918 23:24:54 INFO - mozversion platform_changeset: 2fb177e17d81e069a02797c6234e8ed085cbc55c 23:24:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:24:54 INFO - mozversion platform_version: 48.0a2 23:24:54 INFO - using testdate: 1463034294 23:24:54 INFO - actual date: 1463034294 23:24:54 INFO - starting webserver on 'localhost:49270' 23:24:54 INFO - SUITE-START | Running 2 tests 23:24:54 INFO - TEST-START | dromaeo_css 23:24:54 INFO - operating with platform_type : mac_ 23:24:54 INFO - Initialising browser for dromaeo_css test... 23:24:54 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp8sThd5/profile http://localhost:49270/getInfo.html) 23:24:56 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 23:24:56 INFO - PROCESS | 1671 | colorDepth:24 23:24:56 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 23:24:56 INFO - PROCESS | 1671 | __metrics 23:24:56 INFO - PROCESS | 1671 | 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" 23:24:56 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:24:57 INFO - TEST-INFO | 1671: exit 0 23:24:57 INFO - Browser initialized. 23:24:57 INFO - Running cycle 1/1 for dromaeo_css test... 23:24:57 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp8sThd5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:25:09 INFO - PROCESS | 1674 | RSS: Main: 211578880 23:25:09 INFO - PROCESS | 1674 | 23:26:00 INFO - PROCESS | 1674 | 1463034360376 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] 23:28:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 23:28:13 INFO - PROCESS | 1674 | RSS: Main: 343150592 23:28:13 INFO - PROCESS | 1674 | 23:31:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 23:31:17 INFO - PROCESS | 1674 | RSS: Main: 359567360 23:31:17 INFO - PROCESS | 1674 | 23:34:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 23:34:19 INFO - PROCESS | 1674 | RSS: Main: 361725952 23:34:19 INFO - PROCESS | 1674 | 23:37:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 23:37:22 INFO - PROCESS | 1674 | RSS: Main: 358076416 23:37:22 INFO - PROCESS | 1674 | 23:40:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 23:40:26 INFO - PROCESS | 1674 | RSS: Main: 324009984 23:40:26 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 23:43:29 INFO - PROCESS | 1674 | RSS: Main: 392232960 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | __start_tp_report 23:43:29 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 23:43:29 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 23:43:29 INFO - PROCESS | 1674 | |i|pagename|runs| 23:43:29 INFO - PROCESS | 1674 | |0;dojo.html;3395.1681837949704;3452.361313157066;3470.826458677067;3484.1115515543534;3521.0317162780234;5710.2862142232225;5725.713714314284;5733.541316694665;5737.7991770288045;5763.020286551286;5726.885462290752;5757.625754325966;5762.625429347089;5768.336641286253;5768.451997060283;5122.846314610549;5190.92213616796;5254.343207099113;5276.287701160351;5372.140457526796;5288.312519372495;5292.735363231843;5293.86765330866;5295.602829787762;5296.3379577552805;5227.398849132342;5277.076511610472;5279.313689220396;5288.894222115556;5301.1783173608155;5277.86805329866;5308.230306605559;5320.547753440924;5320.707361095138;5336.119540275849;4340.197063543254;5119.590432765407;5170.172772356443;5228.9738551307;5248.265242866002;5595.13275442303;5596.4123767004285;5601;5602.663840169603;5642.57680673947;6211.19254496917;6287.4655654269445;6311.684415779193;6317.210348706411;6318.810435686891;6093.2078829751845;6208.720607572625;6371.426571608577;6382.744690212386;6393.424591786715;5780.104083867007;5807.651540907559;5814.982378083861;5817.825465236049;5822.737976791054;6313.242410910629;6322.399372059647;6322.462590679752;6332.95506241465;6346.492280617539;4968.378952630921;6024.246969128859;6082.665453400105;6166.845828854314;6173.351798061229;6267.1539342189135;6311.368863113744;6332.271788744353;6340.080688300263;6354.078658594571;6184.072389141575;6217.751289948442;6229.314775374705;6239.532035097386;6244.812655620339;6106.145139680449;6164.845878853064;6173.290071641729;6182.165407669907;6284.057391391328;4862.246351815475;4900.632452566;4930.235813448921;4945.085159245551;4952.826651067196;4435.2903535434825;4449.555044495524;4467.754273514988;4486.910261794746;4522;5751.769929202785;5754.568407369464;5763.711814409263;5767.336756272998;5776.740046697909;5632.831015069554;5994;6105.32841387447;6156.969215153896;6200;5692.31692196939;5714.342850572238;5719.542436605062;5764.193012978187;5794.710264486759;5405.297311349578;5411.998780225671;5412.404635490093;5417.972910135503;5425.83722488326;6208.720607572761;6300.960341543594;6304.401081897213;6340.778072767431;6371.681415929278;6329.208848893888;6350.968245158745;6358.1734374531015;6367.52243581724;6368.395002474757;6335.809925702236;6336.303006669171;6342.207224096988;6347.301796802441;6352.110704501281;5392.487713667352;6129.662868542095;6167.044108163242;6188.969055154876;6210.099535567227;6256.5620406572925;6289.7169627365965;6297.338779428094;6305.621662700252;6323.830091433099;6076.179715738319;6093.542984276286;6093.725782339717;6098.298695650057;6103.847403814762;5896.941030589811;5946.256717910262;6085;6115.818436018264;6123.84690382744;5936.3470018297085;5942.61373010753;5959.582829202088;5971.611845230046;5976.641401515923;6097.4817140543555;6099.542534309856;6105.929529384373;6106.267247930276;6115.969420152871;5806.1000544915605;5907.17299578068;5915.674637894957;6011.909821352596;6018.097285407049 23:43:29 INFO - PROCESS | 1674 | |1;ext.html;516.6693316277582;517.7851191755421;555.4972749661555;577.8212446608918;580.4572724502589;31469.370612587736;38421.61578384216;39225.41161882567;39300;39385;42403.9399015024;42867.78566107173;42901.57098429015;42918.78540607292;43026.78486607563;8469.195426434479;9427.670031548898;9654.75863103421;9662.56518456671;9667.468289244089;8567.95716021419;11550.480228389742;11757.823632645473;11767.058635309155;11802.881971180306;11489.885101148966;11565.653030409101;11568.479418426148;11581.884181158208;11637.127215458831;8546.957265213696;11495.597654082067;11500.194986350958;11597.420128993559;11605.187636865423;9699.66051188212;11345.829812552818;11509.827352589718;11529.654110376701;11543.480543375568;38568.80715596404;38995.61004389948;39057.80471097655;39082.02294942621;39150.60849391498;3004.744596709282;3142.3872344892757;3510.596281427643;3581.0152208142968;3817.8854634361014;28196.577051344248;28397;28468.572971405447;28995.98514052019;29252.707472925424;2734.3847634282324;2741.0954385052883;2754.1599812057316;2778.8610569471443;2794.9161525154277;481529;481906;483126;483316;483630;63000.68499657564;63344.049839252446;63588.36411635917;63611.72776544443;63716.68141659355;68245.65877170583;69350.65324673345;69358.30641693619;69389.30610693828;69531.30468695349;16886.324547017983;16893.493195204162;17055.317787288368;17067.317307307567;17091.48725538236;11986.580469683518;12182.573609923611;12186.390680465942;13076.953843692481;13160.14459060158;17062.573435664206;17295;18610.79437476255;20097.799022009884;20173.899130504255;8103.878441823377;8226.218509241613;8282.556645789507;8310.252077312949;8380.664773409117;909.9090090990848;1026.5175367577226;1046.5186014433293;1074.785042991405;1170.2978213072258;24745.25764227076;27949.16152515428;28206.717932820407;28942.565861511674;29059.56410653842;1122.859642544682;1159.9362035087981;1160.7504386556932;1206.7586482703493;1262.6022802817083;14605.342759575844;15061.924690376478;15173.544793656209;15317.693646127014;15464.768028479582;628945;634465.6553434407;641816;643474;643758;60930.39069609247;61631.38368616346;61670;61714;61814.38185618176;1367.1660287224786;1492.1718446262428;1513.5610672904954;1530.4184409924158;1538.8307286198285;28097.157085287476;28475.735338225557;28708.569371459027;29160.854195729735;29174.27064323324;47976.28035579399;48157.27764083612;48249.75875120602;48326.55020349395;48369.75815120902;354840;355821;355977;356292;790441;26476.602850957635;27049.729502705508;27315.726842731317;27325.726742732317;27350.863245683646;41083.383749244385;42674;43068.56931430771;43134;43333.91665208395;35302.47046294256;37255.44116838304;37275.81362093172;37979.81010094932;38109;36160.4575931356;36394.45408318824;37024.62975370319;37026.444603330434;37378.43932340963;35503.64496355003;37313.626863732105;37811.81094094512;37854.053648659006;39517.80241098776;31639.52540711845;32213.639901802362;33074.031116266204;33078.5038224422;33353.499697505045;24639.63040554429;24656.753432465444;24940.625890612017;25120.74879251184;25375 23:43:29 INFO - PROCESS | 1674 | |2;jquery.html;401.977891215983;407.5801924018261;413.1200542843746;413.15097474689503;423.1832563153115;13572;19709.90145049276;20312.492187695316;20340.288089916856;20534.87058211798;15925.840741592581;17989.91005044973;20549.280775172836;20846.374608761722;20865.06107225175;5134.178531434971;5465.617406781527;5470.91793623096;5474.7810087596445;5476.04169270378;5399.406065332811;5482.890342193154;5486.067368547357;5491.066518691812;5494;5651.2653355063785;5830.941690583103;6026.63840169589;6029.035354343306;6035.818925432244;5847.619904706202;5928.1404196391495;5952.523798096164;5975.312839023524;5987.491063259628;4669.41632295963;5907.409259074058;5913.2608423947;5914.645121292736;5926.436988486087;13980.650483737887;14024.719505609934;14038;19578.021098944995;25095;2068.513899233679;2145.785421457842;2189.7591264960843;2679.9062032828756;2787.484315401637;8587.484750914902;9522.904770952271;10054.447005414699;10112.696619101438;10318.484075796256;1684.6546457976085;1728.4382575662835;1756.6398888227882;1761.0490335218967;1785.9642807143914;108788.4560577192;109594.45202774015;110259.89740102577;114426;121117.57764844653;9941.353812002197;10351.844722329171;11913.821292680617;11951.22317049389;12080.456379462945;15513.457029004156;15541.378344866078;15546.067235965878;15548;15553.455629053155;7530.548167109991;8210.261076503144;8878.067802880734;8944;8971.37200395979;8778.956105219433;8789.165029322205;8791.560421978875;8844.73465796027;8896.243819275305;3963.365861462152;4355.216061108968;4356.324769660644;4737.549932756383;4767.380240568773;1736.6353065856058;1992.7807941126464;1999.7100420438956;2027.624889395437;2046.4474591860412;767.4972892755254;939.5114540438933;980.4558470049049;981.082687687015;1004.3772860826334;20184.79815201829;20257.29099481511;20628.896855515926;20850;20960.95195240232;952.3143336797494;956.8516879883629;969.3117886300664;1006.9345492543108;1018.2108865629049;10893.346399216072;11018.50416731249;11089.500972456259;11098.167637427225;11108.444577771079;597397;608329;617473;618298;618794;113313;113989;117160;125459.74540254663;126747;1033.607229252894;1049.7008352619466;1065.9127689756406;1093.797647435227;1094.141099237095;18058.367957121438;18257.817421826145;18728.344507942158;18755.437336879917;19272.614547709247;46968.53031469778;48398.75800620974;50084.74957625189;50319.23882664089;52160;65791.34208657852;67252;71912.64043679748;73290;74622;7280.708771649075;8120.796980075309;8265;8338.708145214889;8458.28104611115;11927.4036298183;12901.806472903101;13051.412686429323;13248.403821827847;13311.00167487423;11296.717582060186;11411.885881141083;11420;11535.90408911152;11610.013148882439;10984.340939543654;11113.333200008025;11160.888391115985;11356.659300221007;11493.942530287295;11296.661100167008;11355.37545435009;11511.597094101666;11572.59495917639;11732.237404568676;8252.669893204205;8380.20388063133;8386.203310685476;8608.010078841013;8725.520096394504;11018.614348497764;11346.432678365883;11507.597234096766;11634.301941883514;11676.416179191176 23:43:29 INFO - PROCESS | 1674 | |3;mootools.html;1292.1213574769154;1362.632089335879;1378.5244090788678;1750.7461418094376;1873.1102726205054;7965.840683186333;8507;8638.740837774862;8673.30613550916;8714.91285087149;7595.354394876428;8561.315094792417;8803.075677053897;8803.95598022009;8832.955835220815;2437.488127493224;2464;2468.8024958003316;2482.03200751707;2487.7885379742743;2418.1536462238164;2479.1446950801947;2487.067349743846;2490.7384724603926;2502.6621406110125;2449.4121410861353;2481.1192026830486;2486.3286912533604;2494.3888747256947;2496.4133428644254;2443.1693224303644;2467.395488105408;2483.4784695214025;2484.2050543825985;2484.6148846928754;2424.175780234711;2474.2577226831877;2479.6280557916275;2482.5407299649623;2486.3411196033067;8605.827883442296;8881.644734210624;8951.14964078418;8954.552272386354;8960.193582577534;5332.733363331818;5469.453054694539;5472.863178420532;5501.394846566875;5505;3447.6035255945635;3551.698105661022;3558.7864728116397;3571.428571428559;3610.638936106395;1056.5826498533036;1084.9457527123611;1088.67883974227;1089.8910108989116;1099.3898386395547;10472.214583906161;11835.993940515067;11852.288862668267;11887.28676279426;12016.279023258632;4542.909141817145;4546.65900057497;4663.813447462131;4669.976650116796;4685.906281874411;9428.962814090444;9435.339526233098;9439.386439881386;9448.858267126;9452.480113593818;4618.399608050999;5281.366236051698;5284.5772338212855;5293.841184764463;5320.547753441001;4362.738235705868;4466.843660471868;4472.731636101779;4508.301213311942;4950.282209079735;3288.901332960015;3606.404943184398;3919.9804000980384;3926.312895243321;3938.940915886264;1184.7156682396162;1208.7582483503334;1208.9119792186925;1220.7314390834001;1223.01547254461;771.2827070824076;805.8428606421692;813.0609146435876;923.3628796130648;953.609020301673;5574.609777315549;5582.190582365534;5643.633163844419;5869.571687948308;5873.735681894325;869.4609342207872;874.5758307220992;924.2144177449114;936.480253459323;950.0357137505438;3304.8433048432858;3728.2729867676085;3752.155764952918;3813.275477659236;3870.8838734838;10574.365538067741;10931.508082136323;10961.506732197071;11100.888991109985;11133.721656958478;10824.18818588609;10833.620823271147;10841.241113122163;10976.670699879016;10990.010899019127;8126.10612832588;8472.110428404929;8516.276116530164;8559;8559.358048146414;4898.4121905371585;5569.136783798518;5640.323161220515;5650.773969041193;5691;7945.761627151194;9312.394694344846;9467.71596852068;9559.091886270793;9571.28215383835;9294.953525232331;9692.951535242279;9752.707418777449;9764.511774411108;9779.706608801747;8263.95868020656;8330.833383332421;8410.537420441935;8411.747647570583;8436.957815210884;7219.278072192738;7359.8160045999275;8183.918160818315;8402.957985210034;8410.579471026547;7348.926510735038;7372.815679608053;7380.630968451664;7533.886991695239;7538.3969282458365;7304.19653838088;7305.963470182615;7349.30181632744;7467.962660186664;7512.924870751222;6780.627065511444;6975.197852247056;7010.053642758162;7023.367896889305;7025.121859767529;4806;4859;4888;4946.455889852042;5031.943291908594;4377.584129507692;4941.986892687062;4981.850544483671;4986.401631804207;5083.694978301313 23:43:29 INFO - PROCESS | 1674 | |4;prototype.html;316.6137312478775;321.60121449402743;329.72138542931225;330.756893683143;333.92486690494644;5973.163757074013;6103.779244151171;6521.119648847404;6621.6689165541775;6651.401373876351;6195.411435913581;6341.556091073615;6428.678566071701;6544.280129185786;6601.537892347537;4928.039032388686;4933.481984391641;4937.876553086175;4952.702837829742;4973.328600638922;4907.82822601209;4945.975270123644;4962.40451145863;4973.52751488608;4979.825706100287;4614.446266448031;4717.834875779348;4837.105135549935;4912.7297998610065;4917.754112294371;4006.3589825627905;4875.658703890729;4888.975555122237;4894.486078961711;4896.877578060541;4182.937255941133;4187.769672668002;4189.287821070426;4189.581041895816;4196.3705444183315;6234.688265586702;6294.276158241769;6296.96851515744;6306.779262725782;6312.747490100391;5716.228309178291;5722.570807189436;5722.799702010409;5732.914006289909;5750.482456578886;5798.9130163047585;6688.83277918051;6980.860382792366;6997.510174287802;7010.439164866799;4668.066386722635;4697.7181369117625;4703.012367402849;4713.128071306819;4713.363695901044;6278.748850046039;7216.809226477625;7420.628968551605;7430.368418684418;7452.813679657997;4753.904921901542;4762.476127625959;4767.189577771787;4767.570918617341;4778.402699662543;4153.418521406945;4321.092570560218;4738.407699037621;4786.64100192487;4829.975850120727;4723.811047558128;5440.510354068153;5680.801171958961;5687.630304030225;5703.058995265735;6195.1636529068;6590.7693230736695;6943.097397338414;6994.230634630187;7040.859182816315;1540.3222582063856;1572.6225705830518;1669.4574263364455;1697.499237724874;1699.6430749542735;1006.7382480555109;1019.4596863662271;1074.435921141407;1090.536521978156;1107.147496427746;5386.730663466811;6470.805875823733;6489.67551622417;6492.383223593751;6559.311272316433;7200.351968322876;7207.207207207203;7207.423406127497;7222.819429514304;7236.167840698282;6489;6493.25327587324;6494.902576461357;6503.7073331700185;6515.413612774873;3702.6667599916136;3810.2189051244427;3818;3851.056491159628;3860.6718428933855;6783.525153239226;7010;7537.585432801248;7571.205023472567;7611.314981651678;7433.591152486557;7453.701851925862;7459.254074592606;7461.328480436679;7470.365018973448;7529.887051694229;7544.019277494001;7564.886526702104;7581.3176814087;7594.5063570867715;6986.510944233854;7217.963910180415;7222.96388518054;7228.168760592599;7245.7826265212125;7074.434045276263;7125.180604230372;7127.10911136108;7129.572225666477;7169.318914703095;7622.047244094489;7623.352015078559;7639.541627502367;7648.388128949782;7653.895178825867;4583.564561366665;4632.351470794159;4636.953630463652;4640.141573808856;4644.953550464452;6965.756198533027;7277.818054548678;7300.342969132591;7312.19565847767;7316.890246646198;7036.261192574706;7080.362767350757;7088.645567721696;7090.326418990187;7107;7051.43588512928;7056.50604457683;7060.576365417885;7062.505624606229;7062.71749130029;6899.344562266577;6909.067275917687;6912.27421120775;6913.688884000333;6921.515493915377;6810.523263371517;7025.086738723933;7045.647717613996;7049.682764275517;7116.537425067354;6552.3775241352;6696.59820410777;6792.898106528307;6895.34494223048;6934.583924964519 23:43:29 INFO - PROCESS | 1674 | |5;yui.html;699.9020137180795;704.0776582676693;736.2269616902252;763.038571400036;767.5509826751351;12768.616941491748;14509.056911300759;14680.97233193677;14698;14946.850531494681;14129.222892740896;14141.575752727435;14203.857961420383;14210.644733881658;14217.502387416442;753.5215138387136;761.9161892191854;774.1677696476282;941.7692665297012;945.5083356654535;917.6375331743957;941.7457286532632;942.580551654514;942.7548837302282;944.7921457279388;986.0977205856633;996.1930836022844;997.7605374710054;997.8154041502308;999.8300288950859;785.3089281432332;961.3510880155936;991.6777047459605;995.6415690351504;997;727.4398712990967;728.3226599262683;729.5513259345518;729.8649749796251;731.5098883747849;14746.70506589873;14789.704205915821;14797.112173269637;14807.259637018215;14839.554813355617;12487.375631218403;12517.248965062125;12667.556635517712;12707.364631768467;12885.67785805353;10032.99670032998;10104.89895101047;10180.33827801198;10279.331843430153;10330.483475826179;3555.5377800885976;3560.572731272238;3610.7291953103622;3626.2928728897878;3780.527434070741;78496.21503784946;78686.6065669668;80517.38965220722;80942.59528702378;81012;8070.919290807017;9563;9563.378380405251;9979.700608981742;10022.296209644232;22725;22767.430814229778;22783;22785.316440506813;22804.201852935068;6937.132858392701;7036.050133232051;7211.67547460355;7212.891806622905;7288.562686238843;3833.9041523962123;3885.669718073939;3928.8232029558167;3948.1314110895555;4039.9596004040172;3580.4271316589743;3659.469376940329;3660.139867131222;3664.926701465956;3667.633236676311;648.3063122458924;657.6777379084215;667.9365460281266;670.7250027488706;681.8329509270161;523.4007061914087;593.8634114153768;595.8867815115115;606.3330336629672;625.9655718935502;7976.720814771571;8051.637676304464;8171.10117887032;8205.876911846328;8276.213759692819;528.9497497737709;591.3288417646003;592.280379339105;600.3486217454024;615.3108518459264;3286.4084464796565;3810.314143454205;3815.256025075084;3853.653171214548;3889.552701439314;84465;84994;85739.71390429148;86090.13909860821;88217;50194;71230.79595939477;72278.27721722821;72287;72518.27481725221;52890.47109528932;54017.729911350194;64753;65814.67092664506;67161.99257011148;8306.210909963544;8362.289205417608;8482.151784821468;8577.699780507652;8594.78513037179;47960.7601961988;48070.75964620154;48374.516254837;48671.5132848667;50233.4929952102;57662.84674306574;61139.69430152821;65752;66246.67506649936;69424.26439339056;10637.680869573926;10789.514471848628;10998.230123891572;11044.613438529614;11151.553937842395;11855.110866685205;11957.760844782882;12047.879521204675;12144.817827732415;12461.93769031149;11131.963699901622;11486.081113511067;11488;11526;12026.518939242333;11608.610451599341;11658.70853228676;11670;11725.296482210753;12008.579699710468;10649.840252396376;11081.5567377304;11136.220464567727;11927.46326415296;11955.98374138208;9793.363431376938;9981.950090249502;10218;10244.282900196915;10292.742681432725;9068.22920051803;9338.859917101385;9702.563384647849;9804.264680148875;9840.163586095263 23:43:29 INFO - PROCESS | 1674 | __end_tp_report 23:43:29 INFO - PROCESS | 1674 | __start_cc_report 23:43:29 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1228 23:43:29 INFO - PROCESS | 1674 | __end_cc_report 23:43:29 INFO - PROCESS | 1674 | __startTimestamp1463035409541__endTimestamp 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | ------- Summary: start ------- 23:43:29 INFO - PROCESS | 1674 | Number of tests: 6 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5751.28 Median:5936.35 stddev:633.35 (10.7%) stddev-sans-first:607.73 23:43:29 INFO - PROCESS | 1674 | Values: 3395.2 3452.4 3470.8 3484.1 3521.0 5710.3 5725.7 5733.5 5737.8 5763.0 5726.9 5757.6 5762.6 5768.3 5768.5 5122.8 5190.9 5254.3 5276.3 5372.1 5288.3 5292.7 5293.9 5295.6 5296.3 5227.4 5277.1 5279.3 5288.9 5301.2 5277.9 5308.2 5320.5 5320.7 5336.1 4340.2 5119.6 5170.2 5229.0 5248.3 5595.1 5596.4 5601.0 5602.7 5642.6 6211.2 6287.5 6311.7 6317.2 6318.8 6093.2 6208.7 6371.4 6382.7 6393.4 5780.1 5807.7 5815.0 5817.8 5822.7 6313.2 6322.4 6322.5 6333.0 6346.5 4968.4 6024.2 6082.7 6166.8 6173.4 6267.2 6311.4 6332.3 6340.1 6354.1 6184.1 6217.8 6229.3 6239.5 6244.8 6106.1 6164.8 6173.3 6182.2 6284.1 4862.2 4900.6 4930.2 4945.1 4952.8 4435.3 4449.6 4467.8 4486.9 4522.0 5751.8 5754.6 5763.7 5767.3 5776.7 5632.8 5994.0 6105.3 6157.0 6200.0 5692.3 5714.3 5719.5 5764.2 5794.7 5405.3 5412.0 5412.4 5418.0 5425.8 6208.7 6301.0 6304.4 6340.8 6371.7 6329.2 6351.0 6358.2 6367.5 6368.4 6335.8 6336.3 6342.2 6347.3 6352.1 5392.5 6129.7 6167.0 6189.0 6210.1 6256.6 6289.7 6297.3 6305.6 6323.8 6076.2 6093.5 6093.7 6098.3 6103.8 5896.9 5946.3 6085.0 6115.8 6123.8 5936.3 5942.6 5959.6 5971.6 5976.6 6097.5 6099.5 6105.9 6106.3 6116.0 5806.1 5907.2 5915.7 6011.9 6018.1 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:66273.45 Median:195071.24 stddev:143870.31 (73.8%) stddev-sans-first:144189.19 23:43:29 INFO - PROCESS | 1674 | Values: 516.7 517.8 555.5 577.8 580.5 31469.4 38421.6 39225.4 39300.0 39385.0 42403.9 42867.8 42901.6 42918.8 43026.8 8469.2 9427.7 9654.8 9662.6 9667.5 8568.0 11550.5 11757.8 11767.1 11802.9 11489.9 11565.7 11568.5 11581.9 11637.1 8547.0 11495.6 11500.2 11597.4 11605.2 9699.7 11345.8 11509.8 11529.7 11543.5 38568.8 38995.6 39057.8 39082.0 39150.6 3004.7 3142.4 3510.6 3581.0 3817.9 28196.6 28397.0 28468.6 28996.0 29252.7 2734.4 2741.1 2754.2 2778.9 2794.9 481529.0 481906.0 483126.0 483316.0 483630.0 63000.7 63344.0 63588.4 63611.7 63716.7 68245.7 69350.7 69358.3 69389.3 69531.3 16886.3 16893.5 17055.3 17067.3 17091.5 11986.6 12182.6 12186.4 13077.0 13160.1 17062.6 17295.0 18610.8 20097.8 20173.9 8103.9 8226.2 8282.6 8310.3 8380.7 909.9 1026.5 1046.5 1074.8 1170.3 24745.3 27949.2 28206.7 28942.6 29059.6 1122.9 1159.9 1160.8 1206.8 1262.6 14605.3 15061.9 15173.5 15317.7 15464.8 628945.0 634465.7 641816.0 643474.0 643758.0 60930.4 61631.4 61670.0 61714.0 61814.4 1367.2 1492.2 1513.6 1530.4 1538.8 28097.2 28475.7 28708.6 29160.9 29174.3 47976.3 48157.3 48249.8 48326.6 48369.8 354840.0 355821.0 355977.0 356292.0 790441.0 26476.6 27049.7 27315.7 27325.7 27350.9 41083.4 42674.0 43068.6 43134.0 43333.9 35302.5 37255.4 37275.8 37979.8 38109.0 36160.5 36394.5 37024.6 37026.4 37378.4 35503.6 37313.6 37811.8 37854.1 39517.8 31639.5 32213.6 33074.0 33078.5 33353.5 24639.6 24656.8 24940.6 25120.7 25375.0 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:34480.27 Median:11457.44 stddev:101655.34 (887.2%) stddev-sans-first:101908.31 23:43:29 INFO - PROCESS | 1674 | Values: 402.0 407.6 413.1 413.2 423.2 13572.0 19709.9 20312.5 20340.3 20534.9 15925.8 17989.9 20549.3 20846.4 20865.1 5134.2 5465.6 5470.9 5474.8 5476.0 5399.4 5482.9 5486.1 5491.1 5494.0 5651.3 5830.9 6026.6 6029.0 6035.8 5847.6 5928.1 5952.5 5975.3 5987.5 4669.4 5907.4 5913.3 5914.6 5926.4 13980.7 14024.7 14038.0 19578.0 25095.0 2068.5 2145.8 2189.8 2679.9 2787.5 8587.5 9522.9 10054.4 10112.7 10318.5 1684.7 1728.4 1756.6 1761.0 1786.0 108788.5 109594.5 110259.9 114426.0 121117.6 9941.4 10351.8 11913.8 11951.2 12080.5 15513.5 15541.4 15546.1 15548.0 15553.5 7530.5 8210.3 8878.1 8944.0 8971.4 8779.0 8789.2 8791.6 8844.7 8896.2 3963.4 4355.2 4356.3 4737.5 4767.4 1736.6 1992.8 1999.7 2027.6 2046.4 767.5 939.5 980.5 981.1 1004.4 20184.8 20257.3 20628.9 20850.0 20961.0 952.3 956.9 969.3 1006.9 1018.2 10893.3 11018.5 11089.5 11098.2 11108.4 597397.0 608329.0 617473.0 618298.0 618794.0 113313.0 113989.0 117160.0 125459.7 126747.0 1033.6 1049.7 1065.9 1093.8 1094.1 18058.4 18257.8 18728.3 18755.4 19272.6 46968.5 48398.8 50084.7 50319.2 52160.0 65791.3 67252.0 71912.6 73290.0 74622.0 7280.7 8120.8 8265.0 8338.7 8458.3 11927.4 12901.8 13051.4 13248.4 13311.0 11296.7 11411.9 11420.0 11535.9 11610.0 10984.3 11113.3 11160.9 11356.7 11493.9 11296.7 11355.4 11511.6 11572.6 11732.2 8252.7 8380.2 8386.2 8608.0 8725.5 11018.6 11346.4 11507.6 11634.3 11676.4 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5661.87 Median:5057.82 stddev:3170.48 (62.7%) stddev-sans-first:3162.36 23:43:29 INFO - PROCESS | 1674 | Values: 1292.1 1362.6 1378.5 1750.7 1873.1 7965.8 8507.0 8638.7 8673.3 8714.9 7595.4 8561.3 8803.1 8804.0 8833.0 2437.5 2464.0 2468.8 2482.0 2487.8 2418.2 2479.1 2487.1 2490.7 2502.7 2449.4 2481.1 2486.3 2494.4 2496.4 2443.2 2467.4 2483.5 2484.2 2484.6 2424.2 2474.3 2479.6 2482.5 2486.3 8605.8 8881.6 8951.1 8954.6 8960.2 5332.7 5469.5 5472.9 5501.4 5505.0 3447.6 3551.7 3558.8 3571.4 3610.6 1056.6 1084.9 1088.7 1089.9 1099.4 10472.2 11836.0 11852.3 11887.3 12016.3 4542.9 4546.7 4663.8 4670.0 4685.9 9429.0 9435.3 9439.4 9448.9 9452.5 4618.4 5281.4 5284.6 5293.8 5320.5 4362.7 4466.8 4472.7 4508.3 4950.3 3288.9 3606.4 3920.0 3926.3 3938.9 1184.7 1208.8 1208.9 1220.7 1223.0 771.3 805.8 813.1 923.4 953.6 5574.6 5582.2 5643.6 5869.6 5873.7 869.5 874.6 924.2 936.5 950.0 3304.8 3728.3 3752.2 3813.3 3870.9 10574.4 10931.5 10961.5 11100.9 11133.7 10824.2 10833.6 10841.2 10976.7 10990.0 8126.1 8472.1 8516.3 8559.0 8559.4 4898.4 5569.1 5640.3 5650.8 5691.0 7945.8 9312.4 9467.7 9559.1 9571.3 9295.0 9693.0 9752.7 9764.5 9779.7 8264.0 8330.8 8410.5 8411.7 8437.0 7219.3 7359.8 8183.9 8403.0 8410.6 7348.9 7372.8 7380.6 7533.9 7538.4 7304.2 7306.0 7349.3 7468.0 7512.9 6780.6 6975.2 7010.1 7023.4 7025.1 4806.0 4859.0 4888.0 4946.5 5031.9 4377.6 4942.0 4981.9 4986.4 5083.7 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:5730.18 Median:6489.34 stddev:1813.70 (27.9%) stddev-sans-first:1772.68 23:43:29 INFO - PROCESS | 1674 | Values: 316.6 321.6 329.7 330.8 333.9 5973.2 6103.8 6521.1 6621.7 6651.4 6195.4 6341.6 6428.7 6544.3 6601.5 4928.0 4933.5 4937.9 4952.7 4973.3 4907.8 4946.0 4962.4 4973.5 4979.8 4614.4 4717.8 4837.1 4912.7 4917.8 4006.4 4875.7 4889.0 4894.5 4896.9 4182.9 4187.8 4189.3 4189.6 4196.4 6234.7 6294.3 6297.0 6306.8 6312.7 5716.2 5722.6 5722.8 5732.9 5750.5 5798.9 6688.8 6980.9 6997.5 7010.4 4668.1 4697.7 4703.0 4713.1 4713.4 6278.7 7216.8 7420.6 7430.4 7452.8 4753.9 4762.5 4767.2 4767.6 4778.4 4153.4 4321.1 4738.4 4786.6 4830.0 4723.8 5440.5 5680.8 5687.6 5703.1 6195.2 6590.8 6943.1 6994.2 7040.9 1540.3 1572.6 1669.5 1697.5 1699.6 1006.7 1019.5 1074.4 1090.5 1107.1 5386.7 6470.8 6489.7 6492.4 6559.3 7200.4 7207.2 7207.4 7222.8 7236.2 6489.0 6493.3 6494.9 6503.7 6515.4 3702.7 3810.2 3818.0 3851.1 3860.7 6783.5 7010.0 7537.6 7571.2 7611.3 7433.6 7453.7 7459.3 7461.3 7470.4 7529.9 7544.0 7564.9 7581.3 7594.5 6986.5 7218.0 7223.0 7228.2 7245.8 7074.4 7125.2 7127.1 7129.6 7169.3 7622.0 7623.4 7639.5 7648.4 7653.9 4583.6 4632.4 4637.0 4640.1 4645.0 6965.8 7277.8 7300.3 7312.2 7316.9 7036.3 7080.4 7088.6 7090.3 7107.0 7051.4 7056.5 7060.6 7062.5 7062.7 6899.3 6909.1 6912.3 6913.7 6921.5 6810.5 7025.1 7045.6 7049.7 7116.5 6552.4 6696.6 6792.9 6895.3 6934.6 23:43:29 INFO - PROCESS | 1674 | 23:43:29 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:17458.24 Median:591.80 stddev:23814.76 (4024.1%) stddev-sans-first:23848.33 23:43:29 INFO - PROCESS | 1674 | Values: 699.9 704.1 736.2 763.0 767.6 12768.6 14509.1 14681.0 14698.0 14946.9 14129.2 14141.6 14203.9 14210.6 14217.5 753.5 761.9 774.2 941.8 945.5 917.6 941.7 942.6 942.8 944.8 986.1 996.2 997.8 997.8 999.8 785.3 961.4 991.7 995.6 997.0 727.4 728.3 729.6 729.9 731.5 14746.7 14789.7 14797.1 14807.3 14839.6 12487.4 12517.2 12667.6 12707.4 12885.7 10033.0 10104.9 10180.3 10279.3 10330.5 3555.5 3560.6 3610.7 3626.3 3780.5 78496.2 78686.6 80517.4 80942.6 81012.0 8070.9 9563.0 9563.4 9979.7 10022.3 22725.0 22767.4 22783.0 22785.3 22804.2 6937.1 7036.1 7211.7 7212.9 7288.6 3833.9 3885.7 3928.8 3948.1 4040.0 3580.4 3659.5 3660.1 3664.9 3667.6 648.3 657.7 667.9 670.7 681.8 523.4 593.9 595.9 606.3 626.0 7976.7 8051.6 8171.1 8205.9 8276.2 528.9 591.3 592.3 600.3 615.3 3286.4 3810.3 3815.3 3853.7 3889.6 84465.0 84994.0 85739.7 86090.1 88217.0 50194.0 71230.8 72278.3 72287.0 72518.3 52890.5 54017.7 64753.0 65814.7 67162.0 8306.2 8362.3 8482.2 8577.7 8594.8 47960.8 48070.8 48374.5 48671.5 50233.5 57662.8 61139.7 65752.0 66246.7 69424.3 10637.7 10789.5 10998.2 11044.6 11151.6 11855.1 11957.8 12047.9 12144.8 12461.9 11132.0 11486.1 11488.0 11526.0 12026.5 11608.6 11658.7 11670.0 11725.3 12008.6 10649.8 11081.6 11136.2 11927.5 11956.0 9793.4 9982.0 10218.0 10244.3 10292.7 9068.2 9338.9 9702.6 9804.3 9840.2 23:43:29 INFO - PROCESS | 1674 | -------- Summary: end -------- 23:43:29 INFO - PROCESS | 1674 | 23:43:30 INFO - TEST-INFO | 1674: exit 0 23:43:30 INFO - TEST-OK | dromaeo_css | took 1115559ms 23:43:30 INFO - TEST-START | kraken 23:43:30 INFO - operating with platform_type : mac_ 23:43:30 INFO - Initialising browser for kraken test... 23:43:30 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpUVxzKy/profile http://localhost:49270/getInfo.html) 23:43:32 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 23:43:32 INFO - PROCESS | 1683 | colorDepth:24 23:43:32 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 23:43:32 INFO - PROCESS | 1683 | __metrics 23:43:32 INFO - PROCESS | 1683 | 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" 23:43:32 ERROR - PROCESS | 1683 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:43:33 INFO - TEST-INFO | 1683: exit 0 23:43:33 INFO - Browser initialized. 23:43:33 INFO - Running cycle 1/1 for kraken test... 23:43:33 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpUVxzKy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:43:45 INFO - PROCESS | 1685 | RSS: Main: 211341312 23:43:45 INFO - PROCESS | 1685 | 23:43:48 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 23:43:48 INFO - PROCESS | 1685 | RSS: Main: 272125952 23:43:48 INFO - PROCESS | 1685 | 23:43:51 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 23:43:52 INFO - PROCESS | 1685 | RSS: Main: 285855744 23:43:52 INFO - PROCESS | 1685 | 23:43:55 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 23:43:56 INFO - PROCESS | 1685 | RSS: Main: 372400128 23:43:56 INFO - PROCESS | 1685 | 23:43:58 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 23:43:58 INFO - PROCESS | 1685 | RSS: Main: 279248896 23:43:58 INFO - PROCESS | 1685 | 23:44:00 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:44:01 INFO - PROCESS | 1685 | RSS: Main: 279887872 23:44:01 INFO - PROCESS | 1685 | 23:44:04 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 23:44:04 INFO - PROCESS | 1685 | RSS: Main: 318234624 23:44:04 INFO - PROCESS | 1685 | 23:44:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 23:44:08 INFO - PROCESS | 1685 | RSS: Main: 319320064 23:44:08 INFO - PROCESS | 1685 | 23:44:11 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 23:44:11 INFO - PROCESS | 1685 | RSS: Main: 316198912 23:44:11 INFO - PROCESS | 1685 | 23:44:13 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:44:13 INFO - PROCESS | 1685 | RSS: Main: 309088256 23:44:13 INFO - PROCESS | 1685 | 23:44:15 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:44:16 INFO - PROCESS | 1685 | RSS: Main: 315432960 23:44:16 INFO - PROCESS | 1685 | 23:44:18 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:44:19 INFO - PROCESS | 1685 | RSS: Main: 318803968 23:44:19 INFO - PROCESS | 1685 | 23:44:21 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:44:22 INFO - PROCESS | 1685 | RSS: Main: 323706880 23:44:22 INFO - PROCESS | 1685 | 23:44:25 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:44:26 INFO - PROCESS | 1685 | RSS: Main: 321835008 23:44:26 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 23:44:27 INFO - PROCESS | 1685 | RSS: Main: 386060288 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | __start_tp_report 23:44:27 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 23:44:27 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 23:44:27 INFO - PROCESS | 1685 | |i|pagename|runs| 23:44:27 INFO - PROCESS | 1685 | |0;ai-astar;112;114;117;112;117;166;168;115;116;132 23:44:27 INFO - PROCESS | 1685 | |1;audio-beat-detection;153;120;155;155;120;140;121;150;185;114 23:44:27 INFO - PROCESS | 1685 | |2;audio-dft;185;189;184;183;185;185;180;196;260;199 23:44:27 INFO - PROCESS | 1685 | |3;audio-fft;67;77;69;148;47;45;72;82;142;51 23:44:27 INFO - PROCESS | 1685 | |4;audio-oscillator;90;87;148;78;82;87;124;78;81;125 23:44:27 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;87;84;87;84;86;84;88;84;87;83 23:44:27 INFO - PROCESS | 1685 | |6;imaging-darkroom;92;101;95;95;93;95;94;104;123;93 23:44:27 INFO - PROCESS | 1685 | |7;imaging-desaturate;86;84;86;87;86;84;83;86;85;85 23:44:27 INFO - PROCESS | 1685 | |8;json-parse-financial;62;66;67;64;64;64;66;84;61;58 23:44:27 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;63;64;71;73;77;73;67;69;69;70 23:44:27 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;82;80;81;80;81;80;80;80;83;86 23:44:27 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;132;129;129;145;138;124;126;127;145;146 23:44:27 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;165;163;167;168;170;166;164;166;163;164 23:44:27 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;68;66;66;69;69;66;68;67;65 23:44:27 INFO - PROCESS | 1685 | __end_tp_report 23:44:27 INFO - PROCESS | 1685 | __start_cc_report 23:44:27 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,2042 23:44:27 INFO - PROCESS | 1685 | __end_cc_report 23:44:27 INFO - PROCESS | 1685 | __startTimestamp1463035467953__endTimestamp 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | ------- Summary: start ------- 23:44:27 INFO - PROCESS | 1685 | Number of tests: 14 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:126.90 Median:117.00 stddev:21.89 (18.7%) stddev-sans-first:22.54 23:44:27 INFO - PROCESS | 1685 | Values: 112.0 114.0 117.0 112.0 117.0 166.0 168.0 115.0 116.0 132.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:151.50 stddev:22.52 (14.9%) stddev-sans-first:23.48 23:44:27 INFO - PROCESS | 1685 | Values: 153.0 120.0 155.0 155.0 120.0 140.0 121.0 150.0 185.0 114.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:194.60 Median:187.00 stddev:23.73 (12.7%) stddev-sans-first:24.91 23:44:27 INFO - PROCESS | 1685 | Values: 185.0 189.0 184.0 183.0 185.0 185.0 180.0 196.0 260.0 199.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:80.00 Median:68.00 stddev:36.50 (53.7%) stddev-sans-first:38.41 23:44:27 INFO - PROCESS | 1685 | Values: 67.0 77.0 69.0 148.0 47.0 45.0 72.0 82.0 142.0 51.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:81.50 stddev:24.85 (30.5%) stddev-sans-first:26.18 23:44:27 INFO - PROCESS | 1685 | Values: 90.0 87.0 148.0 78.0 82.0 87.0 124.0 78.0 81.0 125.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:85.40 Median:86.50 stddev:1.78 (2.1%) stddev-sans-first:1.79 23:44:27 INFO - PROCESS | 1685 | Values: 87.0 84.0 87.0 84.0 86.0 84.0 88.0 84.0 87.0 83.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:98.50 Median:93.50 stddev:9.41 (10.1%) stddev-sans-first:9.68 23:44:27 INFO - PROCESS | 1685 | Values: 92.0 101.0 95.0 95.0 93.0 95.0 94.0 104.0 123.0 93.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.23 (1.4%) stddev-sans-first:1.27 23:44:27 INFO - PROCESS | 1685 | Values: 86.0 84.0 86.0 87.0 86.0 84.0 83.0 86.0 85.0 85.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:65.00 stddev:7.00 (10.8%) stddev-sans-first:7.30 23:44:27 INFO - PROCESS | 1685 | Values: 62.0 66.0 67.0 64.0 64.0 64.0 66.0 84.0 61.0 58.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.50 stddev:4.25 (6.0%) stddev-sans-first:3.77 23:44:27 INFO - PROCESS | 1685 | Values: 63.0 64.0 71.0 73.0 77.0 73.0 67.0 69.0 69.0 70.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.00 stddev:1.95 (2.4%) stddev-sans-first:2.05 23:44:27 INFO - PROCESS | 1685 | Values: 82.0 80.0 81.0 80.0 81.0 80.0 80.0 80.0 83.0 86.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:134.10 Median:135.00 stddev:8.62 (6.4%) stddev-sans-first:9.11 23:44:27 INFO - PROCESS | 1685 | Values: 132.0 129.0 129.0 145.0 138.0 124.0 126.0 127.0 145.0 146.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:166.00 stddev:2.27 (1.4%) stddev-sans-first:2.40 23:44:27 INFO - PROCESS | 1685 | Values: 165.0 163.0 167.0 168.0 170.0 166.0 164.0 166.0 163.0 164.0 23:44:27 INFO - PROCESS | 1685 | 23:44:27 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:1.37 (2.0%) stddev-sans-first:1.45 23:44:27 INFO - PROCESS | 1685 | Values: 67.0 68.0 66.0 66.0 69.0 69.0 66.0 68.0 67.0 65.0 23:44:27 INFO - PROCESS | 1685 | -------- Summary: end -------- 23:44:27 INFO - PROCESS | 1685 | 23:44:28 INFO - TEST-INFO | 1685: exit 0 23:44:28 INFO - TEST-OK | kraken | took 58563ms 23:44:28 INFO - SUITE-END | took 1174s 23:44:29 INFO - Completed test suite (00:19:34) 23:44:29 INFO - javascript benchmark 23:44:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.1681837949704, 3452.361313157066, 3470.826458677067, 3484.1115515543534, 3521.0317162780234, 5710.2862142232225, 5725.713714314284, 5733.541316694665, 5737.7991770288045, 5763.020286551286, 5726.885462290752, 5757.625754325966, 5762.625429347089, 5768.336641286253, 5768.451997060283, 5122.846314610549, 5190.92213616796, 5254.343207099113, 5276.287701160351, 5372.140457526796, 5288.312519372495, 5292.735363231843, 5293.86765330866, 5295.602829787762, 5296.3379577552805, 5227.398849132342, 5277.076511610472, 5279.313689220396, 5288.894222115556, 5301.1783173608155, 5277.86805329866, 5308.230306605559, 5320.547753440924, 5320.707361095138, 5336.119540275849, 4340.197063543254, 5119.590432765407, 5170.172772356443, 5228.9738551307, 5248.265242866002, 5595.13275442303, 5596.4123767004285, 5601.0, 5602.663840169603, 5642.57680673947, 6211.19254496917, 6287.4655654269445, 6311.684415779193, 6317.210348706411, 6318.810435686891, 6093.2078829751845, 6208.720607572625, 6371.426571608577, 6382.744690212386, 6393.424591786715, 5780.104083867007, 5807.651540907559, 5814.982378083861, 5817.825465236049, 5822.737976791054, 6313.242410910629, 6322.399372059647, 6322.462590679752, 6332.95506241465, 6346.492280617539, 4968.378952630921, 6024.246969128859, 6082.665453400105, 6166.845828854314, 6173.351798061229, 6267.1539342189135, 6311.368863113744, 6332.271788744353, 6340.080688300263, 6354.078658594571, 6184.072389141575, 6217.751289948442, 6229.314775374705, 6239.532035097386, 6244.812655620339, 6106.145139680449, 6164.845878853064, 6173.290071641729, 6182.165407669907, 6284.057391391328, 4862.246351815475, 4900.632452566, 4930.235813448921, 4945.085159245551, 4952.826651067196, 4435.2903535434825, 4449.555044495524, 4467.754273514988, 4486.910261794746, 4522.0, 5751.769929202785, 5754.568407369464, 5763.711814409263, 5767.336756272998, 5776.740046697909, 5632.831015069554, 5994.0, 6105.32841387447, 6156.969215153896, 6200.0, 5692.31692196939, 5714.342850572238, 5719.542436605062, 5764.193012978187, 5794.710264486759, 5405.297311349578, 5411.998780225671, 5412.404635490093, 5417.972910135503, 5425.83722488326, 6208.720607572761, 6300.960341543594, 6304.401081897213, 6340.778072767431, 6371.681415929278, 6329.208848893888, 6350.968245158745, 6358.1734374531015, 6367.52243581724, 6368.395002474757, 6335.809925702236, 6336.303006669171, 6342.207224096988, 6347.301796802441, 6352.110704501281, 5392.487713667352, 6129.662868542095, 6167.044108163242, 6188.969055154876, 6210.099535567227, 6256.5620406572925, 6289.7169627365965, 6297.338779428094, 6305.621662700252, 6323.830091433099, 6076.179715738319, 6093.542984276286, 6093.725782339717, 6098.298695650057, 6103.847403814762, 5896.941030589811, 5946.256717910262, 6085.0, 6115.818436018264, 6123.84690382744, 5936.3470018297085, 5942.61373010753, 5959.582829202088, 5971.611845230046, 5976.641401515923, 6097.4817140543555, 6099.542534309856, 6105.929529384373, 6106.267247930276, 6115.969420152871, 5806.1000544915605, 5907.17299578068, 5915.674637894957, 6011.909821352596, 6018.097285407049], "value": 5712.145834256343, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.6693316277582, 517.7851191755421, 555.4972749661555, 577.8212446608918, 580.4572724502589, 31469.370612587736, 38421.61578384216, 39225.41161882567, 39300.0, 39385.0, 42403.9399015024, 42867.78566107173, 42901.57098429015, 42918.78540607292, 43026.78486607563, 8469.195426434479, 9427.670031548898, 9654.75863103421, 9662.56518456671, 9667.468289244089, 8567.95716021419, 11550.480228389742, 11757.823632645473, 11767.058635309155, 11802.881971180306, 11489.885101148966, 11565.653030409101, 11568.479418426148, 11581.884181158208, 11637.127215458831, 8546.957265213696, 11495.597654082067, 11500.194986350958, 11597.420128993559, 11605.187636865423, 9699.66051188212, 11345.829812552818, 11509.827352589718, 11529.654110376701, 11543.480543375568, 38568.80715596404, 38995.61004389948, 39057.80471097655, 39082.02294942621, 39150.60849391498, 3004.744596709282, 3142.3872344892757, 3510.596281427643, 3581.0152208142968, 3817.8854634361014, 28196.577051344248, 28397.0, 28468.572971405447, 28995.98514052019, 29252.707472925424, 2734.3847634282324, 2741.0954385052883, 2754.1599812057316, 2778.8610569471443, 2794.9161525154277, 481529.0, 481906.0, 483126.0, 483316.0, 483630.0, 63000.68499657564, 63344.049839252446, 63588.36411635917, 63611.72776544443, 63716.68141659355, 68245.65877170583, 69350.65324673345, 69358.30641693619, 69389.30610693828, 69531.30468695349, 16886.324547017983, 16893.493195204162, 17055.317787288368, 17067.317307307567, 17091.48725538236, 11986.580469683518, 12182.573609923611, 12186.390680465942, 13076.953843692481, 13160.14459060158, 17062.573435664206, 17295.0, 18610.79437476255, 20097.799022009884, 20173.899130504255, 8103.878441823377, 8226.218509241613, 8282.556645789507, 8310.252077312949, 8380.664773409117, 909.9090090990848, 1026.5175367577226, 1046.5186014433293, 1074.785042991405, 1170.2978213072258, 24745.25764227076, 27949.16152515428, 28206.717932820407, 28942.565861511674, 29059.56410653842, 1122.859642544682, 1159.9362035087981, 1160.7504386556932, 1206.7586482703493, 1262.6022802817083, 14605.342759575844, 15061.924690376478, 15173.544793656209, 15317.693646127014, 15464.768028479582, 628945.0, 634465.6553434407, 641816.0, 643474.0, 643758.0, 60930.39069609247, 61631.38368616346, 61670.0, 61714.0, 61814.38185618176, 1367.1660287224786, 1492.1718446262428, 1513.5610672904954, 1530.4184409924158, 1538.8307286198285, 28097.157085287476, 28475.735338225557, 28708.569371459027, 29160.854195729735, 29174.27064323324, 47976.28035579399, 48157.27764083612, 48249.75875120602, 48326.55020349395, 48369.75815120902, 354840.0, 355821.0, 355977.0, 356292.0, 790441.0, 26476.602850957635, 27049.729502705508, 27315.726842731317, 27325.726742732317, 27350.863245683646, 41083.383749244385, 42674.0, 43068.56931430771, 43134.0, 43333.91665208395, 35302.47046294256, 37255.44116838304, 37275.81362093172, 37979.81010094932, 38109.0, 36160.4575931356, 36394.45408318824, 37024.62975370319, 37026.444603330434, 37378.43932340963, 35503.64496355003, 37313.626863732105, 37811.81094094512, 37854.053648659006, 39517.80241098776, 31639.52540711845, 32213.639901802362, 33074.031116266204, 33078.5038224422, 33353.499697505045, 24639.63040554429, 24656.753432465444, 24940.625890612017, 25120.74879251184, 25375.0], "value": 20124.225851866406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.977891215983, 407.5801924018261, 413.1200542843746, 413.15097474689503, 423.1832563153115, 13572.0, 19709.90145049276, 20312.492187695316, 20340.288089916856, 20534.87058211798, 15925.840741592581, 17989.91005044973, 20549.280775172836, 20846.374608761722, 20865.06107225175, 5134.178531434971, 5465.617406781527, 5470.91793623096, 5474.7810087596445, 5476.04169270378, 5399.406065332811, 5482.890342193154, 5486.067368547357, 5491.066518691812, 5494.0, 5651.2653355063785, 5830.941690583103, 6026.63840169589, 6029.035354343306, 6035.818925432244, 5847.619904706202, 5928.1404196391495, 5952.523798096164, 5975.312839023524, 5987.491063259628, 4669.41632295963, 5907.409259074058, 5913.2608423947, 5914.645121292736, 5926.436988486087, 13980.650483737887, 14024.719505609934, 14038.0, 19578.021098944995, 25095.0, 2068.513899233679, 2145.785421457842, 2189.7591264960843, 2679.9062032828756, 2787.484315401637, 8587.484750914902, 9522.904770952271, 10054.447005414699, 10112.696619101438, 10318.484075796256, 1684.6546457976085, 1728.4382575662835, 1756.6398888227882, 1761.0490335218967, 1785.9642807143914, 108788.4560577192, 109594.45202774015, 110259.89740102577, 114426.0, 121117.57764844653, 9941.353812002197, 10351.844722329171, 11913.821292680617, 11951.22317049389, 12080.456379462945, 15513.457029004156, 15541.378344866078, 15546.067235965878, 15548.0, 15553.455629053155, 7530.548167109991, 8210.261076503144, 8878.067802880734, 8944.0, 8971.37200395979, 8778.956105219433, 8789.165029322205, 8791.560421978875, 8844.73465796027, 8896.243819275305, 3963.365861462152, 4355.216061108968, 4356.324769660644, 4737.549932756383, 4767.380240568773, 1736.6353065856058, 1992.7807941126464, 1999.7100420438956, 2027.624889395437, 2046.4474591860412, 767.4972892755254, 939.5114540438933, 980.4558470049049, 981.082687687015, 1004.3772860826334, 20184.79815201829, 20257.29099481511, 20628.896855515926, 20850.0, 20960.95195240232, 952.3143336797494, 956.8516879883629, 969.3117886300664, 1006.9345492543108, 1018.2108865629049, 10893.346399216072, 11018.50416731249, 11089.500972456259, 11098.167637427225, 11108.444577771079, 597397.0, 608329.0, 617473.0, 618298.0, 618794.0, 113313.0, 113989.0, 117160.0, 125459.74540254663, 126747.0, 1033.607229252894, 1049.7008352619466, 1065.9127689756406, 1093.797647435227, 1094.141099237095, 18058.367957121438, 18257.817421826145, 18728.344507942158, 18755.437336879917, 19272.614547709247, 46968.53031469778, 48398.75800620974, 50084.74957625189, 50319.23882664089, 52160.0, 65791.34208657852, 67252.0, 71912.64043679748, 73290.0, 74622.0, 7280.708771649075, 8120.796980075309, 8265.0, 8338.708145214889, 8458.28104611115, 11927.4036298183, 12901.806472903101, 13051.412686429323, 13248.403821827847, 13311.00167487423, 11296.717582060186, 11411.885881141083, 11420.0, 11535.90408911152, 11610.013148882439, 10984.340939543654, 11113.333200008025, 11160.888391115985, 11356.659300221007, 11493.942530287295, 11296.661100167008, 11355.37545435009, 11511.597094101666, 11572.59495917639, 11732.237404568676, 8252.669893204205, 8380.20388063133, 8386.203310685476, 8608.010078841013, 8725.520096394504, 11018.614348497764, 11346.432678365883, 11507.597234096766, 11634.301941883514, 11676.416179191176], "value": 9439.954743413782, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.1213574769154, 1362.632089335879, 1378.5244090788678, 1750.7461418094376, 1873.1102726205054, 7965.840683186333, 8507.0, 8638.740837774862, 8673.30613550916, 8714.91285087149, 7595.354394876428, 8561.315094792417, 8803.075677053897, 8803.95598022009, 8832.955835220815, 2437.488127493224, 2464.0, 2468.8024958003316, 2482.03200751707, 2487.7885379742743, 2418.1536462238164, 2479.1446950801947, 2487.067349743846, 2490.7384724603926, 2502.6621406110125, 2449.4121410861353, 2481.1192026830486, 2486.3286912533604, 2494.3888747256947, 2496.4133428644254, 2443.1693224303644, 2467.395488105408, 2483.4784695214025, 2484.2050543825985, 2484.6148846928754, 2424.175780234711, 2474.2577226831877, 2479.6280557916275, 2482.5407299649623, 2486.3411196033067, 8605.827883442296, 8881.644734210624, 8951.14964078418, 8954.552272386354, 8960.193582577534, 5332.733363331818, 5469.453054694539, 5472.863178420532, 5501.394846566875, 5505.0, 3447.6035255945635, 3551.698105661022, 3558.7864728116397, 3571.428571428559, 3610.638936106395, 1056.5826498533036, 1084.9457527123611, 1088.67883974227, 1089.8910108989116, 1099.3898386395547, 10472.214583906161, 11835.993940515067, 11852.288862668267, 11887.28676279426, 12016.279023258632, 4542.909141817145, 4546.65900057497, 4663.813447462131, 4669.976650116796, 4685.906281874411, 9428.962814090444, 9435.339526233098, 9439.386439881386, 9448.858267126, 9452.480113593818, 4618.399608050999, 5281.366236051698, 5284.5772338212855, 5293.841184764463, 5320.547753441001, 4362.738235705868, 4466.843660471868, 4472.731636101779, 4508.301213311942, 4950.282209079735, 3288.901332960015, 3606.404943184398, 3919.9804000980384, 3926.312895243321, 3938.940915886264, 1184.7156682396162, 1208.7582483503334, 1208.9119792186925, 1220.7314390834001, 1223.01547254461, 771.2827070824076, 805.8428606421692, 813.0609146435876, 923.3628796130648, 953.609020301673, 5574.609777315549, 5582.190582365534, 5643.633163844419, 5869.571687948308, 5873.735681894325, 869.4609342207872, 874.5758307220992, 924.2144177449114, 936.480253459323, 950.0357137505438, 3304.8433048432858, 3728.2729867676085, 3752.155764952918, 3813.275477659236, 3870.8838734838, 10574.365538067741, 10931.508082136323, 10961.506732197071, 11100.888991109985, 11133.721656958478, 10824.18818588609, 10833.620823271147, 10841.241113122163, 10976.670699879016, 10990.010899019127, 8126.10612832588, 8472.110428404929, 8516.276116530164, 8559.0, 8559.358048146414, 4898.4121905371585, 5569.136783798518, 5640.323161220515, 5650.773969041193, 5691.0, 7945.761627151194, 9312.394694344846, 9467.71596852068, 9559.091886270793, 9571.28215383835, 9294.953525232331, 9692.951535242279, 9752.707418777449, 9764.511774411108, 9779.706608801747, 8263.95868020656, 8330.833383332421, 8410.537420441935, 8411.747647570583, 8436.957815210884, 7219.278072192738, 7359.8160045999275, 8183.918160818315, 8402.957985210034, 8410.579471026547, 7348.926510735038, 7372.815679608053, 7380.630968451664, 7533.886991695239, 7538.3969282458365, 7304.19653838088, 7305.963470182615, 7349.30181632744, 7467.962660186664, 7512.924870751222, 6780.627065511444, 6975.197852247056, 7010.053642758162, 7023.367896889305, 7025.121859767529, 4806.0, 4859.0, 4888.0, 4946.455889852042, 5031.943291908594, 4377.584129507692, 4941.986892687062, 4981.850544483671, 4986.401631804207, 5083.694978301313], "value": 4553.351417010728, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.6137312478775, 321.60121449402743, 329.72138542931225, 330.756893683143, 333.92486690494644, 5973.163757074013, 6103.779244151171, 6521.119648847404, 6621.6689165541775, 6651.401373876351, 6195.411435913581, 6341.556091073615, 6428.678566071701, 6544.280129185786, 6601.537892347537, 4928.039032388686, 4933.481984391641, 4937.876553086175, 4952.702837829742, 4973.328600638922, 4907.82822601209, 4945.975270123644, 4962.40451145863, 4973.52751488608, 4979.825706100287, 4614.446266448031, 4717.834875779348, 4837.105135549935, 4912.7297998610065, 4917.754112294371, 4006.3589825627905, 4875.658703890729, 4888.975555122237, 4894.486078961711, 4896.877578060541, 4182.937255941133, 4187.769672668002, 4189.287821070426, 4189.581041895816, 4196.3705444183315, 6234.688265586702, 6294.276158241769, 6296.96851515744, 6306.779262725782, 6312.747490100391, 5716.228309178291, 5722.570807189436, 5722.799702010409, 5732.914006289909, 5750.482456578886, 5798.9130163047585, 6688.83277918051, 6980.860382792366, 6997.510174287802, 7010.439164866799, 4668.066386722635, 4697.7181369117625, 4703.012367402849, 4713.128071306819, 4713.363695901044, 6278.748850046039, 7216.809226477625, 7420.628968551605, 7430.368418684418, 7452.813679657997, 4753.904921901542, 4762.476127625959, 4767.189577771787, 4767.570918617341, 4778.402699662543, 4153.418521406945, 4321.092570560218, 4738.407699037621, 4786.64100192487, 4829.975850120727, 4723.811047558128, 5440.510354068153, 5680.801171958961, 5687.630304030225, 5703.058995265735, 6195.1636529068, 6590.7693230736695, 6943.097397338414, 6994.230634630187, 7040.859182816315, 1540.3222582063856, 1572.6225705830518, 1669.4574263364455, 1697.499237724874, 1699.6430749542735, 1006.7382480555109, 1019.4596863662271, 1074.435921141407, 1090.536521978156, 1107.147496427746, 5386.730663466811, 6470.805875823733, 6489.67551622417, 6492.383223593751, 6559.311272316433, 7200.351968322876, 7207.207207207203, 7207.423406127497, 7222.819429514304, 7236.167840698282, 6489.0, 6493.25327587324, 6494.902576461357, 6503.7073331700185, 6515.413612774873, 3702.6667599916136, 3810.2189051244427, 3818.0, 3851.056491159628, 3860.6718428933855, 6783.525153239226, 7010.0, 7537.585432801248, 7571.205023472567, 7611.314981651678, 7433.591152486557, 7453.701851925862, 7459.254074592606, 7461.328480436679, 7470.365018973448, 7529.887051694229, 7544.019277494001, 7564.886526702104, 7581.3176814087, 7594.5063570867715, 6986.510944233854, 7217.963910180415, 7222.96388518054, 7228.168760592599, 7245.7826265212125, 7074.434045276263, 7125.180604230372, 7127.10911136108, 7129.572225666477, 7169.318914703095, 7622.047244094489, 7623.352015078559, 7639.541627502367, 7648.388128949782, 7653.895178825867, 4583.564561366665, 4632.351470794159, 4636.953630463652, 4640.141573808856, 4644.953550464452, 6965.756198533027, 7277.818054548678, 7300.342969132591, 7312.19565847767, 7316.890246646198, 7036.261192574706, 7080.362767350757, 7088.645567721696, 7090.326418990187, 7107.0, 7051.43588512928, 7056.50604457683, 7060.576365417885, 7062.505624606229, 7062.71749130029, 6899.344562266577, 6909.067275917687, 6912.27421120775, 6913.688884000333, 6921.515493915377, 6810.523263371517, 7025.086738723933, 7045.647717613996, 7049.682764275517, 7116.537425067354, 6552.3775241352, 6696.59820410777, 6792.898106528307, 6895.34494223048, 6934.583924964519], "value": 5122.303865944034, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.9020137180795, 704.0776582676693, 736.2269616902252, 763.038571400036, 767.5509826751351, 12768.616941491748, 14509.056911300759, 14680.97233193677, 14698.0, 14946.850531494681, 14129.222892740896, 14141.575752727435, 14203.857961420383, 14210.644733881658, 14217.502387416442, 753.5215138387136, 761.9161892191854, 774.1677696476282, 941.7692665297012, 945.5083356654535, 917.6375331743957, 941.7457286532632, 942.580551654514, 942.7548837302282, 944.7921457279388, 986.0977205856633, 996.1930836022844, 997.7605374710054, 997.8154041502308, 999.8300288950859, 785.3089281432332, 961.3510880155936, 991.6777047459605, 995.6415690351504, 997.0, 727.4398712990967, 728.3226599262683, 729.5513259345518, 729.8649749796251, 731.5098883747849, 14746.70506589873, 14789.704205915821, 14797.112173269637, 14807.259637018215, 14839.554813355617, 12487.375631218403, 12517.248965062125, 12667.556635517712, 12707.364631768467, 12885.67785805353, 10032.99670032998, 10104.89895101047, 10180.33827801198, 10279.331843430153, 10330.483475826179, 3555.5377800885976, 3560.572731272238, 3610.7291953103622, 3626.2928728897878, 3780.527434070741, 78496.21503784946, 78686.6065669668, 80517.38965220722, 80942.59528702378, 81012.0, 8070.919290807017, 9563.0, 9563.378380405251, 9979.700608981742, 10022.296209644232, 22725.0, 22767.430814229778, 22783.0, 22785.316440506813, 22804.201852935068, 6937.132858392701, 7036.050133232051, 7211.67547460355, 7212.891806622905, 7288.562686238843, 3833.9041523962123, 3885.669718073939, 3928.8232029558167, 3948.1314110895555, 4039.9596004040172, 3580.4271316589743, 3659.469376940329, 3660.139867131222, 3664.926701465956, 3667.633236676311, 648.3063122458924, 657.6777379084215, 667.9365460281266, 670.7250027488706, 681.8329509270161, 523.4007061914087, 593.8634114153768, 595.8867815115115, 606.3330336629672, 625.9655718935502, 7976.720814771571, 8051.637676304464, 8171.10117887032, 8205.876911846328, 8276.213759692819, 528.9497497737709, 591.3288417646003, 592.280379339105, 600.3486217454024, 615.3108518459264, 3286.4084464796565, 3810.314143454205, 3815.256025075084, 3853.653171214548, 3889.552701439314, 84465.0, 84994.0, 85739.71390429148, 86090.13909860821, 88217.0, 50194.0, 71230.79595939477, 72278.27721722821, 72287.0, 72518.27481725221, 52890.47109528932, 54017.729911350194, 64753.0, 65814.67092664506, 67161.99257011148, 8306.210909963544, 8362.289205417608, 8482.151784821468, 8577.699780507652, 8594.78513037179, 47960.7601961988, 48070.75964620154, 48374.516254837, 48671.5132848667, 50233.4929952102, 57662.84674306574, 61139.69430152821, 65752.0, 66246.67506649936, 69424.26439339056, 10637.680869573926, 10789.514471848628, 10998.230123891572, 11044.613438529614, 11151.553937842395, 11855.110866685205, 11957.760844782882, 12047.879521204675, 12144.817827732415, 12461.93769031149, 11131.963699901622, 11486.081113511067, 11488.0, 11526.0, 12026.518939242333, 11608.610451599341, 11658.70853228676, 11670.0, 11725.296482210753, 12008.579699710468, 10649.840252396376, 11081.5567377304, 11136.220464567727, 11927.46326415296, 11955.98374138208, 9793.363431376938, 9981.950090249502, 10218.0, 10244.282900196915, 10292.742681432725, 9068.22920051803, 9338.859917101385, 9702.563384647849, 9804.264680148875, 9840.163586095263], "value": 6856.068019140068, "unit": "score"}], "name": "dromaeo_css", "value": 7468.494007567192, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 117.0, 112.0, 117.0, 166.0, 168.0, 115.0, 116.0, 132.0], "value": 126.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 155.0, 155.0, 120.0, 140.0, 121.0, 150.0, 185.0, 114.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 189.0, 184.0, 183.0, 185.0, 185.0, 180.0, 196.0, 260.0, 199.0], "value": 194.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 69.0, 148.0, 47.0, 45.0, 72.0, 82.0, 142.0, 51.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 148.0, 78.0, 82.0, 87.0, 124.0, 78.0, 81.0, 125.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 85.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 95.0, 95.0, 93.0, 95.0, 94.0, 104.0, 123.0, 93.0], "value": 98.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 87.0, 86.0, 84.0, 83.0, 86.0, 85.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 67.0, 64.0, 64.0, 64.0, 66.0, 84.0, 61.0, 58.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 71.0, 73.0, 77.0, 73.0, 67.0, 69.0, 69.0, 70.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 81.0, 80.0, 81.0, 80.0, 80.0, 80.0, 83.0, 86.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 129.0, 129.0, 145.0, 138.0, 124.0, 126.0, 127.0, 145.0, 146.0], "value": 134.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 167.0, 168.0, 170.0, 166.0, 164.0, 166.0, 163.0, 164.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 66.0, 69.0, 69.0, 66.0, 68.0, 67.0, 65.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1493.1999999999998, "alertThreshold": 2.0}]} 23:44:29 INFO - Return code: 0 23:44:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:44:29 ERROR - # TBPL SUCCESS # 23:44:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:44:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:44:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:44:29 INFO - 2016-05-11 23:44:29.252 screenresolution[1689:11846] starting screenresolution argv=screenresolution get 23:44:29 INFO - 2016-05-11 23:44:29.254 screenresolution[1689:11846] Display 0: 1600x1200x32@60 23:44:29 INFO - 2016-05-11 23:44:29.260 screenresolution[1690:11849] starting screenresolution argv=screenresolution list 23:44:29 INFO - Available Modes on Display 0 23:44:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:44:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:44:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:44:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:44:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:44:29 INFO - Intel Iris: 23:44:29 INFO - Chipset Model: Intel Iris 23:44:29 INFO - Type: GPU 23:44:29 INFO - Bus: Built-In 23:44:29 INFO - VRAM (Dynamic, Max): 1536 MB 23:44:29 INFO - Vendor: Intel (0x8086) 23:44:29 INFO - Device ID: 0x0a2e 23:44:29 INFO - Revision ID: 0x0009 23:44:29 INFO - Displays: 23:44:29 INFO - Display: 23:44:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:44:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:44:29 INFO - Display Serial Number: 1600x1200 60 23:44:29 INFO - Main Display: Yes 23:44:29 INFO - Mirror: Off 23:44:29 INFO - Online: Yes 23:44:29 INFO - Rotation: Supported 23:44:29 INFO - Return code: 0 23:44:29 INFO - Running post-action listener: _resource_record_post_action 23:44:29 INFO - Running post-run listener: _resource_record_post_run 23:44:30 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 32562688; Write bytes: 656081408; Read time: 320; Write time: 1885 23:44:30 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348826112; Write bytes: 346129920; Read time: 26848; Write time: 688 23:44:30 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30780928; Write bytes: 299158528; Read time: 302; Write time: 1163 23:44:30 INFO - Running post-run listener: _upload_blobber_files 23:44:30 INFO - Blob upload gear active. 23:44:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:44:30 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 23:44:30 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:44:30 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:44:30 INFO - (blobuploader) - INFO - Open directory for files ... 23:44:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:44:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:44:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:44:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:44:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:44:32 INFO - (blobuploader) - INFO - Done attempting. 23:44:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:44:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:44:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:44:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:44:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:44:33 INFO - (blobuploader) - INFO - Done attempting. 23:44:33 INFO - (blobuploader) - INFO - Iteration through files over. 23:44:33 INFO - Return code: 0 23:44:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:44:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:44:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/081f48cbb92298663492d831d5efbcad77d1c05c05a4ba59c5e66a3929186ce8ab40b8626776dbae9529b2fa098e35beae12a955b8afa6dde4c370b1746736fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2036bd9e68663399a23f7f11afccd8d85f90a648d8e8a6c284d68eb7b6dd268fcc753a1ed7d07769ac1e0cb79c919ae34de0d9b1d3a45699a29468d258608083"} 23:44:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:44:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:44:33 INFO - Contents: 23:44:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/081f48cbb92298663492d831d5efbcad77d1c05c05a4ba59c5e66a3929186ce8ab40b8626776dbae9529b2fa098e35beae12a955b8afa6dde4c370b1746736fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2036bd9e68663399a23f7f11afccd8d85f90a648d8e8a6c284d68eb7b6dd268fcc753a1ed7d07769ac1e0cb79c919ae34de0d9b1d3a45699a29468d258608083"} 23:44:33 INFO - Running post-run listener: copy_logs_to_upload_dir 23:44:33 INFO - Copying logs to upload dir... 23:44:33 INFO - mkdir: /builds/slave/test/build/upload/logs 23:44:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.282918 ========= master_lag: 2.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-11 23:44:35.002812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 23:44:35.006178) ========= 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.s96PKX6LwC/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.E6oPTfqATs/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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/Mozilla-Aurora/sha512/081f48cbb92298663492d831d5efbcad77d1c05c05a4ba59c5e66a3929186ce8ab40b8626776dbae9529b2fa098e35beae12a955b8afa6dde4c370b1746736fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2036bd9e68663399a23f7f11afccd8d85f90a648d8e8a6c284d68eb7b6dd268fcc753a1ed7d07769ac1e0cb79c919ae34de0d9b1d3a45699a29468d258608083"} build_url:https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011132 build_url: 'https://queue.taskcluster.net/v1/task/a5swiOwTRgGHPLsEeXd3Ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/081f48cbb92298663492d831d5efbcad77d1c05c05a4ba59c5e66a3929186ce8ab40b8626776dbae9529b2fa098e35beae12a955b8afa6dde4c370b1746736fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2036bd9e68663399a23f7f11afccd8d85f90a648d8e8a6c284d68eb7b6dd268fcc753a1ed7d07769ac1e0cb79c919ae34de0d9b1d3a45699a29468d258608083"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 23:44:35.034157) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 23:44:35.034477) ========= 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.s96PKX6LwC/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.E6oPTfqATs/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.005121 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 23:44:35.082627) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 23:44:35.082952) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 23:44:35.083279) ========= ========= Total master_lag: 13.46 =========