builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0067 starttime: 1461327491.56 results: success (0) buildid: 20160422033711 builduid: 7b3e392ce0df4c0c82084ffeeb148872 revision: 005449bf12df9fa386f1644823961cebd94ed0e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:11.555722) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:11.556183) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:11.556461) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:11.937148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:11.937549) ========= 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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-04-22 05:18:11-- 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% 6.62M=0.002s 2016-04-22 05:18:12 (6.62 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125226 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:12.089774) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:12.090157) ========= 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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.019912 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:12.150874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-22 05:18:12.151230) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 005449bf12df9fa386f1644823961cebd94ed0e7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 005449bf12df9fa386f1644823961cebd94ed0e7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mn7pFSMJ7e/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.jJncAsY8A8/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-04-22 05:18:12,404 truncating revision to first 12 chars 2016-04-22 05:18:12,404 Setting DEBUG logging. 2016-04-22 05:18:12,404 attempt 1/10 2016-04-22 05:18:12,404 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/005449bf12df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 05:18:14,060 unpacking tar archive at: fx-team-005449bf12df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.901942 ========= master_lag: 5.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-04-22 05:18:19.139312) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:19.139639) ========= script_repo_revision: 005449bf12df9fa386f1644823961cebd94ed0e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 05:18:19.140052) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2016-04-22 05:18:19.140314) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2016-04-22 05:18:26.797630) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-22 05:18:26.797940) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mn7pFSMJ7e/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.jJncAsY8A8/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:18:27 INFO - MultiFileLogger online at 20160422 05:18:27 in /builds/slave/test 05:18:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:18:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:18:27 INFO - {'append_to_log': False, 05:18:27 INFO - 'base_work_dir': '/builds/slave/test', 05:18:27 INFO - 'blob_upload_branch': 'Fx-Team', 05:18:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:18:27 INFO - 'branch': 'Fx-Team', 05:18:27 INFO - 'buildbot_json_path': 'buildprops.json', 05:18:27 INFO - 'config_files': ('talos/mac_config.py',), 05:18:27 INFO - 'default_actions': ('clobber', 05:18:27 INFO - 'read-buildbot-config', 05:18:27 INFO - 'download-and-extract', 05:18:27 INFO - 'populate-webroot', 05:18:27 INFO - 'create-virtualenv', 05:18:27 INFO - 'install', 05:18:27 INFO - 'run-tests'), 05:18:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:18:27 INFO - 'download_minidump_stackwalk': True, 05:18:27 INFO - 'download_symbols': 'ondemand', 05:18:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:18:27 INFO - 'tooltool.py': '/tools/tooltool.py', 05:18:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:18:27 INFO - '/tools/misc-python/virtualenv.py')}, 05:18:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:18:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:18:27 INFO - 'installer_path': 'installer.exe', 05:18:27 INFO - 'log_level': 'info', 05:18:27 INFO - 'log_name': 'talos', 05:18:27 INFO - 'log_to_console': True, 05:18:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:18:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:18:27 INFO - 'opt_config_files': (), 05:18:27 INFO - 'pip_index': False, 05:18:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:27 INFO - 'cmd': ('bash', 05:18:27 INFO - '-c', 05:18:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:27 INFO - 'enabled': True, 05:18:27 INFO - 'halt_on_failure': False, 05:18:27 INFO - 'name': 'check_screen_resolution'},), 05:18:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:27 INFO - 'cmd': ('bash', 05:18:27 INFO - '-c', 05:18:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:27 INFO - 'enabled': True, 05:18:27 INFO - 'halt_on_failure': False, 05:18:27 INFO - 'name': 'check_screen_resolution'},), 05:18:27 INFO - 'run_cmd_checks_enabled': True, 05:18:27 INFO - 'sps_profile': False, 05:18:27 INFO - 'sps_profile_interval': 0, 05:18:27 INFO - 'suite': 'dromaeojs', 05:18:27 INFO - 'system_bits': '32', 05:18:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:18:27 INFO - 'title': 't-yosemite-r7-0067', 05:18:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:18:27 INFO - 'use_talos_json': True, 05:18:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:18:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:18:27 INFO - 'work_dir': 'build'} 05:18:27 INFO - ##### 05:18:27 INFO - ##### Running clobber step. 05:18:27 INFO - ##### 05:18:27 INFO - Running pre-action listener: _resource_record_pre_action 05:18:27 INFO - Running main action method: clobber 05:18:27 INFO - rmtree: /builds/slave/test/build 05:18:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:18:27 INFO - Running post-action listener: _resource_record_post_action 05:18:27 INFO - ##### 05:18:27 INFO - ##### Running read-buildbot-config step. 05:18:27 INFO - ##### 05:18:27 INFO - Running pre-action listener: _resource_record_pre_action 05:18:27 INFO - Running main action method: read_buildbot_config 05:18:27 INFO - Using buildbot properties: 05:18:27 INFO - { 05:18:27 INFO - "project": "", 05:18:27 INFO - "product": "firefox", 05:18:27 INFO - "script_repo_revision": "production", 05:18:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:18:27 INFO - "repository": "", 05:18:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:18:27 INFO - "buildid": "20160422033711", 05:18:27 INFO - "pgo_build": "False", 05:18:27 INFO - "basedir": "/builds/slave/test", 05:18:27 INFO - "buildnumber": 753, 05:18:27 INFO - "slavename": "t-yosemite-r7-0067", 05:18:27 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:18:27 INFO - "platform": "yosemite_r7", 05:18:27 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:18:27 INFO - "branch": "fx-team", 05:18:27 INFO - "repo_path": "integration/fx-team", 05:18:27 INFO - "revision": "005449bf12df9fa386f1644823961cebd94ed0e7", 05:18:27 INFO - "stage_platform": "macosx64", 05:18:27 INFO - "builduid": "7b3e392ce0df4c0c82084ffeeb148872", 05:18:27 INFO - "slavebuilddir": "test" 05:18:27 INFO - } 05:18:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:18:27 INFO - Running post-action listener: _resource_record_post_action 05:18:27 INFO - ##### 05:18:27 INFO - ##### Running download-and-extract step. 05:18:27 INFO - ##### 05:18:27 INFO - Running pre-action listener: _resource_record_pre_action 05:18:27 INFO - Running main action method: download_and_extract 05:18:27 INFO - mkdir: /builds/slave/test/build/tests 05:18:27 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')]} 05:18:27 INFO - https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:18:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:18:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:18:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:18:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:18:28 INFO - Downloaded 1199 bytes. 05:18:28 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:18:28 INFO - Using the following test package requirements: 05:18:28 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:18:28 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:18:28 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:18:28 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'jsshell-mac.zip'], 05:18:28 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:18:28 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:18:28 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:18:28 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:18:28 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:18:28 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:18:28 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:18:28 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:28 INFO - https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:18:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:18:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:18:30 INFO - Downloaded 18105781 bytes. 05:18:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:33 INFO - Return code: 0 05:18:33 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:33 INFO - https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:18:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:18:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:18:34 INFO - Downloaded 18105781 bytes. 05:18:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:37 INFO - Return code: 0 05:18:37 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')]} 05:18:37 INFO - https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:18:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:18:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:18:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:18:38 INFO - Downloaded 10845952 bytes. 05:18:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:18:39 INFO - Return code: 0 05:18:39 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')]} 05:18:39 INFO - https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:18:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:18:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:18:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:18:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:18:43 INFO - Downloaded 110032931 bytes. 05:18:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:18:43 INFO - mkdir: /builds/slave/test/properties 05:18:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:43 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:43 INFO - Contents: 05:18:43 INFO - build_url:https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:18:45 INFO - Running post-action listener: _resource_record_post_action 05:18:45 INFO - Running post-action listener: set_extra_try_arguments 05:18:45 INFO - ##### 05:18:45 INFO - ##### Running populate-webroot step. 05:18:45 INFO - ##### 05:18:45 INFO - Running pre-action listener: _resource_record_pre_action 05:18:45 INFO - Running main action method: populate_webroot 05:18:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:45 INFO - '--tppagecycles', 05:18:45 INFO - '7'], 05:18:45 INFO - 'tests': ['tsvgm']}}, 05:18:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['damp', 'tps']}, 05:18:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'tests': ['damp', 'tps']}, 05:18:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['dromaeo_dom']}, 05:18:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['a11yr', 05:18:45 INFO - 'ts_paint', 05:18:45 INFO - 'tpaint', 05:18:45 INFO - 'sessionrestore', 05:18:45 INFO - 'sessionrestore_no_auto_restore', 05:18:45 INFO - 'tabpaint']}, 05:18:45 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:45 INFO - 'ts_paint', 05:18:45 INFO - 'tpaint', 05:18:45 INFO - 'sessionrestore', 05:18:45 INFO - 'sessionrestore_no_auto_restore', 05:18:45 INFO - 'tabpaint']}, 05:18:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:45 INFO - 'ts_paint', 05:18:45 INFO - 'tpaint', 05:18:45 INFO - 'sessionrestore', 05:18:45 INFO - 'sessionrestore_no_auto_restore', 05:18:45 INFO - 'tabpaint']}, 05:18:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:45 INFO - 'ts_paint', 05:18:45 INFO - 'tpaint', 05:18:45 INFO - 'sessionrestore', 05:18:45 INFO - 'sessionrestore_no_auto_restore', 05:18:45 INFO - 'tabpaint']}, 05:18:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['a11yr', 05:18:45 INFO - 'ts_paint', 05:18:45 INFO - 'tpaint', 05:18:45 INFO - 'sessionrestore', 05:18:45 INFO - 'sessionrestore_no_auto_restore', 05:18:45 INFO - 'tabpaint']}, 05:18:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['a11yr', 05:18:45 INFO - 'ts_paint', 05:18:45 INFO - 'tpaint', 05:18:45 INFO - 'sessionrestore', 05:18:45 INFO - 'sessionrestore_no_auto_restore', 05:18:45 INFO - 'tabpaint']}, 05:18:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['tsvgx', 05:18:45 INFO - 'tsvgr_opacity', 05:18:45 INFO - 'tart', 05:18:45 INFO - 'tscrollx', 05:18:45 INFO - 'cart']}, 05:18:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:45 INFO - 'tsvgr_opacity', 05:18:45 INFO - 'tart', 05:18:45 INFO - 'tscrollx', 05:18:45 INFO - 'cart']}, 05:18:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'talos_options': ['--disable-e10s'], 05:18:45 INFO - 'tests': ['tp5o']}, 05:18:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'tests': ['tp5o']}, 05:18:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'talos_options': ['--disable-e10s', 05:18:45 INFO - '--xperf_path', 05:18:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:45 INFO - 'tests': ['tp5n']}, 05:18:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:45 INFO - 'talos_options': ['--xperf_path', 05:18:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:45 INFO - 'tests': ['tp5n']}}, 05:18:45 INFO - 'talos.zip': {'path': '', 05:18:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:45 INFO - Running post-action listener: _resource_record_post_action 05:18:45 INFO - ##### 05:18:45 INFO - ##### Running create-virtualenv step. 05:18:45 INFO - ##### 05:18:45 INFO - Running pre-action listener: _resource_record_pre_action 05:18:45 INFO - Running main action method: create_virtualenv 05:18:45 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:18:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:45 INFO - Using real prefix '/tools/python27' 05:18:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:46 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:49 INFO - Installing pip.................done. 05:18:49 INFO - Return code: 0 05:18:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18: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 05:18: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 05:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:49 INFO - 'HOME': '/Users/cltbld', 05:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:49 INFO - 'LOGNAME': 'cltbld', 05:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:49 INFO - 'MOZ_NO_REMOTE': '1', 05:18:49 INFO - 'NO_EM_RESTART': '1', 05:18:49 INFO - 'PAGER': '/bin/cat', 05:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:49 INFO - 'PWD': '/builds/slave/test', 05:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:49 INFO - 'SHELL': '/bin/bash', 05:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:18:49 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:49 INFO - 'USER': 'cltbld', 05:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:49 INFO - 'XPC_FLAGS': '0x0', 05:18:49 INFO - 'XPC_SERVICE_NAME': '0', 05:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18: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 05:18:50 INFO - Cleaning up... 05:18:50 INFO - Return code: 0 05:18:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:18: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')]} 05:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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')]} 05:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18: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 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18: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 05:18: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 05:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:50 INFO - 'HOME': '/Users/cltbld', 05:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:50 INFO - 'LOGNAME': 'cltbld', 05:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:50 INFO - 'MOZ_NO_REMOTE': '1', 05:18:50 INFO - 'NO_EM_RESTART': '1', 05:18:50 INFO - 'PAGER': '/bin/cat', 05:18:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:50 INFO - 'PWD': '/builds/slave/test', 05:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:50 INFO - 'SHELL': '/bin/bash', 05:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:18:50 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:50 INFO - 'USER': 'cltbld', 05:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:50 INFO - 'XPC_FLAGS': '0x0', 05:18:50 INFO - 'XPC_SERVICE_NAME': '0', 05:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:50 INFO - Downloading/unpacking psutil>=0.7.1 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18: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 05:18:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:53 INFO - Installing collected packages: psutil 05:18:53 INFO - Running setup.py install for psutil 05:18:53 INFO - building 'psutil._psutil_osx' extension 05:18: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 05:18: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:18: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:18:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:18:53 INFO - building 'psutil._psutil_posix' extension 05:18:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:18:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:18:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:18:53 INFO - ^ 05:18:53 INFO - 1 warning generated. 05:18:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:18:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:54 INFO - Successfully installed psutil 05:18:54 INFO - Cleaning up... 05:18:54 INFO - Return code: 0 05:18:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:18:54 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')]} 05:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:54 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')]} 05:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:54 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 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:54 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 05:18:54 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 05:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:54 INFO - 'HOME': '/Users/cltbld', 05:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:54 INFO - 'LOGNAME': 'cltbld', 05:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:54 INFO - 'MOZ_NO_REMOTE': '1', 05:18:54 INFO - 'NO_EM_RESTART': '1', 05:18:54 INFO - 'PAGER': '/bin/cat', 05:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:54 INFO - 'PWD': '/builds/slave/test', 05:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:54 INFO - 'SHELL': '/bin/bash', 05:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:18:54 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:54 INFO - 'USER': 'cltbld', 05:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:54 INFO - 'XPC_FLAGS': '0x0', 05:18:54 INFO - 'XPC_SERVICE_NAME': '0', 05:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:18:56 INFO - Installing collected packages: mozsystemmonitor 05:18:56 INFO - Running setup.py install for mozsystemmonitor 05:18:57 INFO - Successfully installed mozsystemmonitor 05:18:57 INFO - Cleaning up... 05:18:57 INFO - Return code: 0 05:18:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:18:57 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')]} 05:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:57 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')]} 05:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:57 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 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:57 INFO - 'HOME': '/Users/cltbld', 05:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:57 INFO - 'LOGNAME': 'cltbld', 05:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:57 INFO - 'MOZ_NO_REMOTE': '1', 05:18:57 INFO - 'NO_EM_RESTART': '1', 05:18:57 INFO - 'PAGER': '/bin/cat', 05:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:57 INFO - 'PWD': '/builds/slave/test', 05:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:57 INFO - 'SHELL': '/bin/bash', 05:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:18:57 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:57 INFO - 'USER': 'cltbld', 05:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:57 INFO - 'XPC_FLAGS': '0x0', 05:18:57 INFO - 'XPC_SERVICE_NAME': '0', 05:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:59 INFO - Downloading blobuploader-1.2.4.tar.gz 05:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:18:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:00 INFO - Downloading docopt-0.6.1.tar.gz 05:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:01 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:01 INFO - Running setup.py install for blobuploader 05:19:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:01 INFO - Running setup.py install for requests 05:19:01 INFO - Running setup.py install for docopt 05:19:01 INFO - Successfully installed blobuploader requests docopt 05:19:01 INFO - Cleaning up... 05:19:01 INFO - Return code: 0 05:19:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:01 INFO - 'HOME': '/Users/cltbld', 05:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:01 INFO - 'LOGNAME': 'cltbld', 05:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:01 INFO - 'MOZ_NO_REMOTE': '1', 05:19:01 INFO - 'NO_EM_RESTART': '1', 05:19:01 INFO - 'PAGER': '/bin/cat', 05:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:01 INFO - 'PWD': '/builds/slave/test', 05:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:01 INFO - 'SHELL': '/bin/bash', 05:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:19:01 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:01 INFO - 'USER': 'cltbld', 05:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:01 INFO - 'XPC_FLAGS': '0x0', 05:19:01 INFO - 'XPC_SERVICE_NAME': '0', 05:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-VB1tXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-frd_QN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-uR5oPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-tyPG3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-hPrN_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-HmK1i9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-sPncay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-F70yjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-HvhMFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-IsiM57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-H2Gx2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Y6i1wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-SQo0XL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ULRWKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-p3uDoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-3jnIRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-2u3Vzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:03 INFO - Running setup.py install for manifestparser 05:19:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:04 INFO - Running setup.py install for mozcrash 05:19:04 INFO - Running setup.py install for mozdebug 05:19:04 INFO - Running setup.py install for mozdevice 05:19:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:04 INFO - Running setup.py install for mozfile 05:19:04 INFO - Running setup.py install for mozhttpd 05:19:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:04 INFO - Running setup.py install for mozinfo 05:19:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:04 INFO - Running setup.py install for mozInstall 05:19:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Running setup.py install for mozleak 05:19:05 INFO - Running setup.py install for mozlog 05:19:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Running setup.py install for moznetwork 05:19:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Running setup.py install for mozprocess 05:19:05 INFO - Running setup.py install for mozprofile 05:19:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:05 INFO - Running setup.py install for mozrunner 05:19:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:06 INFO - Running setup.py install for mozscreenshot 05:19:06 INFO - Running setup.py install for moztest 05:19:06 INFO - Running setup.py install for mozversion 05:19:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:06 INFO - Cleaning up... 05:19:06 INFO - Return code: 0 05:19:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:06 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 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:06 INFO - 'HOME': '/Users/cltbld', 05:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:06 INFO - 'LOGNAME': 'cltbld', 05:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:06 INFO - 'MOZ_NO_REMOTE': '1', 05:19:06 INFO - 'NO_EM_RESTART': '1', 05:19:06 INFO - 'PAGER': '/bin/cat', 05:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:06 INFO - 'PWD': '/builds/slave/test', 05:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:06 INFO - 'SHELL': '/bin/bash', 05:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:19:06 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:06 INFO - 'USER': 'cltbld', 05:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:06 INFO - 'XPC_FLAGS': '0x0', 05:19:06 INFO - 'XPC_SERVICE_NAME': '0', 05:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:06 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-eiX607-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:06 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-yCNtbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19: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)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Ed_ZJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-yfgZKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-oxQkCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-gY7V_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-cO212C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-0ZXWUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-vVIqSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-m555A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:07 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)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:07 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Isj6G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:08 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ht56FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:08 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)) 05:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:08 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-BQkr2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:08 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-QSkiWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:08 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)) 05:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:08 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Gt1gHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:08 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-kEvGyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:08 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qz8VGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:11 INFO - Downloading blessings-1.6.tar.gz 05:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:11 INFO - Installing collected packages: blessings 05:19:11 INFO - Running setup.py install for blessings 05:19:11 INFO - Successfully installed blessings 05:19:11 INFO - Cleaning up... 05:19:11 INFO - Return code: 0 05:19:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:11 INFO - Reading from file tmpfile_stdout 05:19:11 INFO - Current package versions: 05:19:11 INFO - blessings == 1.6 05:19:11 INFO - blobuploader == 1.2.4 05:19:11 INFO - docopt == 0.6.1 05:19:11 INFO - manifestparser == 1.1 05:19:11 INFO - mozInstall == 1.12 05:19:11 INFO - mozcrash == 0.17 05:19:11 INFO - mozdebug == 0.1 05:19:11 INFO - mozdevice == 0.48 05:19:11 INFO - mozfile == 1.2 05:19:11 INFO - mozhttpd == 0.7 05:19:11 INFO - mozinfo == 0.9 05:19:11 INFO - mozleak == 0.1 05:19:11 INFO - mozlog == 3.1 05:19:11 INFO - moznetwork == 0.27 05:19:11 INFO - mozprocess == 0.22 05:19:11 INFO - mozprofile == 0.28 05:19:11 INFO - mozrunner == 6.11 05:19:11 INFO - mozscreenshot == 0.1 05:19:11 INFO - mozsystemmonitor == 0.0 05:19:11 INFO - moztest == 0.7 05:19:11 INFO - mozversion == 1.4 05:19:11 INFO - psutil == 3.1.1 05:19:11 INFO - requests == 1.2.3 05:19:11 INFO - wsgiref == 0.1.2 05:19:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:11 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 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:11 INFO - 'HOME': '/Users/cltbld', 05:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:11 INFO - 'LOGNAME': 'cltbld', 05:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:11 INFO - 'MOZ_NO_REMOTE': '1', 05:19:11 INFO - 'NO_EM_RESTART': '1', 05:19:11 INFO - 'PAGER': '/bin/cat', 05:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:11 INFO - 'PWD': '/builds/slave/test', 05:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:11 INFO - 'SHELL': '/bin/bash', 05:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:19:11 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:11 INFO - 'USER': 'cltbld', 05:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:11 INFO - 'XPC_FLAGS': '0x0', 05:19:11 INFO - 'XPC_SERVICE_NAME': '0', 05:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:11 INFO - Cleaning up... 05:19:11 INFO - Return code: 0 05:19:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:11 INFO - 'HOME': '/Users/cltbld', 05:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:11 INFO - 'LOGNAME': 'cltbld', 05:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:11 INFO - 'MOZ_NO_REMOTE': '1', 05:19:11 INFO - 'NO_EM_RESTART': '1', 05:19:11 INFO - 'PAGER': '/bin/cat', 05:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:11 INFO - 'PWD': '/builds/slave/test', 05:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:11 INFO - 'SHELL': '/bin/bash', 05:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:19:11 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:11 INFO - 'USER': 'cltbld', 05:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:11 INFO - 'XPC_FLAGS': '0x0', 05:19:11 INFO - 'XPC_SERVICE_NAME': '0', 05:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:12 INFO - Downloading/unpacking jsonschema 05:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:14 INFO - Downloading/unpacking functools32 (from jsonschema) 05:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:15 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:15 INFO - warning: no files found matching '*.txt' 05:19:15 INFO - no previously-included directories found matching 'build' 05:19:15 INFO - no previously-included directories found matching 'dist' 05:19:15 INFO - no previously-included directories found matching '.git*' 05:19:15 INFO - Installing collected packages: jsonschema, functools32 05:19:15 INFO - Running setup.py install for functools32 05:19:15 INFO - warning: no files found matching '*.txt' 05:19:15 INFO - no previously-included directories found matching 'build' 05:19:15 INFO - no previously-included directories found matching 'dist' 05:19:15 INFO - no previously-included directories found matching '.git*' 05:19:15 INFO - Successfully installed jsonschema functools32 05:19:15 INFO - Cleaning up... 05:19:15 INFO - Return code: 0 05:19:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:19:15 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')]} 05:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:15 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')]} 05:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:15 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 0x105a9da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105aad420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7624a8a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, '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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:15 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 05:19:15 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 05:19:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:19:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:15 INFO - 'HOME': '/Users/cltbld', 05:19:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:15 INFO - 'LOGNAME': 'cltbld', 05:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:15 INFO - 'MOZ_NO_REMOTE': '1', 05:19:15 INFO - 'NO_EM_RESTART': '1', 05:19:15 INFO - 'PAGER': '/bin/cat', 05:19:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:15 INFO - 'PWD': '/builds/slave/test', 05:19:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:15 INFO - 'SHELL': '/bin/bash', 05:19:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:19:15 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:19:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:15 INFO - 'USER': 'cltbld', 05:19:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:15 INFO - 'XPC_FLAGS': '0x0', 05:19:15 INFO - 'XPC_SERVICE_NAME': '0', 05:19:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:15 INFO - Downloading/unpacking flake8 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:19:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:19:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:19:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:19 INFO - no previously-included directories found matching 'docs/_build' 05:19:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:19:19 INFO - Running setup.py install for pep8 05:19:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:19 INFO - no previously-included directories found matching 'docs/_build' 05:19:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:19:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:19:19 INFO - Cleaning up... 05:19:19 INFO - Return code: 0 05:19:19 INFO - Running post-action listener: _resource_record_post_action 05:19:19 INFO - Running post-action listener: _start_resource_monitoring 05:19:19 INFO - Starting resource monitoring. 05:19:19 INFO - ##### 05:19:19 INFO - ##### Running install step. 05:19:19 INFO - ##### 05:19:19 INFO - Running pre-action listener: _resource_record_pre_action 05:19:19 INFO - Running main action method: install 05:19:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:19 INFO - Reading from file tmpfile_stdout 05:19:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:20 INFO - Reading from file tmpfile_stdout 05:19:20 INFO - Output received: 05:19:20 INFO - Usage: mozinstall [options] installer 05:19:20 INFO - Options: 05:19:20 INFO - -h, --help show this help message and exit 05:19:20 INFO - -d DEST, --destination=DEST 05:19:20 INFO - Directory to install application into. [default: 05:19:20 INFO - "/builds/slave/test"] 05:19:20 INFO - --app=APP Application being installed. [default: firefox] 05:19:20 INFO - mkdir: /builds/slave/test/build/application 05:19:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:19:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:19:53 INFO - Reading from file tmpfile_stdout 05:19:53 INFO - Output received: 05:19:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:19:53 INFO - Running post-action listener: _resource_record_post_action 05:19:53 INFO - ##### 05:19:53 INFO - ##### Running run-tests step. 05:19:53 INFO - ##### 05:19:53 INFO - Running pre-action listener: _resource_record_pre_action 05:19:53 INFO - Running main action method: run_tests 05:19:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:53 INFO - 2016-04-22 05:19:53.904 screenresolution[6242:101770] starting screenresolution argv=screenresolution get 05:19:53 INFO - 2016-04-22 05:19:53.906 screenresolution[6242:101770] Display 0: 1600x1200x32@60 05:19:53 INFO - 2016-04-22 05:19:53.911 screenresolution[6243:101772] starting screenresolution argv=screenresolution list 05:19:53 INFO - Available Modes on Display 0 05:19:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:54 INFO - Intel Iris: 05:19:54 INFO - Chipset Model: Intel Iris 05:19:54 INFO - Type: GPU 05:19:54 INFO - Bus: Built-In 05:19:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:54 INFO - Vendor: Intel (0x8086) 05:19:54 INFO - Device ID: 0x0a2e 05:19:54 INFO - Revision ID: 0x0009 05:19:54 INFO - Displays: 05:19:54 INFO - Display: 05:19:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:54 INFO - Display Serial Number: 1600x1200 60 05:19:54 INFO - Main Display: Yes 05:19:54 INFO - Mirror: Off 05:19:54 INFO - Online: Yes 05:19:54 INFO - Rotation: Supported 05:19:54 INFO - Return code: 0 05:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:54 INFO - Python 2.7.3 05:19:54 INFO - Return code: 0 05:19:54 INFO - grabbing minidump binary from tooltool 05:19:54 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')]} 05:19:54 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 0x10593ce00>, '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 0x105765db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105937b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:19:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:19:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:54 INFO - Return code: 0 05:19:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:19:55 INFO - Return code: 0 05:19:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 05:19:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --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 05:19:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mn7pFSMJ7e/Render', 05:19:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:55 INFO - 'HOME': '/Users/cltbld', 05:19:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:55 INFO - 'LOGNAME': 'cltbld', 05:19:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:19:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:19:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:55 INFO - 'MOZ_NO_REMOTE': '1', 05:19:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:19:55 INFO - 'NO_EM_RESTART': '1', 05:19:55 INFO - 'PAGER': '/bin/cat', 05:19:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:55 INFO - 'PWD': '/builds/slave/test', 05:19:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:19:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:55 INFO - 'SHELL': '/bin/bash', 05:19:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jJncAsY8A8/Listeners', 05:19:55 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 05:19:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:55 INFO - 'USER': 'cltbld', 05:19:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:55 INFO - 'XPC_FLAGS': '0x0', 05:19:55 INFO - 'XPC_SERVICE_NAME': '0', 05:19:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 05:19:55 INFO - mozversion application_buildid: 20160422033711 05:19:55 INFO - mozversion application_changeset: 005449bf12df9fa386f1644823961cebd94ed0e7 05:19:55 INFO - mozversion application_display_name: Nightly 05:19:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:19:55 INFO - mozversion application_name: Firefox 05:19:55 INFO - mozversion application_remotingname: firefox 05:19:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:19:55 INFO - mozversion application_vendor: Mozilla 05:19:55 INFO - mozversion application_version: 48.0a1 05:19:55 INFO - mozversion platform_buildid: 20160422033711 05:19:55 INFO - mozversion platform_changeset: 005449bf12df9fa386f1644823961cebd94ed0e7 05:19:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:19:55 INFO - mozversion platform_version: 48.0a1 05:19:55 INFO - using testdate: 1461327595 05:19:55 INFO - actual date: 1461327595 05:19:55 INFO - starting webserver on 'localhost:56934' 05:19:55 INFO - SUITE-START | Running 2 tests 05:19:55 INFO - TEST-START | dromaeo_css 05:19:55 INFO - operating with platform_type : mac_ 05:19:55 INFO - Initialising browser for dromaeo_css test... 05:19:55 INFO - TEST-INFO | started process 6261 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpRABvlo/profile http://localhost:56934/getInfo.html) 05:19:57 INFO - PROCESS | 6261 | __metrics Screen width/height:1600/1200 05:19:57 INFO - PROCESS | 6261 | colorDepth:24 05:19:57 INFO - PROCESS | 6261 | Browser inner width/height: 1024/689 05:19:57 INFO - PROCESS | 6261 | __metrics 05:19:58 INFO - PROCESS | 6261 | 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" 05:19:58 ERROR - PROCESS | 6261 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:19:58 INFO - TEST-INFO | 6261: exit 0 05:19:58 INFO - Browser initialized. 05:19:58 INFO - Running cycle 1/1 for dromaeo_css test... 05:19:58 INFO - TEST-INFO | started process 6264 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpRABvlo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:10 INFO - PROCESS | 6264 | RSS: Main: 238673920 05:20:10 INFO - PROCESS | 6264 | 05:21:01 INFO - PROCESS | 6264 | 1461327661361 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:23:12 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56934/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56934/tests/dromaeo/cssquery-ext.html) 05:23:14 INFO - PROCESS | 6264 | RSS: Main: 422883328 05:23:14 INFO - PROCESS | 6264 | 05:26:17 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56934/tests/dromaeo/cssquery-ext.html (next: http://localhost:56934/tests/dromaeo/cssquery-jquery.html) 05:26:18 INFO - PROCESS | 6264 | RSS: Main: 376934400 05:26:18 INFO - PROCESS | 6264 | 05:29:20 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56934/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56934/tests/dromaeo/cssquery-mootools.html) 05:29:21 INFO - PROCESS | 6264 | RSS: Main: 378142720 05:29:21 INFO - PROCESS | 6264 | 05:32:23 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56934/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56934/tests/dromaeo/cssquery-prototype.html) 05:32:24 INFO - PROCESS | 6264 | RSS: Main: 377020416 05:32:24 INFO - PROCESS | 6264 | 05:35:27 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56934/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56934/tests/dromaeo/cssquery-yui.html) 05:35:27 INFO - PROCESS | 6264 | RSS: Main: 343977984 05:35:27 INFO - PROCESS | 6264 | 05:38:30 INFO - PROCESS | 6264 | Cycle 1(1): loaded http://localhost:56934/tests/dromaeo/cssquery-yui.html (next: http://localhost:56934/tests/dromaeo/cssquery-dojo.html) 05:38:31 INFO - PROCESS | 6264 | RSS: Main: 449695744 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | __start_tp_report 05:38:31 INFO - PROCESS | 6264 | _x_x_mozilla_page_load 05:38:31 INFO - PROCESS | 6264 | _x_x_mozilla_page_load_details 05:38:31 INFO - PROCESS | 6264 | |i|pagename|runs| 05:38:31 INFO - PROCESS | 6264 | |0;dojo.html;3356.496525521172;3376.4428869236576;3408.5398471206395;3416.2655029168723;3456.446968485043;5589.692566908819;5642.548596112311;5673.602847800656;5691.943080569198;5696.601237913348;5604.747786349614;5678.063119585274;5685.800996965107;5695.401982791799;5695.914561281574;5114.156164232906;5129.333186685725;5144.614153938451;5171.250168725533;5218.243354713564;4539.636829053684;4943.9258411123865;5051.5453609175165;5201.219817027439;5234.842954711346;4134.235166494193;5175.58595312376;5194.662346947455;5206.167013277876;5240.816571420001;5161.303224064741;5201.427842937273;5221.39953905302;5234.319538459976;5293.550048245904;5069.163588007974;5092.79628814847;5152.9742351288005;5153.175491921312;5167.560757335594;5569.832905012856;5587.3015873015875;5591.245181900432;5600.187972743929;5632.577556683265;6207.7206525706;6223.066540019034;6229.813105606839;6233.5948163369685;6244.56288059836;6039.335673075958;6178.84552886177;6200.596961197508;6215.658138802364;6277.183966084425;4830.589399901048;5842.5618078644275;5858.912116318216;5861.120831875168;5885.352611212763;6234.719437625317;6299.212598425197;6312.305646378894;6314;6320.525960552977;6062.514998800086;6089.878202435926;6123.418275263843;6174.320824709278;6186.969065154735;6216.191895053614;6246;6267.122602835607;6271.153394291712;6274.749010039547;5246.396664383645;6117.694115294217;6209.348018458088;6222.253329600387;6231.127642130105;6134.099846298167;6139.96930015356;6151.108089327112;6188.133661287423;6241.063840423973;4884.584810291165;4917.926231106536;4928.433230178503;4931.654784165145;4932.457429682732;4403.779811009436;4410.095930334273;4420.58004489573;4475.888102797391;4506.977465112654;4838.250071238964;5721.542276617861;5751.59738818287;5759.712014399263;5764.106563482667;6153.29237137726;6195.132681424514;6288.622682639057;6292.528060395489;6302.464290535264;5324.787008519694;5686.289213848269;5701.4013528579735;5726.2555867737765;5727.914081288784;4886.828960986349;5367.570594352443;5373.516383525502;5378.462153784591;5435.10320797064;6204.045033376525;6239.532035097477;6262.874742505033;6346.650934198571;6401.839954001187;6238.937610624017;6272.655003974643;6272.937270627418;6274.901892168761;6302.684865756785;6275.403836635513;6336.714847831767;6354.809355719336;6372.203474565679;6379.457746091634;6094.573379863367;6129.358790882775;6143.96928015357;6217.533684973555;6218;6249.718762655601;6254;6262.2172228471445;6301.7164227608955;6307.936920630735;5933.970330148321;6026.548008899438;6114.38856114385;6128.142060111677;6129.295131059806;5973.313068996948;6046.606970547076;6109.266887973472;6128.479079278133;6129;4406.603405693375;5900.970495147497;5929.970350148222;5930.436608522184;5932.940670593239;5985.6408615483215;6010.54920880927;6067.817965461043;6109.95128740435;6121.483458747687;4822.734749588806;5848.181254624441;5909.172715819875;5980.491658209101;6015.578909476295 05:38:31 INFO - PROCESS | 6264 | |1;ext.html;484.5154845154845;490.50704042437343;531.2907268796157;574.967932561053;610.7556977209118;28868.566971495446;30505.084847454586;31202.84398578007;34996;35101.122471938215;40119.59880401195;40562.797186014104;40746.79626601863;40834.59165408345;41010.38484422736;9447.716568502956;9658.130768230858;9689.46707931067;9689.806203875918;9723.513824308791;8502.319814414832;8794.6921857735;11349.829752553718;11677.883221167764;11683;11408.20142590019;11496.310221386702;11496.540138394495;11511.539538418454;11512.13658975576;8507.489550626982;8772.03507614162;11421;11528.596499122532;11538.826917596243;8515.616797244076;8733.301335893113;11372.886271137266;11655.475503602274;11699.47352369137;37734.81132594347;38162.42756358657;38174.618253817665;38226.0443488915;38372.80813595942;3086.35186610812;3170.731707317078;3237.919052023695;3737.570179429346;4016.256992456405;26639.334016649544;27425.17724468269;27532.58701119485;27561.724382756318;27709.58435623467;2638.6965498967675;2683.7047924728263;2719.9936023671366;2747.011076012634;2779.027340430853;458562;463875;466395;466758.9999999966;466819;57246.14130788042;58863.117053244234;58867.11699324514;58916.7054164735;58968;61125.38874611286;61357;61400.692996535625;61405;61497.38502615006;16247.67504649924;16648.500544983668;16695.49913502597;16757.581060473585;16773.58066048334;11717.707057323465;12091.758164836654;12851.16467429614;13039.869601304055;13093.934530327479;15240.161791101596;15301.540953771404;15316.23418829054;15369.538913832603;15467.381304747683;5290.497402746733;5385.865353366197;5412.864678382994;5581.246531718247;6275.152854364603;942.4298299528823;975.2588033094898;1009.2329829329763;1011.8532812742111;1061.2836335473523;24117.879410602836;29084.12747617575;29296.873047216654;29703.851480742458;29828.850855746015;883.3595643158659;1179.941002949849;1208.7642909632536;1241.1374095003885;1256.5036810459817;13517.66205844842;14206.85793142076;15087.849121508645;15322.92739508246;15381.230938453033;520940;621407;624528;626024;627535;58311.70844145752;58808.705956469945;58815;58861.705691472125;58872.41127588755;1383.1355402873205;1383.8823699985412;1440.2222799688261;1477.7857210704174;1480.8074950256398;27903.744331504724;27949.720502794713;28433.573496397985;28558.714412856436;28584.714152859036;44208.55791442173;44600;45265.32102018538;45332.09335813199;45353;344263;347715;347842;348462;349659;32411.837940810146;37392.25215495729;37421.43867842039;38498.422523662724;38977.80511097427;41411.7929410351;42220.788896055325;42252.78873605612;42363.788181058895;42437.78781106075;33491;34922.47616285809;35411;35726.821365893;35762.64237357593;29964.700352997064;34454.65545344615;36199.45700814542;36246.09384765402;37190.8140459296;36631.45052824156;36655.63344366629;36797.44803827891;36886.446703298934;37691.05772355713;29725.702742973157;30405.239869002566;30445;30475;30607.846960765055;19612.509687257934;23142.652860207447;23303.76696233016;23606.409839754142;23840.880795595913 05:38:31 INFO - PROCESS | 6264 | |2;jquery.html;391.1336389896379;393.2057244366379;395.1819733152372;398.3506883779441;406.9979650101749;13418.865811341908;16847.83152168478;19445.611087778238;20438.591228175446;20475.385738427853;16301.266443010063;16385.754213686774;19716.605667886633;20244.493887652818;20674.379768606967;5699;5872.383399743029;5903.527717782567;5911.5270778337845;5917.88164236715;5640.830775076754;5908.17285580017;5938.910916336236;5946.524278057745;6009.5192384609345;3879.243547508223;4101.979490102545;7243.89134162988;7700.345470635003;7709.267619576131;7616.847663046763;7680.69277228913;7720.15078341382;7732.845343093107;7735.381169506426;6743;7162.498625096246;7193.928060719378;7310.52481588695;7562.243775622449;22926.426839328982;27350.042748503907;27873.58189627157;28445.431091378057;28572;2253.515494168745;2276.1919518571;2388.8447250928634;2407.626817843237;3132.859021344045;15061.698766024618;15292;15401.614959625987;15606.318104568583;16019.958702684287;1736.0364997426423;1789.0160411773472;1843.0784607696153;1871.6163186546726;1890.5273681579606;134806;138874.30562847122;139427;139797.6020239805;141029;19957.301494447624;20227.39317820468;20445.2844150454;20526.973651317374;20921.476963076046;35280.11799704977;35472.290554188774;35486.29027419437;35495.64504354975;35516.46725299123;10248.53881575316;10671.25301228922;10705.571777128984;10710.1443480347;10821.29661572011;7572.432067594952;8426.747197584082;8731.825363492695;8767.342449316327;8859.778505537284;9196.264298856075;9290.535473226311;9379.343445958853;9397.906020939703;9442.197413219954;2929.9414011719646;3220.7423406127455;3593.586737525166;3597.5323207982797;3715.684166845794;705.3373514980988;879.1911441473859;914.7392992996964;949.5679465843025;1003.7039073473343;16145.59636009109;18353;19598.8040119597;19675;20147.970405918873;919.6827094652334;946.758576562972;965.899169306755;974.5955428497236;1013.5489707080278;8232.794180145544;10419.687409377731;10579.735506612396;10670.946645266724;10758.300710453952;573793;578957;580240;589713.8228941674;596226;129297;131499;132075.33962330318;133516;133785.33107334402;1003.6738060130486;1023.2939271902363;1030.2376241581176;1042.213129087534;1043.9373637581768;14357.497487587885;14518.782218266946;18287.359942401952;18390.54023649366;18534.907325463286;36945.81527092347;45831.08337833158;48223.75888120537;50334;51764;74514.62742686251;75151.24848751443;77196.61401693181;77327.61336193283;79171.60414197893;6727.428168605723;7822.726204582812;8010.679572817022;8079.596020199084;8155.79610509742;11970.102242331686;12155.513779448724;12219.694507637025;12329.753404931671;12388.132830701765;6026.789062382795;6233.501319894488;8214.794630134294;9616;11003.33979961205;10593.470326483492;10833.67498975032;10886.94556527244;11045.558177672803;11101.222914396238;10867.565297388015;11157.330560166416;11360.1479889007;11526.366049867338;11529.884701153216;7920.84158316842;8001.319887809368;8175.959120204599;8215.383846211438;8216.383771217063;9877.30858839903;10934.398608076632;11180.217384782987;11266.267692599955;11296.209265351676 05:38:31 INFO - PROCESS | 6264 | |3;mootools.html;1361.319340329835;1578.5816758558983;1588.261458421834;1830.1306879232366;1969.2024729984353;7812.023497062867;8636.17956294152;8860.601272942717;8871.423357481759;8877;8456.112108228639;8667.826643467128;8699.826003479926;8701.95649021754;8714.782130446742;2425.9029638814472;2461.6430617560445;2461.704595448549;2464.2607217834666;2465.6794616699804;2398.2565404724505;2457.2505385857307;2459.139301244568;2463.9753602464016;2469.370310570811;2392.928212153638;2412.372783076404;2420.5884999550117;2420.697412823397;2423.6485709572103;2388.713354397466;2412.4089598048413;2420.031987205123;2421.128393778238;2425.466397392571;2384.988075059631;2404.8076153907646;2411.3007227903886;2412.9517409651885;2413.6379543068506;8587;8702.042775294713;8856.247218986395;8857.114288571156;8880;5235.764390602394;5453.100238460649;5462.153366228241;5484.7531861065945;5493.587980901449;3383.932321353559;3540.840662170183;3588.820558972067;3596.8741094061584;3607.765495242827;994.820932232198;1016.7966406718612;1019.6125472320471;1026.820306446369;1028.3367228137809;11354.886451135466;11577.6526704199;11580.247283926517;11583.6524904253;11586.768264634744;4691.81232750693;4828.076972494323;4835.782389792468;4920.114379411741;4933.9013219736125;10160.644377446753;10185.796284074277;10193.337433066976;10199.031092046243;10222.488875556193;5460.645058071213;5467.097928841776;5468.097763868917;5471.644343117685;5513.5037846594;4329.675274354436;4499.6400287976885;4662.836800711959;4988.6757360771435;4999.325091112653;3583.1904888031377;3937.92124157521;4059.106996460774;4082.3060079786505;4140.171965606891;1328.7708869295864;1369.103237379518;1371.1567386057502;1384.5946364440101;1385.0304786649185;787.8700014497658;864.831357885219;892.9821403571892;897.9191872731485;909.6991302437577;5347.946520534822;5567.220589117527;5608.691521966331;6001.699915004232;6007.219061521974;852.6802449081595;886.6453418632599;958.8398038373646;999.9750006249902;1002.6139936124645;3939.2515422069714;4029.5768944261017;4100.91798164035;4121.855735049259;4157.3140431829015;9832.262580306506;10226.846597301046;10259;10377.169826413869;10396.064354208158;10193;10203.489825508843;10328.122109620766;10333.689989300334;10372.844407333896;7969.880451793228;8207.753767386868;8297.502149871027;8301.46040507365;8312.625931833203;5657.264555607745;5925.348211696623;6023.728932198149;6050.45545900871;6063.878722425438;9533.570989305359;9709.417434953926;9831.803363932824;9941.055599718014;9959.203263738738;8733.737987860375;9117.225035872025;10231.079202871777;10244.334118282288;10287.485625718535;6900.516963812485;7119.928800711927;7364.15312239078;7640;7656.693732250647;8281.130481299377;8284.917150828414;8285.378596605158;8369.916300836914;8406.436037008527;7432.516886402366;7449;7449.4412919032375;7541.585212813348;7566.697332106654;7243.529170603894;7267;7299.781006569811;7429.591372474566;7493.925060749323;6898.724051037902;6926.307369263033;6955.547889387173;6959.338862808025;6960.9303906962305;4761.714297142044;4927.236278376858;5000.474950130184;5033.546980771748;5048.419431765394;4937;4990.525900039491;5031.270465782514;5070.087384270867;5218.321618189611 05:38:31 INFO - PROCESS | 6264 | |4;prototype.html;314.98740050397987;318.7274879977619;325.47435891035974;328.71073455359283;333.0574474237907;5193.2989046478715;5565.109582466806;5810;5896.5282777377815;5923.407659234074;4982.402111746586;5700.515456186229;5825.1844741736195;5857.765689372431;5859.560532960024;3556.137309465879;4164.208800327928;4249.6387807036435;4363.192809330269;4444.755538445384;3227.1125440503934;3286.6549012353717;3809.866654667087;4239.936400953989;4370.978145109286;4352.368906508555;4364.890877728067;4367.759773212472;4374.825006999717;4378.343248512732;3368.34317308125;4050.987253186703;4145.502539695226;4364.803583838719;4399.5160532341415;3794.1083845296334;3804.714646401503;3827.789471579062;3841.2893614681093;3853.537575490931;4339.696221264513;4586.908261834745;4791.736454495001;4880.41435027799;5548.334199895998;4371.388005679208;4981.12830254704;5076.162433198518;5106.64253502255;5109.897802043975;6115.6942152892625;6123.081537769326;6201.937980620181;6208.937910620881;6208.937910620926;4246.363045543162;4254.553271906467;4256.212600668886;4262.126264115849;4265.146970605861;4891.951080489185;6151.630902145886;6252.624842509418;6534.183227096565;6582.275949645536;4266.679999000087;4279.593438623326;4302.870913872589;4309.913801723948;4316.482022157298;4382.742887412985;4390.560943905584;4392.648588112943;4392.912141757147;4396.736195828261;4669.9259170391;5004.149294619923;5054.140796064605;5089.491050894881;5097.770600322994;6231.345708700612;6251.687415629201;6256.530760192913;6265.932242360011;6276.560640755195;1234.802431610938;1445.7325394801994;1459.37246983798;1464.6777708904024;1466.3767898643034;816.6855760532119;865.7532603208056;904.3127223310331;951.6764300137986;960.2462067277154;4562.771861406983;5649.858753531194;5715.514181294637;5919.200907877467;5928.496077833346;5042.294078828967;5335.013022590833;6140.600860944024;6508;6510.874780105576;5604.6356986796545;5719.428057194247;5733.999524149411;5845.678487683133;5876.6180198286975;3143.497040473559;3356.0702596007395;3360.823711700934;3362.9831850840587;3393.355262500117;6453.161089058486;6600.910849709744;6704;6705.698243578953;6707.496937729592;6517.380848819449;6584.9012264816065;6588.209414870151;6589.7364105435245;6612.603243805387;5586.441355864462;5778.832937648382;6624.635645039472;6683.532152749261;6690.297518760557;6205.968970155121;6382.872392527721;6435.485161187187;6437.839054023687;6442.613443193423;5253.684778913277;6285.340039295808;6317.4630156437215;6345.524085693499;6355.078513615592;6382.234131904202;6655.23464801554;6700.396964273239;6703.4972377070935;6706.138049913969;3681.687056600219;4017.794661601449;4143.212789569972;4169.187008533307;4177.874663760092;5634.577406694596;5778.913316300343;6378.90431643535;6418.390252925965;6429.453496452854;5952.404759524013;6160.630362178284;6184.474319682878;6206.813795586139;6252.374762523711;6074.453299203093;6138.662373569497;6143.262808463013;6149.938500614937;6173.84565385851;4989.80040798364;5252.261087501356;5323.866903327293;5838.620489668158;6044.637321760709;4982.626303027215;6017.849553761191;6135.325114237519;6193.071039344134;6208.72060757258;5713.228714123707;6034.129419879851;6081;6081.391860813883;6083.6349819011 05:38:31 INFO - PROCESS | 6264 | |5;yui.html;622.3993845938668;725.3145777240508;735.2830989784964;760.2891296637645;786.1156199275815;14833.480828171016;14936.701265974687;15406.460773872917;15416.922915385421;15418.768718469231;13872.583822485316;13998;14015.859841401609;14016.22910739909;14017.229052402114;708.6917191021896;731.5683746589511;885.6590212768099;891.2068259249257;920.7237828651378;765.9157492675802;913.3150137396952;918.0406475233397;920.9171174594285;922.0594993107027;974.1232890398627;978.6770365779312;980.568549838069;981.5926390547914;984.2077127912028;684.2336583026992;709.5135690727917;924.5931790012373;957.0142752964458;971.4899677669209;718.6263143165525;719.3238355945394;725.0465637686478;725.2240103089696;725.3471875312208;14905.701885962328;14937.775933361008;14943.701125977419;14957.626059348495;15007.024543404645;11632.941835290854;11664;12406.19359741623;12498.125131240817;12632.74734505314;9919.40483570988;10091.89908100917;10141.13800326973;10157.035081667229;10720.249582529226;3460.325236578868;3485.302939412102;3495.4582039783872;3731.2910546996254;3764.5294338207723;82982.9999999994;84235.5788221055;84690;84710.57644711799;84821;9433.716988490356;9487;9857;9862.901370986341;9882.209423246122;22538.887305563694;22588.774112258994;22597.887010564842;22600.66099008516;22606.886965565067;7438.256174382518;7545.019147510789;7553;7595.354394876497;7609;3506.7194624429985;3926.960730392717;4007.479027726377;4042.6159514846045;4144.544100149042;3564.1624218308675;3635.345637785171;3639.2721455708433;3643.5809881863397;3646.234290798962;528.7250629672626;618.9845253868599;646.1825790375179;650.2294780684904;653.5033374635311;497.4577710295795;560.9551235901118;566.3571845954871;584.7777844419094;598.9850253743604;7849.097353804272;7895.526268423913;7970.760877173694;8138.3692339918025;8179.22582513902;553.5615613223675;571.3657839797821;575.4705670782889;598.8233471126028;601.3715667127861;3773.2264885698914;3875.418687196943;4019.8995025124023;4068.3897415387337;4143.482064741907;92074;92809.0719092814;93103;94426;94557.5272123635;69583;74442;78639.60680196677;83239;84868.57565712131;62726.37273627205;67053;67768.3223167772;73418.8987165193;74569.62715186497;6924.446044316342;7830.491018083806;7954.5624990626075;8064.112947575879;8209.83580328402;41504.79247603743;42949.78525107355;43384.915377114565;44611.77694111509;44773.776131119135;57429.42570574241;58027.549311267554;59409.40590594212;61796.69101654463;62682;10875.129989600971;11025.117990560897;11104.389258590532;11111;11145.164112691742;11680.766384672428;11802.409879506162;11868.10989175798;11994.220375675553;12750.68123296925;9753;10852.728681783019;11471.311721296748;12122.151449398458;12189.207701499374;11126.332420054823;11212.887871121184;11237.04485118774;11580.247283926517;11690.357030363071;11066.28069175501;11136.721581960515;11179.66461006171;11343.546258149581;11547;9237.676681316121;9346.392484488486;9506.667266645634;9657.517124143906;9851.556679949563;7844.607769611611;8034.397420193392;8546.359023073419;8812.643147074295;9548.140667339972 05:38:31 INFO - PROCESS | 6264 | __end_tp_report 05:38:31 INFO - PROCESS | 6264 | __start_cc_report 05:38:31 INFO - PROCESS | 6264 | _x_x_mozilla_cycle_collect,1886 05:38:31 INFO - PROCESS | 6264 | __end_cc_report 05:38:31 INFO - PROCESS | 6264 | __startTimestamp1461328711051__endTimestamp 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | ------- Summary: start ------- 05:38:31 INFO - PROCESS | 6264 | Number of tests: 6 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | [#0] dojo.html Cycles:165 Average:5688.45 Median:5930.44 stddev:674.45 (11.4%) stddev-sans-first:651.23 05:38:31 INFO - PROCESS | 6264 | Values: 3356.5 3376.4 3408.5 3416.3 3456.4 5589.7 5642.5 5673.6 5691.9 5696.6 5604.7 5678.1 5685.8 5695.4 5695.9 5114.2 5129.3 5144.6 5171.3 5218.2 4539.6 4943.9 5051.5 5201.2 5234.8 4134.2 5175.6 5194.7 5206.2 5240.8 5161.3 5201.4 5221.4 5234.3 5293.6 5069.2 5092.8 5153.0 5153.2 5167.6 5569.8 5587.3 5591.2 5600.2 5632.6 6207.7 6223.1 6229.8 6233.6 6244.6 6039.3 6178.8 6200.6 6215.7 6277.2 4830.6 5842.6 5858.9 5861.1 5885.4 6234.7 6299.2 6312.3 6314.0 6320.5 6062.5 6089.9 6123.4 6174.3 6187.0 6216.2 6246.0 6267.1 6271.2 6274.7 5246.4 6117.7 6209.3 6222.3 6231.1 6134.1 6140.0 6151.1 6188.1 6241.1 4884.6 4917.9 4928.4 4931.7 4932.5 4403.8 4410.1 4420.6 4475.9 4507.0 4838.3 5721.5 5751.6 5759.7 5764.1 6153.3 6195.1 6288.6 6292.5 6302.5 5324.8 5686.3 5701.4 5726.3 5727.9 4886.8 5367.6 5373.5 5378.5 5435.1 6204.0 6239.5 6262.9 6346.7 6401.8 6238.9 6272.7 6272.9 6274.9 6302.7 6275.4 6336.7 6354.8 6372.2 6379.5 6094.6 6129.4 6144.0 6217.5 6218.0 6249.7 6254.0 6262.2 6301.7 6307.9 5934.0 6026.5 6114.4 6128.1 6129.3 5973.3 6046.6 6109.3 6128.5 6129.0 4406.6 5901.0 5930.0 5930.4 5932.9 5985.6 6010.5 6067.8 6110.0 6121.5 4822.7 5848.2 5909.2 5980.5 6015.6 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | [#1] ext.html Cycles:180 Average:61140.55 Median:192290.74 stddev:129213.36 (67.2%) stddev-sans-first:129495.58 05:38:31 INFO - PROCESS | 6264 | Values: 484.5 490.5 531.3 575.0 610.8 28868.6 30505.1 31202.8 34996.0 35101.1 40119.6 40562.8 40746.8 40834.6 41010.4 9447.7 9658.1 9689.5 9689.8 9723.5 8502.3 8794.7 11349.8 11677.9 11683.0 11408.2 11496.3 11496.5 11511.5 11512.1 8507.5 8772.0 11421.0 11528.6 11538.8 8515.6 8733.3 11372.9 11655.5 11699.5 37734.8 38162.4 38174.6 38226.0 38372.8 3086.4 3170.7 3237.9 3737.6 4016.3 26639.3 27425.2 27532.6 27561.7 27709.6 2638.7 2683.7 2720.0 2747.0 2779.0 458562.0 463875.0 466395.0 466759.0 466819.0 57246.1 58863.1 58867.1 58916.7 58968.0 61125.4 61357.0 61400.7 61405.0 61497.4 16247.7 16648.5 16695.5 16757.6 16773.6 11717.7 12091.8 12851.2 13039.9 13093.9 15240.2 15301.5 15316.2 15369.5 15467.4 5290.5 5385.9 5412.9 5581.2 6275.2 942.4 975.3 1009.2 1011.9 1061.3 24117.9 29084.1 29296.9 29703.9 29828.9 883.4 1179.9 1208.8 1241.1 1256.5 13517.7 14206.9 15087.8 15322.9 15381.2 520940.0 621407.0 624528.0 626024.0 627535.0 58311.7 58808.7 58815.0 58861.7 58872.4 1383.1 1383.9 1440.2 1477.8 1480.8 27903.7 27949.7 28433.6 28558.7 28584.7 44208.6 44600.0 45265.3 45332.1 45353.0 344263.0 347715.0 347842.0 348462.0 349659.0 32411.8 37392.3 37421.4 38498.4 38977.8 41411.8 42220.8 42252.8 42363.8 42437.8 33491.0 34922.5 35411.0 35726.8 35762.6 29964.7 34454.7 36199.5 36246.1 37190.8 36631.5 36655.6 36797.4 36886.4 37691.1 29725.7 30405.2 30445.0 30475.0 30607.8 19612.5 23142.7 23303.8 23606.4 23840.9 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | [#2] jquery.html Cycles:180 Average:36122.47 Median:3031.40 stddev:98024.10 (3233.6%) stddev-sans-first:98262.37 05:38:31 INFO - PROCESS | 6264 | Values: 391.1 393.2 395.2 398.4 407.0 13418.9 16847.8 19445.6 20438.6 20475.4 16301.3 16385.8 19716.6 20244.5 20674.4 5699.0 5872.4 5903.5 5911.5 5917.9 5640.8 5908.2 5938.9 5946.5 6009.5 3879.2 4102.0 7243.9 7700.3 7709.3 7616.8 7680.7 7720.2 7732.8 7735.4 6743.0 7162.5 7193.9 7310.5 7562.2 22926.4 27350.0 27873.6 28445.4 28572.0 2253.5 2276.2 2388.8 2407.6 3132.9 15061.7 15292.0 15401.6 15606.3 16020.0 1736.0 1789.0 1843.1 1871.6 1890.5 134806.0 138874.3 139427.0 139797.6 141029.0 19957.3 20227.4 20445.3 20527.0 20921.5 35280.1 35472.3 35486.3 35495.6 35516.5 10248.5 10671.3 10705.6 10710.1 10821.3 7572.4 8426.7 8731.8 8767.3 8859.8 9196.3 9290.5 9379.3 9397.9 9442.2 2929.9 3220.7 3593.6 3597.5 3715.7 705.3 879.2 914.7 949.6 1003.7 16145.6 18353.0 19598.8 19675.0 20148.0 919.7 946.8 965.9 974.6 1013.5 8232.8 10419.7 10579.7 10670.9 10758.3 573793.0 578957.0 580240.0 589713.8 596226.0 129297.0 131499.0 132075.3 133516.0 133785.3 1003.7 1023.3 1030.2 1042.2 1043.9 14357.5 14518.8 18287.4 18390.5 18534.9 36945.8 45831.1 48223.8 50334.0 51764.0 74514.6 75151.2 77196.6 77327.6 79171.6 6727.4 7822.7 8010.7 8079.6 8155.8 11970.1 12155.5 12219.7 12329.8 12388.1 6026.8 6233.5 8214.8 9616.0 11003.3 10593.5 10833.7 10886.9 11045.6 11101.2 10867.6 11157.3 11360.1 11526.4 11529.9 7920.8 8001.3 8176.0 8215.4 8216.4 9877.3 10934.4 11180.2 11266.3 11296.2 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | [#3] mootools.html Cycles:180 Average:5697.50 Median:4999.90 stddev:3124.53 (62.5%) stddev-sans-first:3116.29 05:38:31 INFO - PROCESS | 6264 | Values: 1361.3 1578.6 1588.3 1830.1 1969.2 7812.0 8636.2 8860.6 8871.4 8877.0 8456.1 8667.8 8699.8 8702.0 8714.8 2425.9 2461.6 2461.7 2464.3 2465.7 2398.3 2457.3 2459.1 2464.0 2469.4 2392.9 2412.4 2420.6 2420.7 2423.6 2388.7 2412.4 2420.0 2421.1 2425.5 2385.0 2404.8 2411.3 2413.0 2413.6 8587.0 8702.0 8856.2 8857.1 8880.0 5235.8 5453.1 5462.2 5484.8 5493.6 3383.9 3540.8 3588.8 3596.9 3607.8 994.8 1016.8 1019.6 1026.8 1028.3 11354.9 11577.7 11580.2 11583.7 11586.8 4691.8 4828.1 4835.8 4920.1 4933.9 10160.6 10185.8 10193.3 10199.0 10222.5 5460.6 5467.1 5468.1 5471.6 5513.5 4329.7 4499.6 4662.8 4988.7 4999.3 3583.2 3937.9 4059.1 4082.3 4140.2 1328.8 1369.1 1371.2 1384.6 1385.0 787.9 864.8 893.0 897.9 909.7 5347.9 5567.2 5608.7 6001.7 6007.2 852.7 886.6 958.8 1000.0 1002.6 3939.3 4029.6 4100.9 4121.9 4157.3 9832.3 10226.8 10259.0 10377.2 10396.1 10193.0 10203.5 10328.1 10333.7 10372.8 7969.9 8207.8 8297.5 8301.5 8312.6 5657.3 5925.3 6023.7 6050.5 6063.9 9533.6 9709.4 9831.8 9941.1 9959.2 8733.7 9117.2 10231.1 10244.3 10287.5 6900.5 7119.9 7364.2 7640.0 7656.7 8281.1 8284.9 8285.4 8369.9 8406.4 7432.5 7449.0 7449.4 7541.6 7566.7 7243.5 7267.0 7299.8 7429.6 7493.9 6898.7 6926.3 6955.5 6959.3 6960.9 4761.7 4927.2 5000.5 5033.5 5048.4 4937.0 4990.5 5031.3 5070.1 5218.3 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | [#4] prototype.html Cycles:180 Average:4982.09 Median:5675.19 stddev:1583.42 (27.9%) stddev-sans-first:1548.62 05:38:31 INFO - PROCESS | 6264 | Values: 315.0 318.7 325.5 328.7 333.1 5193.3 5565.1 5810.0 5896.5 5923.4 4982.4 5700.5 5825.2 5857.8 5859.6 3556.1 4164.2 4249.6 4363.2 4444.8 3227.1 3286.7 3809.9 4239.9 4371.0 4352.4 4364.9 4367.8 4374.8 4378.3 3368.3 4051.0 4145.5 4364.8 4399.5 3794.1 3804.7 3827.8 3841.3 3853.5 4339.7 4586.9 4791.7 4880.4 5548.3 4371.4 4981.1 5076.2 5106.6 5109.9 6115.7 6123.1 6201.9 6208.9 6208.9 4246.4 4254.6 4256.2 4262.1 4265.1 4892.0 6151.6 6252.6 6534.2 6582.3 4266.7 4279.6 4302.9 4309.9 4316.5 4382.7 4390.6 4392.6 4392.9 4396.7 4669.9 5004.1 5054.1 5089.5 5097.8 6231.3 6251.7 6256.5 6265.9 6276.6 1234.8 1445.7 1459.4 1464.7 1466.4 816.7 865.8 904.3 951.7 960.2 4562.8 5649.9 5715.5 5919.2 5928.5 5042.3 5335.0 6140.6 6508.0 6510.9 5604.6 5719.4 5734.0 5845.7 5876.6 3143.5 3356.1 3360.8 3363.0 3393.4 6453.2 6600.9 6704.0 6705.7 6707.5 6517.4 6584.9 6588.2 6589.7 6612.6 5586.4 5778.8 6624.6 6683.5 6690.3 6206.0 6382.9 6435.5 6437.8 6442.6 5253.7 6285.3 6317.5 6345.5 6355.1 6382.2 6655.2 6700.4 6703.5 6706.1 3681.7 4017.8 4143.2 4169.2 4177.9 5634.6 5778.9 6378.9 6418.4 6429.5 5952.4 6160.6 6184.5 6206.8 6252.4 6074.5 6138.7 6143.3 6149.9 6173.8 4989.8 5252.3 5323.9 5838.6 6044.6 4982.6 6017.8 6135.3 6193.1 6208.7 5713.2 6034.1 6081.0 6081.4 6083.6 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - PROCESS | 6264 | [#5] yui.html Cycles:180 Average:17981.38 Median:600.18 stddev:25438.77 (4238.5%) stddev-sans-first:25476.74 05:38:31 INFO - PROCESS | 6264 | Values: 622.4 725.3 735.3 760.3 786.1 14833.5 14936.7 15406.5 15416.9 15418.8 13872.6 13998.0 14015.9 14016.2 14017.2 708.7 731.6 885.7 891.2 920.7 765.9 913.3 918.0 920.9 922.1 974.1 978.7 980.6 981.6 984.2 684.2 709.5 924.6 957.0 971.5 718.6 719.3 725.0 725.2 725.3 14905.7 14937.8 14943.7 14957.6 15007.0 11632.9 11664.0 12406.2 12498.1 12632.7 9919.4 10091.9 10141.1 10157.0 10720.2 3460.3 3485.3 3495.5 3731.3 3764.5 82983.0 84235.6 84690.0 84710.6 84821.0 9433.7 9487.0 9857.0 9862.9 9882.2 22538.9 22588.8 22597.9 22600.7 22606.9 7438.3 7545.0 7553.0 7595.4 7609.0 3506.7 3927.0 4007.5 4042.6 4144.5 3564.2 3635.3 3639.3 3643.6 3646.2 528.7 619.0 646.2 650.2 653.5 497.5 561.0 566.4 584.8 599.0 7849.1 7895.5 7970.8 8138.4 8179.2 553.6 571.4 575.5 598.8 601.4 3773.2 3875.4 4019.9 4068.4 4143.5 92074.0 92809.1 93103.0 94426.0 94557.5 69583.0 74442.0 78639.6 83239.0 84868.6 62726.4 67053.0 67768.3 73418.9 74569.6 6924.4 7830.5 7954.6 8064.1 8209.8 41504.8 42949.8 43384.9 44611.8 44773.8 57429.4 58027.5 59409.4 61796.7 62682.0 10875.1 11025.1 11104.4 11111.0 11145.2 11680.8 11802.4 11868.1 11994.2 12750.7 9753.0 10852.7 11471.3 12122.2 12189.2 11126.3 11212.9 11237.0 11580.2 11690.4 11066.3 11136.7 11179.7 11343.5 11547.0 9237.7 9346.4 9506.7 9657.5 9851.6 7844.6 8034.4 8546.4 8812.6 9548.1 05:38:31 INFO - PROCESS | 6264 | -------- Summary: end -------- 05:38:31 INFO - PROCESS | 6264 | 05:38:31 INFO - TEST-INFO | 6264: exit 0 05:38:31 INFO - TEST-OK | dromaeo_css | took 1115987ms 05:38:31 INFO - TEST-START | kraken 05:38:31 INFO - operating with platform_type : mac_ 05:38:31 INFO - Initialising browser for kraken test... 05:38:31 INFO - TEST-INFO | started process 6267 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpUawGOq/profile http://localhost:56934/getInfo.html) 05:38:33 INFO - PROCESS | 6267 | __metrics Screen width/height:1600/1200 05:38:33 INFO - PROCESS | 6267 | colorDepth:24 05:38:33 INFO - PROCESS | 6267 | Browser inner width/height: 1024/689 05:38:33 INFO - PROCESS | 6267 | __metrics 05:38:34 INFO - PROCESS | 6267 | 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" 05:38:34 ERROR - PROCESS | 6267 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:38:34 INFO - TEST-INFO | 6267: exit 0 05:38:34 INFO - Browser initialized. 05:38:34 INFO - Running cycle 1/1 for kraken test... 05:38:34 INFO - TEST-INFO | started process 6269 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpUawGOq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:46 INFO - PROCESS | 6269 | RSS: Main: 238796800 05:38:46 INFO - PROCESS | 6269 | 05:38:49 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56934/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:50 INFO - PROCESS | 6269 | RSS: Main: 286089216 05:38:50 INFO - PROCESS | 6269 | 05:38:52 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56934/tests/kraken/driver.html?testName=audio-dft) 05:38:53 INFO - PROCESS | 6269 | RSS: Main: 298995712 05:38:53 INFO - PROCESS | 6269 | 05:38:57 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56934/tests/kraken/driver.html?testName=audio-fft) 05:38:57 INFO - PROCESS | 6269 | RSS: Main: 383045632 05:38:57 INFO - PROCESS | 6269 | 05:38:59 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56934/tests/kraken/driver.html?testName=audio-oscillator) 05:39:00 INFO - PROCESS | 6269 | RSS: Main: 285868032 05:39:00 INFO - PROCESS | 6269 | 05:39:02 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56934/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:02 INFO - PROCESS | 6269 | RSS: Main: 289783808 05:39:02 INFO - PROCESS | 6269 | 05:39:05 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56934/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:06 INFO - PROCESS | 6269 | RSS: Main: 328802304 05:39:06 INFO - PROCESS | 6269 | 05:39:09 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56934/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:09 INFO - PROCESS | 6269 | RSS: Main: 329228288 05:39:09 INFO - PROCESS | 6269 | 05:39:12 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56934/tests/kraken/driver.html?testName=json-parse-financial) 05:39:13 INFO - PROCESS | 6269 | RSS: Main: 326553600 05:39:13 INFO - PROCESS | 6269 | 05:39:14 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56934/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:15 INFO - PROCESS | 6269 | RSS: Main: 319373312 05:39:15 INFO - PROCESS | 6269 | 05:39:17 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:17 INFO - PROCESS | 6269 | RSS: Main: 325926912 05:39:17 INFO - PROCESS | 6269 | 05:39:19 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:20 INFO - PROCESS | 6269 | RSS: Main: 329629696 05:39:20 INFO - PROCESS | 6269 | 05:39:23 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:24 INFO - PROCESS | 6269 | RSS: Main: 330149888 05:39:24 INFO - PROCESS | 6269 | 05:39:27 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:27 INFO - PROCESS | 6269 | RSS: Main: 325926912 05:39:27 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | Cycle 1(1): loaded http://localhost:56934/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56934/tests/kraken/driver.html?testName=ai-astar) 05:39:29 INFO - PROCESS | 6269 | RSS: Main: 390119424 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | __start_tp_report 05:39:29 INFO - PROCESS | 6269 | _x_x_mozilla_page_load 05:39:29 INFO - PROCESS | 6269 | _x_x_mozilla_page_load_details 05:39:29 INFO - PROCESS | 6269 | |i|pagename|runs| 05:39:29 INFO - PROCESS | 6269 | |0;ai-astar;110;113;112;113;111;113;118;112;112;113 05:39:29 INFO - PROCESS | 6269 | |1;audio-beat-detection;160;120;144;163;119;143;141;121;174;116 05:39:29 INFO - PROCESS | 6269 | |2;audio-dft;183;188;257;225;186;185;180;181;184;186 05:39:29 INFO - PROCESS | 6269 | |3;audio-fft;73;75;93;91;55;59;56;79;83;55 05:39:29 INFO - PROCESS | 6269 | |4;audio-oscillator;91;99;132;78;79;77;125;79;79;126 05:39:29 INFO - PROCESS | 6269 | |5;imaging-gaussian-blur;88;85;89;85;87;83;87;86;88;82 05:39:29 INFO - PROCESS | 6269 | |6;imaging-darkroom;94;93;93;94;93;93;94;94;93;94 05:39:29 INFO - PROCESS | 6269 | |7;imaging-desaturate;87;86;85;87;85;85;86;85;87;104 05:39:29 INFO - PROCESS | 6269 | |8;json-parse-financial;57;62;59;59;59;59;62;81;58;58 05:39:29 INFO - PROCESS | 6269 | |9;json-stringify-tinderbox;66;64;76;76;72;70;69;70;68;72 05:39:29 INFO - PROCESS | 6269 | |10;stanford-crypto-aes;79;82;79;82;81;81;81;82;83;83 05:39:29 INFO - PROCESS | 6269 | |11;stanford-crypto-ccm;150;159;163;139;134;138;138;166;237;158 05:39:29 INFO - PROCESS | 6269 | |12;stanford-crypto-pbkdf2;184;174;170;170;169;169;167;172;178;165 05:39:29 INFO - PROCESS | 6269 | |13;stanford-crypto-sha256-iterative;68;68;69;83;93;97;99;70;68;74 05:39:29 INFO - PROCESS | 6269 | __end_tp_report 05:39:29 INFO - PROCESS | 6269 | __start_cc_report 05:39:29 INFO - PROCESS | 6269 | _x_x_mozilla_cycle_collect,1951 05:39:29 INFO - PROCESS | 6269 | __end_cc_report 05:39:29 INFO - PROCESS | 6269 | __startTimestamp1461328769815__endTimestamp 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | ------- Summary: start ------- 05:39:29 INFO - PROCESS | 6269 | Number of tests: 14 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:2.11 (1.9%) stddev-sans-first:2.00 05:39:29 INFO - PROCESS | 6269 | Values: 110.0 113.0 112.0 113.0 111.0 113.0 118.0 112.0 112.0 113.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:143.50 stddev:20.79 (14.5%) stddev-sans-first:20.76 05:39:29 INFO - PROCESS | 6269 | Values: 160.0 120.0 144.0 163.0 119.0 143.0 141.0 121.0 174.0 116.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#2] audio-dft Cycles:10 Average:195.50 Median:186.00 stddev:25.25 (13.6%) stddev-sans-first:26.37 05:39:29 INFO - PROCESS | 6269 | Values: 183.0 188.0 257.0 225.0 186.0 185.0 180.0 181.0 184.0 186.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#3] audio-fft Cycles:10 Average:71.90 Median:77.00 stddev:14.85 (19.3%) stddev-sans-first:15.75 05:39:29 INFO - PROCESS | 6269 | Values: 73.0 75.0 93.0 91.0 55.0 59.0 56.0 79.0 83.0 55.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:79.00 stddev:22.66 (28.7%) stddev-sans-first:23.94 05:39:29 INFO - PROCESS | 6269 | Values: 91.0 99.0 132.0 78.0 79.0 77.0 125.0 79.0 79.0 126.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:2.26 (2.6%) stddev-sans-first:2.28 05:39:29 INFO - PROCESS | 6269 | Values: 88.0 85.0 89.0 85.0 87.0 83.0 87.0 86.0 88.0 82.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 05:39:29 INFO - PROCESS | 6269 | Values: 94.0 93.0 93.0 94.0 93.0 93.0 94.0 94.0 93.0 94.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#7] imaging-desaturate Cycles:10 Average:87.70 Median:86.00 stddev:5.79 (6.7%) stddev-sans-first:6.14 05:39:29 INFO - PROCESS | 6269 | Values: 87.0 86.0 85.0 87.0 85.0 85.0 86.0 85.0 87.0 104.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:59.00 stddev:7.07 (12.0%) stddev-sans-first:7.32 05:39:29 INFO - PROCESS | 6269 | Values: 57.0 62.0 59.0 59.0 59.0 59.0 62.0 81.0 58.0 58.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.00 stddev:3.89 (5.5%) stddev-sans-first:3.80 05:39:29 INFO - PROCESS | 6269 | Values: 66.0 64.0 76.0 76.0 72.0 70.0 69.0 70.0 68.0 72.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:82.00 stddev:1.42 (1.7%) stddev-sans-first:1.24 05:39:29 INFO - PROCESS | 6269 | Values: 79.0 82.0 79.0 82.0 81.0 81.0 81.0 82.0 83.0 83.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#11] stanford-crypto-ccm Cycles:10 Average:158.20 Median:158.50 stddev:30.06 (19.0%) stddev-sans-first:31.74 05:39:29 INFO - PROCESS | 6269 | Values: 150.0 159.0 163.0 139.0 134.0 138.0 138.0 166.0 237.0 158.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:171.00 stddev:5.61 (3.3%) stddev-sans-first:3.84 05:39:29 INFO - PROCESS | 6269 | Values: 184.0 174.0 170.0 170.0 169.0 169.0 167.0 172.0 178.0 165.0 05:39:29 INFO - PROCESS | 6269 | 05:39:29 INFO - PROCESS | 6269 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:78.50 stddev:12.93 (16.5%) stddev-sans-first:13.10 05:39:29 INFO - PROCESS | 6269 | Values: 68.0 68.0 69.0 83.0 93.0 97.0 99.0 70.0 68.0 74.0 05:39:29 INFO - PROCESS | 6269 | -------- Summary: end -------- 05:39:29 INFO - PROCESS | 6269 | 05:39:30 INFO - TEST-INFO | 6269: exit 0 05:39:30 INFO - TEST-OK | kraken | took 58962ms 05:39:30 INFO - SUITE-END | took 1174s 05:39:30 INFO - Completed test suite (00:19:35) 05:39:30 INFO - javascript benchmark 05:39:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3356.496525521172, 3376.4428869236576, 3408.5398471206395, 3416.2655029168723, 3456.446968485043, 5589.692566908819, 5642.548596112311, 5673.602847800656, 5691.943080569198, 5696.601237913348, 5604.747786349614, 5678.063119585274, 5685.800996965107, 5695.401982791799, 5695.914561281574, 5114.156164232906, 5129.333186685725, 5144.614153938451, 5171.250168725533, 5218.243354713564, 4539.636829053684, 4943.9258411123865, 5051.5453609175165, 5201.219817027439, 5234.842954711346, 4134.235166494193, 5175.58595312376, 5194.662346947455, 5206.167013277876, 5240.816571420001, 5161.303224064741, 5201.427842937273, 5221.39953905302, 5234.319538459976, 5293.550048245904, 5069.163588007974, 5092.79628814847, 5152.9742351288005, 5153.175491921312, 5167.560757335594, 5569.832905012856, 5587.3015873015875, 5591.245181900432, 5600.187972743929, 5632.577556683265, 6207.7206525706, 6223.066540019034, 6229.813105606839, 6233.5948163369685, 6244.56288059836, 6039.335673075958, 6178.84552886177, 6200.596961197508, 6215.658138802364, 6277.183966084425, 4830.589399901048, 5842.5618078644275, 5858.912116318216, 5861.120831875168, 5885.352611212763, 6234.719437625317, 6299.212598425197, 6312.305646378894, 6314.0, 6320.525960552977, 6062.514998800086, 6089.878202435926, 6123.418275263843, 6174.320824709278, 6186.969065154735, 6216.191895053614, 6246.0, 6267.122602835607, 6271.153394291712, 6274.749010039547, 5246.396664383645, 6117.694115294217, 6209.348018458088, 6222.253329600387, 6231.127642130105, 6134.099846298167, 6139.96930015356, 6151.108089327112, 6188.133661287423, 6241.063840423973, 4884.584810291165, 4917.926231106536, 4928.433230178503, 4931.654784165145, 4932.457429682732, 4403.779811009436, 4410.095930334273, 4420.58004489573, 4475.888102797391, 4506.977465112654, 4838.250071238964, 5721.542276617861, 5751.59738818287, 5759.712014399263, 5764.106563482667, 6153.29237137726, 6195.132681424514, 6288.622682639057, 6292.528060395489, 6302.464290535264, 5324.787008519694, 5686.289213848269, 5701.4013528579735, 5726.2555867737765, 5727.914081288784, 4886.828960986349, 5367.570594352443, 5373.516383525502, 5378.462153784591, 5435.10320797064, 6204.045033376525, 6239.532035097477, 6262.874742505033, 6346.650934198571, 6401.839954001187, 6238.937610624017, 6272.655003974643, 6272.937270627418, 6274.901892168761, 6302.684865756785, 6275.403836635513, 6336.714847831767, 6354.809355719336, 6372.203474565679, 6379.457746091634, 6094.573379863367, 6129.358790882775, 6143.96928015357, 6217.533684973555, 6218.0, 6249.718762655601, 6254.0, 6262.2172228471445, 6301.7164227608955, 6307.936920630735, 5933.970330148321, 6026.548008899438, 6114.38856114385, 6128.142060111677, 6129.295131059806, 5973.313068996948, 6046.606970547076, 6109.266887973472, 6128.479079278133, 6129.0, 4406.603405693375, 5900.970495147497, 5929.970350148222, 5930.436608522184, 5932.940670593239, 5985.6408615483215, 6010.54920880927, 6067.817965461043, 6109.95128740435, 6121.483458747687, 4822.734749588806, 5848.181254624441, 5909.172715819875, 5980.491658209101, 6015.578909476295], "value": 5646.53284564509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.5154845154845, 490.50704042437343, 531.2907268796157, 574.967932561053, 610.7556977209118, 28868.566971495446, 30505.084847454586, 31202.84398578007, 34996.0, 35101.122471938215, 40119.59880401195, 40562.797186014104, 40746.79626601863, 40834.59165408345, 41010.38484422736, 9447.716568502956, 9658.130768230858, 9689.46707931067, 9689.806203875918, 9723.513824308791, 8502.319814414832, 8794.6921857735, 11349.829752553718, 11677.883221167764, 11683.0, 11408.20142590019, 11496.310221386702, 11496.540138394495, 11511.539538418454, 11512.13658975576, 8507.489550626982, 8772.03507614162, 11421.0, 11528.596499122532, 11538.826917596243, 8515.616797244076, 8733.301335893113, 11372.886271137266, 11655.475503602274, 11699.47352369137, 37734.81132594347, 38162.42756358657, 38174.618253817665, 38226.0443488915, 38372.80813595942, 3086.35186610812, 3170.731707317078, 3237.919052023695, 3737.570179429346, 4016.256992456405, 26639.334016649544, 27425.17724468269, 27532.58701119485, 27561.724382756318, 27709.58435623467, 2638.6965498967675, 2683.7047924728263, 2719.9936023671366, 2747.011076012634, 2779.027340430853, 458562.0, 463875.0, 466395.0, 466758.9999999966, 466819.0, 57246.14130788042, 58863.117053244234, 58867.11699324514, 58916.7054164735, 58968.0, 61125.38874611286, 61357.0, 61400.692996535625, 61405.0, 61497.38502615006, 16247.67504649924, 16648.500544983668, 16695.49913502597, 16757.581060473585, 16773.58066048334, 11717.707057323465, 12091.758164836654, 12851.16467429614, 13039.869601304055, 13093.934530327479, 15240.161791101596, 15301.540953771404, 15316.23418829054, 15369.538913832603, 15467.381304747683, 5290.497402746733, 5385.865353366197, 5412.864678382994, 5581.246531718247, 6275.152854364603, 942.4298299528823, 975.2588033094898, 1009.2329829329763, 1011.8532812742111, 1061.2836335473523, 24117.879410602836, 29084.12747617575, 29296.873047216654, 29703.851480742458, 29828.850855746015, 883.3595643158659, 1179.941002949849, 1208.7642909632536, 1241.1374095003885, 1256.5036810459817, 13517.66205844842, 14206.85793142076, 15087.849121508645, 15322.92739508246, 15381.230938453033, 520940.0, 621407.0, 624528.0, 626024.0, 627535.0, 58311.70844145752, 58808.705956469945, 58815.0, 58861.705691472125, 58872.41127588755, 1383.1355402873205, 1383.8823699985412, 1440.2222799688261, 1477.7857210704174, 1480.8074950256398, 27903.744331504724, 27949.720502794713, 28433.573496397985, 28558.714412856436, 28584.714152859036, 44208.55791442173, 44600.0, 45265.32102018538, 45332.09335813199, 45353.0, 344263.0, 347715.0, 347842.0, 348462.0, 349659.0, 32411.837940810146, 37392.25215495729, 37421.43867842039, 38498.422523662724, 38977.80511097427, 41411.7929410351, 42220.788896055325, 42252.78873605612, 42363.788181058895, 42437.78781106075, 33491.0, 34922.47616285809, 35411.0, 35726.821365893, 35762.64237357593, 29964.700352997064, 34454.65545344615, 36199.45700814542, 36246.09384765402, 37190.8140459296, 36631.45052824156, 36655.63344366629, 36797.44803827891, 36886.446703298934, 37691.05772355713, 29725.702742973157, 30405.239869002566, 30445.0, 30475.0, 30607.846960765055, 19612.509687257934, 23142.652860207447, 23303.76696233016, 23606.409839754142, 23840.880795595913], "value": 19111.546485957373, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.1336389896379, 393.2057244366379, 395.1819733152372, 398.3506883779441, 406.9979650101749, 13418.865811341908, 16847.83152168478, 19445.611087778238, 20438.591228175446, 20475.385738427853, 16301.266443010063, 16385.754213686774, 19716.605667886633, 20244.493887652818, 20674.379768606967, 5699.0, 5872.383399743029, 5903.527717782567, 5911.5270778337845, 5917.88164236715, 5640.830775076754, 5908.17285580017, 5938.910916336236, 5946.524278057745, 6009.5192384609345, 3879.243547508223, 4101.979490102545, 7243.89134162988, 7700.345470635003, 7709.267619576131, 7616.847663046763, 7680.69277228913, 7720.15078341382, 7732.845343093107, 7735.381169506426, 6743.0, 7162.498625096246, 7193.928060719378, 7310.52481588695, 7562.243775622449, 22926.426839328982, 27350.042748503907, 27873.58189627157, 28445.431091378057, 28572.0, 2253.515494168745, 2276.1919518571, 2388.8447250928634, 2407.626817843237, 3132.859021344045, 15061.698766024618, 15292.0, 15401.614959625987, 15606.318104568583, 16019.958702684287, 1736.0364997426423, 1789.0160411773472, 1843.0784607696153, 1871.6163186546726, 1890.5273681579606, 134806.0, 138874.30562847122, 139427.0, 139797.6020239805, 141029.0, 19957.301494447624, 20227.39317820468, 20445.2844150454, 20526.973651317374, 20921.476963076046, 35280.11799704977, 35472.290554188774, 35486.29027419437, 35495.64504354975, 35516.46725299123, 10248.53881575316, 10671.25301228922, 10705.571777128984, 10710.1443480347, 10821.29661572011, 7572.432067594952, 8426.747197584082, 8731.825363492695, 8767.342449316327, 8859.778505537284, 9196.264298856075, 9290.535473226311, 9379.343445958853, 9397.906020939703, 9442.197413219954, 2929.9414011719646, 3220.7423406127455, 3593.586737525166, 3597.5323207982797, 3715.684166845794, 705.3373514980988, 879.1911441473859, 914.7392992996964, 949.5679465843025, 1003.7039073473343, 16145.59636009109, 18353.0, 19598.8040119597, 19675.0, 20147.970405918873, 919.6827094652334, 946.758576562972, 965.899169306755, 974.5955428497236, 1013.5489707080278, 8232.794180145544, 10419.687409377731, 10579.735506612396, 10670.946645266724, 10758.300710453952, 573793.0, 578957.0, 580240.0, 589713.8228941674, 596226.0, 129297.0, 131499.0, 132075.33962330318, 133516.0, 133785.33107334402, 1003.6738060130486, 1023.2939271902363, 1030.2376241581176, 1042.213129087534, 1043.9373637581768, 14357.497487587885, 14518.782218266946, 18287.359942401952, 18390.54023649366, 18534.907325463286, 36945.81527092347, 45831.08337833158, 48223.75888120537, 50334.0, 51764.0, 74514.62742686251, 75151.24848751443, 77196.61401693181, 77327.61336193283, 79171.60414197893, 6727.428168605723, 7822.726204582812, 8010.679572817022, 8079.596020199084, 8155.79610509742, 11970.102242331686, 12155.513779448724, 12219.694507637025, 12329.753404931671, 12388.132830701765, 6026.789062382795, 6233.501319894488, 8214.794630134294, 9616.0, 11003.33979961205, 10593.470326483492, 10833.67498975032, 10886.94556527244, 11045.558177672803, 11101.222914396238, 10867.565297388015, 11157.330560166416, 11360.1479889007, 11526.366049867338, 11529.884701153216, 7920.84158316842, 8001.319887809368, 8175.959120204599, 8215.383846211438, 8216.383771217063, 9877.30858839903, 10934.398608076632, 11180.217384782987, 11266.267692599955, 11296.209265351676], "value": 10496.692505022875, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.319340329835, 1578.5816758558983, 1588.261458421834, 1830.1306879232366, 1969.2024729984353, 7812.023497062867, 8636.17956294152, 8860.601272942717, 8871.423357481759, 8877.0, 8456.112108228639, 8667.826643467128, 8699.826003479926, 8701.95649021754, 8714.782130446742, 2425.9029638814472, 2461.6430617560445, 2461.704595448549, 2464.2607217834666, 2465.6794616699804, 2398.2565404724505, 2457.2505385857307, 2459.139301244568, 2463.9753602464016, 2469.370310570811, 2392.928212153638, 2412.372783076404, 2420.5884999550117, 2420.697412823397, 2423.6485709572103, 2388.713354397466, 2412.4089598048413, 2420.031987205123, 2421.128393778238, 2425.466397392571, 2384.988075059631, 2404.8076153907646, 2411.3007227903886, 2412.9517409651885, 2413.6379543068506, 8587.0, 8702.042775294713, 8856.247218986395, 8857.114288571156, 8880.0, 5235.764390602394, 5453.100238460649, 5462.153366228241, 5484.7531861065945, 5493.587980901449, 3383.932321353559, 3540.840662170183, 3588.820558972067, 3596.8741094061584, 3607.765495242827, 994.820932232198, 1016.7966406718612, 1019.6125472320471, 1026.820306446369, 1028.3367228137809, 11354.886451135466, 11577.6526704199, 11580.247283926517, 11583.6524904253, 11586.768264634744, 4691.81232750693, 4828.076972494323, 4835.782389792468, 4920.114379411741, 4933.9013219736125, 10160.644377446753, 10185.796284074277, 10193.337433066976, 10199.031092046243, 10222.488875556193, 5460.645058071213, 5467.097928841776, 5468.097763868917, 5471.644343117685, 5513.5037846594, 4329.675274354436, 4499.6400287976885, 4662.836800711959, 4988.6757360771435, 4999.325091112653, 3583.1904888031377, 3937.92124157521, 4059.106996460774, 4082.3060079786505, 4140.171965606891, 1328.7708869295864, 1369.103237379518, 1371.1567386057502, 1384.5946364440101, 1385.0304786649185, 787.8700014497658, 864.831357885219, 892.9821403571892, 897.9191872731485, 909.6991302437577, 5347.946520534822, 5567.220589117527, 5608.691521966331, 6001.699915004232, 6007.219061521974, 852.6802449081595, 886.6453418632599, 958.8398038373646, 999.9750006249902, 1002.6139936124645, 3939.2515422069714, 4029.5768944261017, 4100.91798164035, 4121.855735049259, 4157.3140431829015, 9832.262580306506, 10226.846597301046, 10259.0, 10377.169826413869, 10396.064354208158, 10193.0, 10203.489825508843, 10328.122109620766, 10333.689989300334, 10372.844407333896, 7969.880451793228, 8207.753767386868, 8297.502149871027, 8301.46040507365, 8312.625931833203, 5657.264555607745, 5925.348211696623, 6023.728932198149, 6050.45545900871, 6063.878722425438, 9533.570989305359, 9709.417434953926, 9831.803363932824, 9941.055599718014, 9959.203263738738, 8733.737987860375, 9117.225035872025, 10231.079202871777, 10244.334118282288, 10287.485625718535, 6900.516963812485, 7119.928800711927, 7364.15312239078, 7640.0, 7656.693732250647, 8281.130481299377, 8284.917150828414, 8285.378596605158, 8369.916300836914, 8406.436037008527, 7432.516886402366, 7449.0, 7449.4412919032375, 7541.585212813348, 7566.697332106654, 7243.529170603894, 7267.0, 7299.781006569811, 7429.591372474566, 7493.925060749323, 6898.724051037902, 6926.307369263033, 6955.547889387173, 6959.338862808025, 6960.9303906962305, 4761.714297142044, 4927.236278376858, 5000.474950130184, 5033.546980771748, 5048.419431765394, 4937.0, 4990.525900039491, 5031.270465782514, 5070.087384270867, 5218.321618189611], "value": 4608.717737347626, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.98740050397987, 318.7274879977619, 325.47435891035974, 328.71073455359283, 333.0574474237907, 5193.2989046478715, 5565.109582466806, 5810.0, 5896.5282777377815, 5923.407659234074, 4982.402111746586, 5700.515456186229, 5825.1844741736195, 5857.765689372431, 5859.560532960024, 3556.137309465879, 4164.208800327928, 4249.6387807036435, 4363.192809330269, 4444.755538445384, 3227.1125440503934, 3286.6549012353717, 3809.866654667087, 4239.936400953989, 4370.978145109286, 4352.368906508555, 4364.890877728067, 4367.759773212472, 4374.825006999717, 4378.343248512732, 3368.34317308125, 4050.987253186703, 4145.502539695226, 4364.803583838719, 4399.5160532341415, 3794.1083845296334, 3804.714646401503, 3827.789471579062, 3841.2893614681093, 3853.537575490931, 4339.696221264513, 4586.908261834745, 4791.736454495001, 4880.41435027799, 5548.334199895998, 4371.388005679208, 4981.12830254704, 5076.162433198518, 5106.64253502255, 5109.897802043975, 6115.6942152892625, 6123.081537769326, 6201.937980620181, 6208.937910620881, 6208.937910620926, 4246.363045543162, 4254.553271906467, 4256.212600668886, 4262.126264115849, 4265.146970605861, 4891.951080489185, 6151.630902145886, 6252.624842509418, 6534.183227096565, 6582.275949645536, 4266.679999000087, 4279.593438623326, 4302.870913872589, 4309.913801723948, 4316.482022157298, 4382.742887412985, 4390.560943905584, 4392.648588112943, 4392.912141757147, 4396.736195828261, 4669.9259170391, 5004.149294619923, 5054.140796064605, 5089.491050894881, 5097.770600322994, 6231.345708700612, 6251.687415629201, 6256.530760192913, 6265.932242360011, 6276.560640755195, 1234.802431610938, 1445.7325394801994, 1459.37246983798, 1464.6777708904024, 1466.3767898643034, 816.6855760532119, 865.7532603208056, 904.3127223310331, 951.6764300137986, 960.2462067277154, 4562.771861406983, 5649.858753531194, 5715.514181294637, 5919.200907877467, 5928.496077833346, 5042.294078828967, 5335.013022590833, 6140.600860944024, 6508.0, 6510.874780105576, 5604.6356986796545, 5719.428057194247, 5733.999524149411, 5845.678487683133, 5876.6180198286975, 3143.497040473559, 3356.0702596007395, 3360.823711700934, 3362.9831850840587, 3393.355262500117, 6453.161089058486, 6600.910849709744, 6704.0, 6705.698243578953, 6707.496937729592, 6517.380848819449, 6584.9012264816065, 6588.209414870151, 6589.7364105435245, 6612.603243805387, 5586.441355864462, 5778.832937648382, 6624.635645039472, 6683.532152749261, 6690.297518760557, 6205.968970155121, 6382.872392527721, 6435.485161187187, 6437.839054023687, 6442.613443193423, 5253.684778913277, 6285.340039295808, 6317.4630156437215, 6345.524085693499, 6355.078513615592, 6382.234131904202, 6655.23464801554, 6700.396964273239, 6703.4972377070935, 6706.138049913969, 3681.687056600219, 4017.794661601449, 4143.212789569972, 4169.187008533307, 4177.874663760092, 5634.577406694596, 5778.913316300343, 6378.90431643535, 6418.390252925965, 6429.453496452854, 5952.404759524013, 6160.630362178284, 6184.474319682878, 6206.813795586139, 6252.374762523711, 6074.453299203093, 6138.662373569497, 6143.262808463013, 6149.938500614937, 6173.84565385851, 4989.80040798364, 5252.261087501356, 5323.866903327293, 5838.620489668158, 6044.637321760709, 4982.626303027215, 6017.849553761191, 6135.325114237519, 6193.071039344134, 6208.72060757258, 5713.228714123707, 6034.129419879851, 6081.0, 6081.391860813883, 6083.6349819011], "value": 4469.177124182265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.3993845938668, 725.3145777240508, 735.2830989784964, 760.2891296637645, 786.1156199275815, 14833.480828171016, 14936.701265974687, 15406.460773872917, 15416.922915385421, 15418.768718469231, 13872.583822485316, 13998.0, 14015.859841401609, 14016.22910739909, 14017.229052402114, 708.6917191021896, 731.5683746589511, 885.6590212768099, 891.2068259249257, 920.7237828651378, 765.9157492675802, 913.3150137396952, 918.0406475233397, 920.9171174594285, 922.0594993107027, 974.1232890398627, 978.6770365779312, 980.568549838069, 981.5926390547914, 984.2077127912028, 684.2336583026992, 709.5135690727917, 924.5931790012373, 957.0142752964458, 971.4899677669209, 718.6263143165525, 719.3238355945394, 725.0465637686478, 725.2240103089696, 725.3471875312208, 14905.701885962328, 14937.775933361008, 14943.701125977419, 14957.626059348495, 15007.024543404645, 11632.941835290854, 11664.0, 12406.19359741623, 12498.125131240817, 12632.74734505314, 9919.40483570988, 10091.89908100917, 10141.13800326973, 10157.035081667229, 10720.249582529226, 3460.325236578868, 3485.302939412102, 3495.4582039783872, 3731.2910546996254, 3764.5294338207723, 82982.9999999994, 84235.5788221055, 84690.0, 84710.57644711799, 84821.0, 9433.716988490356, 9487.0, 9857.0, 9862.901370986341, 9882.209423246122, 22538.887305563694, 22588.774112258994, 22597.887010564842, 22600.66099008516, 22606.886965565067, 7438.256174382518, 7545.019147510789, 7553.0, 7595.354394876497, 7609.0, 3506.7194624429985, 3926.960730392717, 4007.479027726377, 4042.6159514846045, 4144.544100149042, 3564.1624218308675, 3635.345637785171, 3639.2721455708433, 3643.5809881863397, 3646.234290798962, 528.7250629672626, 618.9845253868599, 646.1825790375179, 650.2294780684904, 653.5033374635311, 497.4577710295795, 560.9551235901118, 566.3571845954871, 584.7777844419094, 598.9850253743604, 7849.097353804272, 7895.526268423913, 7970.760877173694, 8138.3692339918025, 8179.22582513902, 553.5615613223675, 571.3657839797821, 575.4705670782889, 598.8233471126028, 601.3715667127861, 3773.2264885698914, 3875.418687196943, 4019.8995025124023, 4068.3897415387337, 4143.482064741907, 92074.0, 92809.0719092814, 93103.0, 94426.0, 94557.5272123635, 69583.0, 74442.0, 78639.60680196677, 83239.0, 84868.57565712131, 62726.37273627205, 67053.0, 67768.3223167772, 73418.8987165193, 74569.62715186497, 6924.446044316342, 7830.491018083806, 7954.5624990626075, 8064.112947575879, 8209.83580328402, 41504.79247603743, 42949.78525107355, 43384.915377114565, 44611.77694111509, 44773.776131119135, 57429.42570574241, 58027.549311267554, 59409.40590594212, 61796.69101654463, 62682.0, 10875.129989600971, 11025.117990560897, 11104.389258590532, 11111.0, 11145.164112691742, 11680.766384672428, 11802.409879506162, 11868.10989175798, 11994.220375675553, 12750.68123296925, 9753.0, 10852.728681783019, 11471.311721296748, 12122.151449398458, 12189.207701499374, 11126.332420054823, 11212.887871121184, 11237.04485118774, 11580.247283926517, 11690.357030363071, 11066.28069175501, 11136.721581960515, 11179.66461006171, 11343.546258149581, 11547.0, 9237.676681316121, 9346.392484488486, 9506.667266645634, 9657.517124143906, 9851.556679949563, 7844.607769611611, 8034.397420193392, 8546.359023073419, 8812.643147074295, 9548.140667339972], "value": 6800.188909552885, "unit": "score"}], "name": "dromaeo_css", "value": 7357.836917749769, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 112.0, 113.0, 111.0, 113.0, 118.0, 112.0, 112.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 120.0, 144.0, 163.0, 119.0, 143.0, 141.0, 121.0, 174.0, 116.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 188.0, 257.0, 225.0, 186.0, 185.0, 180.0, 181.0, 184.0, 186.0], "value": 195.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 93.0, 91.0, 55.0, 59.0, 56.0, 79.0, 83.0, 55.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 132.0, 78.0, 79.0, 77.0, 125.0, 79.0, 79.0, 126.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 87.0, 83.0, 87.0, 86.0, 88.0, 82.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 87.0, 85.0, 85.0, 86.0, 85.0, 87.0, 104.0], "value": 87.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 59.0, 59.0, 59.0, 59.0, 62.0, 81.0, 58.0, 58.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 76.0, 76.0, 72.0, 70.0, 69.0, 70.0, 68.0, 72.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 79.0, 82.0, 81.0, 81.0, 81.0, 82.0, 83.0, 83.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 159.0, 163.0, 139.0, 134.0, 138.0, 138.0, 166.0, 237.0, 158.0], "value": 158.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 174.0, 170.0, 170.0, 169.0, 169.0, 167.0, 172.0, 178.0, 165.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 83.0, 93.0, 97.0, 99.0, 70.0, 68.0, 74.0], "value": 78.9, "unit": "score"}], "name": "kraken", "value": 1505.8000000000002, "alertThreshold": 2.0}]} 05:39:31 INFO - Return code: 0 05:39:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:39:31 ERROR - # TBPL SUCCESS # 05:39:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:31 INFO - 2016-04-22 05:39:31.093 screenresolution[6273:103638] starting screenresolution argv=screenresolution get 05:39:31 INFO - 2016-04-22 05:39:31.096 screenresolution[6273:103638] Display 0: 1600x1200x32@60 05:39:31 INFO - 2016-04-22 05:39:31.102 screenresolution[6274:103641] starting screenresolution argv=screenresolution list 05:39:31 INFO - Available Modes on Display 0 05:39:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:31 INFO - Intel Iris: 05:39:31 INFO - Chipset Model: Intel Iris 05:39:31 INFO - Type: GPU 05:39:31 INFO - Bus: Built-In 05:39:31 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:31 INFO - Vendor: Intel (0x8086) 05:39:31 INFO - Device ID: 0x0a2e 05:39:31 INFO - Revision ID: 0x0009 05:39:31 INFO - Displays: 05:39:31 INFO - Display: 05:39:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:31 INFO - Display Serial Number: 1600x1200 60 05:39:31 INFO - Main Display: Yes 05:39:31 INFO - Mirror: Off 05:39:31 INFO - Online: Yes 05:39:31 INFO - Rotation: Supported 05:39:31 INFO - Return code: 0 05:39:31 INFO - Running post-action listener: _resource_record_post_action 05:39:31 INFO - Running post-run listener: _resource_record_post_run 05:39:32 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 1588736; Write bytes: 694084096; Read time: 24; Write time: 2026 05:39:32 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394490368; Write bytes: 389408768; Read time: 29777; Write time: 749 05:39:32 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 1555968; Write bytes: 295381504; Read time: 22; Write time: 1247 05:39:32 INFO - Running post-run listener: _upload_blobber_files 05:39:32 INFO - Blob upload gear active. 05:39:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:32 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 05:39:32 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:39:32 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:39:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:39:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:39:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:33 INFO - (blobuploader) - INFO - Done attempting. 05:39:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:39:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:39:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:34 INFO - (blobuploader) - INFO - Done attempting. 05:39:34 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:34 INFO - Return code: 0 05:39:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f768766d413c80dd2e72aeb5d5d93dc0ec1d4a97c32b8bcf19afdab5e0056ee7fb4cdfe8ff24eff0115b6ec37959630220c6dc1c068d4d8a3f6cf884325c2fdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8aaa80f037b36678d5b81305fb7e09b2e97ad05aad123056c58287166250a7ed9946fd59373fa63a9b02982e70d62beed9be726aad9ba4c7b5bdbd4a8841fab"} 05:39:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:34 INFO - Contents: 05:39:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f768766d413c80dd2e72aeb5d5d93dc0ec1d4a97c32b8bcf19afdab5e0056ee7fb4cdfe8ff24eff0115b6ec37959630220c6dc1c068d4d8a3f6cf884325c2fdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8aaa80f037b36678d5b81305fb7e09b2e97ad05aad123056c58287166250a7ed9946fd59373fa63a9b02982e70d62beed9be726aad9ba4c7b5bdbd4a8841fab"} 05:39:34 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:34 INFO - Copying logs to upload dir... 05:39:34 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.692675 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-22 05:39:34.598001) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 05:39:34.601123) ========= 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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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/Fx-Team/sha512/f768766d413c80dd2e72aeb5d5d93dc0ec1d4a97c32b8bcf19afdab5e0056ee7fb4cdfe8ff24eff0115b6ec37959630220c6dc1c068d4d8a3f6cf884325c2fdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8aaa80f037b36678d5b81305fb7e09b2e97ad05aad123056c58287166250a7ed9946fd59373fa63a9b02982e70d62beed9be726aad9ba4c7b5bdbd4a8841fab"} build_url:https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/WMUEHUnBQFyxVl6_GhRe6A/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f768766d413c80dd2e72aeb5d5d93dc0ec1d4a97c32b8bcf19afdab5e0056ee7fb4cdfe8ff24eff0115b6ec37959630220c6dc1c068d4d8a3f6cf884325c2fdc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8aaa80f037b36678d5b81305fb7e09b2e97ad05aad123056c58287166250a7ed9946fd59373fa63a9b02982e70d62beed9be726aad9ba4c7b5bdbd4a8841fab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 05:39:34.636150) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:39:34.636472) ========= 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.mn7pFSMJ7e/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.jJncAsY8A8/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.004986 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 05:39:34.685437) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 05:39:34.688787) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 05:39:34.689213) ========= ========= Total master_lag: 5.33 =========