builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1462467029.42 results: success (0) buildid: 20160505075213 builduid: 199cbbdfb872484e8c191b259dfcbcf7 revision: e9149132e07a04f5bcaf5f5519d74d1079febe14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.420275) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.420702) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.420994) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.440503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.440789) ========= 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.g0uqDJJObY/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.tI5DF0rmcf/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-05 09:50:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.0M=0.001s 2016-05-05 09:50:29 (23.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.598682) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.599451) ========= 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.g0uqDJJObY/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.tI5DF0rmcf/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.019883 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:29.649441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 09:50:29.649787) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e9149132e07a04f5bcaf5f5519d74d1079febe14 --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 e9149132e07a04f5bcaf5f5519d74d1079febe14 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g0uqDJJObY/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.tI5DF0rmcf/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-05 09:50:29,704 truncating revision to first 12 chars 2016-05-05 09:50:29,704 Setting DEBUG logging. 2016-05-05 09:50:29,704 attempt 1/10 2016-05-05 09:50:29,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e9149132e07a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 09:50:31,008 unpacking tar archive at: fx-team-e9149132e07a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.554086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 09:50:31.224678) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:31.225491) ========= script_repo_revision: e9149132e07a04f5bcaf5f5519d74d1079febe14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:31.226007) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:31.226272) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 09:50:31.239997) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-05 09:50:31.240557) ========= /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.g0uqDJJObY/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.tI5DF0rmcf/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:50:31 INFO - MultiFileLogger online at 20160505 09:50:31 in /builds/slave/test 09:50:31 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 09:50:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:50:31 INFO - {'append_to_log': False, 09:50:31 INFO - 'base_work_dir': '/builds/slave/test', 09:50:31 INFO - 'blob_upload_branch': 'Fx-Team', 09:50:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:50:31 INFO - 'branch': 'Fx-Team', 09:50:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:50:31 INFO - 'config_files': ('talos/mac_config.py',), 09:50:31 INFO - 'default_actions': ('clobber', 09:50:31 INFO - 'read-buildbot-config', 09:50:31 INFO - 'download-and-extract', 09:50:31 INFO - 'populate-webroot', 09:50:31 INFO - 'create-virtualenv', 09:50:31 INFO - 'install', 09:50:31 INFO - 'run-tests'), 09:50:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:50:31 INFO - 'download_minidump_stackwalk': True, 09:50:31 INFO - 'download_symbols': 'ondemand', 09:50:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:50:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:50:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:50:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:50:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:50:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:50:31 INFO - 'installer_path': 'installer.exe', 09:50:31 INFO - 'log_level': 'info', 09:50:31 INFO - 'log_name': 'talos', 09:50:31 INFO - 'log_to_console': True, 09:50:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:50:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:50:31 INFO - 'opt_config_files': (), 09:50:31 INFO - 'pip_index': False, 09:50:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:50:31 INFO - 'cmd': ('bash', 09:50:31 INFO - '-c', 09:50:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:50:31 INFO - 'enabled': True, 09:50:31 INFO - 'halt_on_failure': False, 09:50:31 INFO - 'name': 'check_screen_resolution'},), 09:50:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:50:31 INFO - 'cmd': ('bash', 09:50:31 INFO - '-c', 09:50:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:50:31 INFO - 'enabled': True, 09:50:31 INFO - 'halt_on_failure': False, 09:50:31 INFO - 'name': 'check_screen_resolution'},), 09:50:31 INFO - 'run_cmd_checks_enabled': True, 09:50:31 INFO - 'sps_profile': False, 09:50:31 INFO - 'sps_profile_interval': 0, 09:50:31 INFO - 'suite': 'dromaeojs', 09:50:31 INFO - 'system_bits': '32', 09:50:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:50:31 INFO - 'title': 't-yosemite-r7-0154', 09:50:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:50:31 INFO - 'use_talos_json': True, 09:50:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:50:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:50:31 INFO - 'work_dir': 'build'} 09:50:31 INFO - ##### 09:50:31 INFO - ##### Running clobber step. 09:50:31 INFO - ##### 09:50:31 INFO - Running pre-action listener: _resource_record_pre_action 09:50:31 INFO - Running main action method: clobber 09:50:31 INFO - rmtree: /builds/slave/test/build 09:50:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:50:32 INFO - Running post-action listener: _resource_record_post_action 09:50:32 INFO - ##### 09:50:32 INFO - ##### Running read-buildbot-config step. 09:50:32 INFO - ##### 09:50:32 INFO - Running pre-action listener: _resource_record_pre_action 09:50:32 INFO - Running main action method: read_buildbot_config 09:50:32 INFO - Using buildbot properties: 09:50:32 INFO - { 09:50:32 INFO - "project": "", 09:50:32 INFO - "product": "firefox", 09:50:32 INFO - "script_repo_revision": "production", 09:50:32 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:50:32 INFO - "repository": "", 09:50:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:50:32 INFO - "buildid": "20160505075213", 09:50:32 INFO - "pgo_build": "False", 09:50:32 INFO - "basedir": "/builds/slave/test", 09:50:32 INFO - "buildnumber": 694, 09:50:32 INFO - "slavename": "t-yosemite-r7-0154", 09:50:32 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:50:32 INFO - "platform": "yosemite_r7", 09:50:32 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:50:32 INFO - "branch": "fx-team", 09:50:32 INFO - "repo_path": "integration/fx-team", 09:50:32 INFO - "revision": "e9149132e07a04f5bcaf5f5519d74d1079febe14", 09:50:32 INFO - "stage_platform": "macosx64", 09:50:32 INFO - "builduid": "199cbbdfb872484e8c191b259dfcbcf7", 09:50:32 INFO - "slavebuilddir": "test" 09:50:32 INFO - } 09:50:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:50:32 INFO - Running post-action listener: _resource_record_post_action 09:50:32 INFO - ##### 09:50:32 INFO - ##### Running download-and-extract step. 09:50:32 INFO - ##### 09:50:32 INFO - Running pre-action listener: _resource_record_pre_action 09:50:32 INFO - Running main action method: download_and_extract 09:50:32 INFO - mkdir: /builds/slave/test/build/tests 09:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:32 INFO - https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:50:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:50:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:50:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:50:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:50:35 INFO - Downloaded 1199 bytes. 09:50:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:50:35 INFO - Using the following test package requirements: 09:50:35 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:50:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:50:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:50:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'jsshell-mac.zip'], 09:50:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:50:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:50:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:50:35 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:50:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:50:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:50:35 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:50:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:50:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:35 INFO - https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:50:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:50:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:50:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:50:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:50:36 INFO - Downloaded 18131072 bytes. 09:50:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:50:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:50:39 INFO - Return code: 0 09:50:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:50: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')]} 09:50:39 INFO - https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:50:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:50:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:50:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:50:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:50:41 INFO - Downloaded 18131072 bytes. 09:50:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:50:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:50:44 INFO - Return code: 0 09:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:44 INFO - https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:50:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:50:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:50:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:50:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:50:46 INFO - Downloaded 10840152 bytes. 09:50:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:50:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:50:46 INFO - Return code: 0 09:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:46 INFO - https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:50:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:50:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:50:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:50:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:50:49 INFO - Downloaded 110489944 bytes. 09:50:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:50:49 INFO - mkdir: /builds/slave/test/properties 09:50:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:50:49 INFO - Writing to file /builds/slave/test/properties/build_url 09:50:49 INFO - Contents: 09:50:49 INFO - build_url:https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:50:51 INFO - Running post-action listener: _resource_record_post_action 09:50:51 INFO - Running post-action listener: set_extra_try_arguments 09:50:51 INFO - ##### 09:50:51 INFO - ##### Running populate-webroot step. 09:50:51 INFO - ##### 09:50:51 INFO - Running pre-action listener: _resource_record_pre_action 09:50:51 INFO - Running main action method: populate_webroot 09:50:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:50:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:50:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:50:51 INFO - '--tppagecycles', 09:50:51 INFO - '7'], 09:50:51 INFO - 'tests': ['tsvgm']}}, 09:50:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:50:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:50:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:50:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:50:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:50:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:50:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['damp', 'tps']}, 09:50:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'tests': ['damp', 'tps']}, 09:50:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['dromaeo_dom']}, 09:50:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:50:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['a11yr', 09:50:51 INFO - 'ts_paint', 09:50:51 INFO - 'tpaint', 09:50:51 INFO - 'sessionrestore', 09:50:51 INFO - 'sessionrestore_no_auto_restore', 09:50:51 INFO - 'tabpaint']}, 09:50:51 INFO - 'other-e10s': {'tests': ['a11yr', 09:50:51 INFO - 'ts_paint', 09:50:51 INFO - 'tpaint', 09:50:51 INFO - 'sessionrestore', 09:50:51 INFO - 'sessionrestore_no_auto_restore', 09:50:51 INFO - 'tabpaint']}, 09:50:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:50:51 INFO - 'ts_paint', 09:50:51 INFO - 'tpaint', 09:50:51 INFO - 'sessionrestore', 09:50:51 INFO - 'sessionrestore_no_auto_restore', 09:50:51 INFO - 'tabpaint']}, 09:50:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:50:51 INFO - 'ts_paint', 09:50:51 INFO - 'tpaint', 09:50:51 INFO - 'sessionrestore', 09:50:51 INFO - 'sessionrestore_no_auto_restore', 09:50:51 INFO - 'tabpaint']}, 09:50:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['a11yr', 09:50:51 INFO - 'ts_paint', 09:50:51 INFO - 'tpaint', 09:50:51 INFO - 'sessionrestore', 09:50:51 INFO - 'sessionrestore_no_auto_restore', 09:50:51 INFO - 'tabpaint']}, 09:50:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['a11yr', 09:50:51 INFO - 'ts_paint', 09:50:51 INFO - 'tpaint', 09:50:51 INFO - 'sessionrestore', 09:50:51 INFO - 'sessionrestore_no_auto_restore', 09:50:51 INFO - 'tabpaint']}, 09:50:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['tsvgx', 09:50:51 INFO - 'tsvgr_opacity', 09:50:51 INFO - 'tart', 09:50:51 INFO - 'tscrollx', 09:50:51 INFO - 'cart']}, 09:50:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:50:51 INFO - 'tsvgr_opacity', 09:50:51 INFO - 'tart', 09:50:51 INFO - 'tscrollx', 09:50:51 INFO - 'cart']}, 09:50:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'talos_options': ['--disable-e10s'], 09:50:51 INFO - 'tests': ['tp5o']}, 09:50:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'tests': ['tp5o']}, 09:50:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'talos_options': ['--disable-e10s', 09:50:51 INFO - '--xperf_path', 09:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:50:51 INFO - 'tests': ['tp5n']}, 09:50:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:50:51 INFO - 'talos_options': ['--xperf_path', 09:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:50:51 INFO - 'tests': ['tp5n']}}, 09:50:51 INFO - 'talos.zip': {'path': '', 09:50:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:50:51 INFO - Running post-action listener: _resource_record_post_action 09:50:51 INFO - ##### 09:50:51 INFO - ##### Running create-virtualenv step. 09:50:51 INFO - ##### 09:50:51 INFO - Running pre-action listener: _resource_record_pre_action 09:50:51 INFO - Running main action method: create_virtualenv 09:50:51 INFO - Creating virtualenv /builds/slave/test/build/venv 09:50:51 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 09:50:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:50:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:50:51 INFO - Using real prefix '/tools/python27' 09:50:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:50:52 INFO - Installing distribute.............................................................................................................................................................................................done. 09:50:55 INFO - Installing pip.................done. 09:50:55 INFO - Return code: 0 09:50:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:50:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:50:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:50:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:50:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:50:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:50:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:50:55 INFO - 'HOME': '/Users/cltbld', 09:50:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:50:55 INFO - 'LOGNAME': 'cltbld', 09:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:55 INFO - 'MOZ_NO_REMOTE': '1', 09:50:55 INFO - 'NO_EM_RESTART': '1', 09:50:55 INFO - 'PAGER': '/bin/cat', 09:50:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:55 INFO - 'PWD': '/builds/slave/test', 09:50:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:50:55 INFO - 'SHELL': '/bin/bash', 09:50:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:50:55 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:50:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:50:55 INFO - 'USER': 'cltbld', 09:50:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:50:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:55 INFO - 'XPC_FLAGS': '0x0', 09:50:55 INFO - 'XPC_SERVICE_NAME': '0', 09:50:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:50:55 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 09:50:55 INFO - Cleaning up... 09:50:55 INFO - Return code: 0 09:50:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:50:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:50:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:50:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:50:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:50:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:50:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:50:55 INFO - 'HOME': '/Users/cltbld', 09:50:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:50:55 INFO - 'LOGNAME': 'cltbld', 09:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:55 INFO - 'MOZ_NO_REMOTE': '1', 09:50:55 INFO - 'NO_EM_RESTART': '1', 09:50:55 INFO - 'PAGER': '/bin/cat', 09:50:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:55 INFO - 'PWD': '/builds/slave/test', 09:50:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:50:55 INFO - 'SHELL': '/bin/bash', 09:50:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:50:55 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:50:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:50:55 INFO - 'USER': 'cltbld', 09:50:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:50:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:55 INFO - 'XPC_FLAGS': '0x0', 09:50:55 INFO - 'XPC_SERVICE_NAME': '0', 09:50:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:50:55 INFO - Downloading/unpacking psutil>=0.7.1 09:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:50:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:50:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:50:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:50:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:50:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:50:58 INFO - Installing collected packages: psutil 09:50:58 INFO - Running setup.py install for psutil 09:50:58 INFO - building 'psutil._psutil_osx' extension 09:50:58 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 09:50:58 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 09:50:58 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 09:50:58 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 09:50:58 INFO - building 'psutil._psutil_posix' extension 09:50:58 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 09:50:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:50:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:50:59 INFO - ^ 09:50:59 INFO - 1 warning generated. 09:50:59 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 09:50:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:50:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:50:59 INFO - Successfully installed psutil 09:50:59 INFO - Cleaning up... 09:50:59 INFO - Return code: 0 09:50:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:50:59 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')]} 09:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:59 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')]} 09:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:50:59 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 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:50:59 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 09:50:59 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 09:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:50:59 INFO - 'HOME': '/Users/cltbld', 09:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:50:59 INFO - 'LOGNAME': 'cltbld', 09:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:59 INFO - 'MOZ_NO_REMOTE': '1', 09:50:59 INFO - 'NO_EM_RESTART': '1', 09:50:59 INFO - 'PAGER': '/bin/cat', 09:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:59 INFO - 'PWD': '/builds/slave/test', 09:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:50:59 INFO - 'SHELL': '/bin/bash', 09:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:50:59 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:50:59 INFO - 'USER': 'cltbld', 09:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:59 INFO - 'XPC_FLAGS': '0x0', 09:50:59 INFO - 'XPC_SERVICE_NAME': '0', 09:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:50:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:50: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 09:50: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 09:50: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 09:50: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 09:50: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 09:50: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 09:51:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:51:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:51:02 INFO - Installing collected packages: mozsystemmonitor 09:51:02 INFO - Running setup.py install for mozsystemmonitor 09:51:02 INFO - Successfully installed mozsystemmonitor 09:51:02 INFO - Cleaning up... 09:51:02 INFO - Return code: 0 09:51:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:02 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 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:51:02 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 09:51:02 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 09:51:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:51:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:02 INFO - 'HOME': '/Users/cltbld', 09:51:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:02 INFO - 'LOGNAME': 'cltbld', 09:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:02 INFO - 'MOZ_NO_REMOTE': '1', 09:51:02 INFO - 'NO_EM_RESTART': '1', 09:51:02 INFO - 'PAGER': '/bin/cat', 09:51:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:02 INFO - 'PWD': '/builds/slave/test', 09:51:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:02 INFO - 'SHELL': '/bin/bash', 09:51:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:51:02 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:51:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:02 INFO - 'USER': 'cltbld', 09:51:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:02 INFO - 'XPC_FLAGS': '0x0', 09:51:02 INFO - 'XPC_SERVICE_NAME': '0', 09:51:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:51:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:51:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:06 INFO - Downloading docopt-0.6.1.tar.gz 09:51:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:51:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:51:06 INFO - Running setup.py install for blobuploader 09:51:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:51:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:51:06 INFO - Running setup.py install for requests 09:51:06 INFO - Running setup.py install for docopt 09:51:07 INFO - Successfully installed blobuploader requests docopt 09:51:07 INFO - Cleaning up... 09:51:07 INFO - Return code: 0 09:51:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:07 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 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:51:07 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 09:51:07 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 09:51:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:51:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:07 INFO - 'HOME': '/Users/cltbld', 09:51:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:07 INFO - 'LOGNAME': 'cltbld', 09:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:07 INFO - 'MOZ_NO_REMOTE': '1', 09:51:07 INFO - 'NO_EM_RESTART': '1', 09:51:07 INFO - 'PAGER': '/bin/cat', 09:51:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:07 INFO - 'PWD': '/builds/slave/test', 09:51:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:07 INFO - 'SHELL': '/bin/bash', 09:51:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:51:07 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:51:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:07 INFO - 'USER': 'cltbld', 09:51:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:07 INFO - 'XPC_FLAGS': '0x0', 09:51:07 INFO - 'XPC_SERVICE_NAME': '0', 09:51:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:07 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-tNRlF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:07 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-wkG7wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:07 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-xsCuoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:07 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5OPEge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:07 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-9W5CrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:07 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hteNIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:07 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ClQUEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Vdy2A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-XUVtn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QQZKsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-OS_WCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-sRfPtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-NLRt_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HMdgNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-kEVdht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:08 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-rmcmfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:09 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-R56lTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:51:09 INFO - Running setup.py install for manifestparser 09:51:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:51:09 INFO - Running setup.py install for mozcrash 09:51:09 INFO - Running setup.py install for mozdebug 09:51:09 INFO - Running setup.py install for mozdevice 09:51:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:51:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:51:09 INFO - Running setup.py install for mozfile 09:51:09 INFO - Running setup.py install for mozhttpd 09:51:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Running setup.py install for mozinfo 09:51:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Running setup.py install for mozInstall 09:51:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Running setup.py install for mozleak 09:51:10 INFO - Running setup.py install for mozlog 09:51:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Running setup.py install for moznetwork 09:51:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:51:10 INFO - Running setup.py install for mozprocess 09:51:10 INFO - Running setup.py install for mozprofile 09:51:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:51:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:51:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:51:11 INFO - Running setup.py install for mozrunner 09:51:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:51:11 INFO - Running setup.py install for mozscreenshot 09:51:11 INFO - Running setup.py install for moztest 09:51:11 INFO - Running setup.py install for mozversion 09:51:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:51:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:51:11 INFO - Cleaning up... 09:51:11 INFO - Return code: 0 09:51:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51: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')]} 09:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51: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')]} 09:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:51:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:51:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:51:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:51:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:11 INFO - 'HOME': '/Users/cltbld', 09:51:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:11 INFO - 'LOGNAME': 'cltbld', 09:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:11 INFO - 'MOZ_NO_REMOTE': '1', 09:51:11 INFO - 'NO_EM_RESTART': '1', 09:51:11 INFO - 'PAGER': '/bin/cat', 09:51:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:11 INFO - 'PWD': '/builds/slave/test', 09:51:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:11 INFO - 'SHELL': '/bin/bash', 09:51:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:51:11 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:51:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:11 INFO - 'USER': 'cltbld', 09:51:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:11 INFO - 'XPC_FLAGS': '0x0', 09:51:11 INFO - 'XPC_SERVICE_NAME': '0', 09:51:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-9OsIw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:51:12 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)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Xqbsff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-qivLvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yYeP2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ZKMhRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-79Q7AK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-qwrGyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-GHHRYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Uk__YD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:51:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:51:12 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-1V9xa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:51:13 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)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:51:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HPW0NH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:51:13 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)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:51:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-SHlpOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:51:13 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)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:51:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-mOnJN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:51:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-I5hv5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:51:13 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)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-w71RHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:51:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-mLw8i0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:51:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-8BW2nF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:51:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:16 INFO - Downloading blessings-1.6.tar.gz 09:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:51:16 INFO - Installing collected packages: blessings 09:51:16 INFO - Running setup.py install for blessings 09:51:16 INFO - Successfully installed blessings 09:51:16 INFO - Cleaning up... 09:51:16 INFO - Return code: 0 09:51:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:16 INFO - Reading from file tmpfile_stdout 09:51:16 INFO - Current package versions: 09:51:16 INFO - blessings == 1.6 09:51:16 INFO - blobuploader == 1.2.4 09:51:16 INFO - docopt == 0.6.1 09:51:16 INFO - manifestparser == 1.1 09:51:16 INFO - mozInstall == 1.12 09:51:16 INFO - mozcrash == 0.17 09:51:16 INFO - mozdebug == 0.1 09:51:16 INFO - mozdevice == 0.48 09:51:16 INFO - mozfile == 1.2 09:51:16 INFO - mozhttpd == 0.7 09:51:16 INFO - mozinfo == 0.9 09:51:16 INFO - mozleak == 0.1 09:51:16 INFO - mozlog == 3.1 09:51:16 INFO - moznetwork == 0.27 09:51:16 INFO - mozprocess == 0.22 09:51:16 INFO - mozprofile == 0.28 09:51:16 INFO - mozrunner == 6.11 09:51:16 INFO - mozscreenshot == 0.1 09:51:16 INFO - mozsystemmonitor == 0.0 09:51:16 INFO - moztest == 0.7 09:51:16 INFO - mozversion == 1.4 09:51:16 INFO - psutil == 3.1.1 09:51:16 INFO - requests == 1.2.3 09:51:16 INFO - wsgiref == 0.1.2 09:51:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:51:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:51:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:51:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:16 INFO - 'HOME': '/Users/cltbld', 09:51:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:16 INFO - 'LOGNAME': 'cltbld', 09:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:16 INFO - 'MOZ_NO_REMOTE': '1', 09:51:16 INFO - 'NO_EM_RESTART': '1', 09:51:16 INFO - 'PAGER': '/bin/cat', 09:51:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:16 INFO - 'PWD': '/builds/slave/test', 09:51:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:16 INFO - 'SHELL': '/bin/bash', 09:51:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:51:16 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:51:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:16 INFO - 'USER': 'cltbld', 09:51:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:16 INFO - 'XPC_FLAGS': '0x0', 09:51:16 INFO - 'XPC_SERVICE_NAME': '0', 09:51:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 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)) 09:51:17 INFO - Cleaning up... 09:51:17 INFO - Return code: 0 09:51:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:51:17 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')]} 09:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 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')]} 09:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:17 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 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:51:17 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 09:51:17 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 09:51:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:51:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:17 INFO - 'HOME': '/Users/cltbld', 09:51:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:17 INFO - 'LOGNAME': 'cltbld', 09:51:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:17 INFO - 'MOZ_NO_REMOTE': '1', 09:51:17 INFO - 'NO_EM_RESTART': '1', 09:51:17 INFO - 'PAGER': '/bin/cat', 09:51:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:17 INFO - 'PWD': '/builds/slave/test', 09:51:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:17 INFO - 'SHELL': '/bin/bash', 09:51:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:51:17 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:51:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:17 INFO - 'USER': 'cltbld', 09:51:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:17 INFO - 'XPC_FLAGS': '0x0', 09:51:17 INFO - 'XPC_SERVICE_NAME': '0', 09:51:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:17 INFO - Downloading/unpacking jsonschema 09:51:17 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 09:51:17 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 09:51:17 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 09:51:17 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 09:51:17 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 09:51:17 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 09:51:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:51:19 INFO - Downloading/unpacking functools32 (from jsonschema) 09:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:51:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:51:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:51:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:51:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:51:20 INFO - warning: no files found matching '*.txt' 09:51:20 INFO - no previously-included directories found matching 'build' 09:51:20 INFO - no previously-included directories found matching 'dist' 09:51:20 INFO - no previously-included directories found matching '.git*' 09:51:20 INFO - Installing collected packages: jsonschema, functools32 09:51:20 INFO - Running setup.py install for functools32 09:51:20 INFO - warning: no files found matching '*.txt' 09:51:20 INFO - no previously-included directories found matching 'build' 09:51:20 INFO - no previously-included directories found matching 'dist' 09:51:20 INFO - no previously-included directories found matching '.git*' 09:51:20 INFO - Successfully installed jsonschema functools32 09:51:20 INFO - Cleaning up... 09:51:20 INFO - Return code: 0 09:51:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:51:20 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')]} 09:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:20 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')]} 09:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:51:20 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 0x1045fea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10460e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7a9cccd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, '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.g0uqDJJObY/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.tI5DF0rmcf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 09:51:20 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 09:51:20 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 09:51:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:51:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:20 INFO - 'HOME': '/Users/cltbld', 09:51:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:20 INFO - 'LOGNAME': 'cltbld', 09:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:20 INFO - 'MOZ_NO_REMOTE': '1', 09:51:20 INFO - 'NO_EM_RESTART': '1', 09:51:20 INFO - 'PAGER': '/bin/cat', 09:51:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:20 INFO - 'PWD': '/builds/slave/test', 09:51:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:20 INFO - 'SHELL': '/bin/bash', 09:51:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:51:20 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:51:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:20 INFO - 'USER': 'cltbld', 09:51:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:20 INFO - 'XPC_FLAGS': '0x0', 09:51:20 INFO - 'XPC_SERVICE_NAME': '0', 09:51:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:51:20 INFO - Downloading/unpacking flake8 09:51:20 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 09:51:20 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 09:51:20 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 09:51:20 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 09:51:20 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 09:51:20 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 09:51:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:51:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:51:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:23 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 09:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:51:24 INFO - no previously-included directories found matching 'docs/_build' 09:51:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:51:24 INFO - Running setup.py install for pep8 09:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:51:24 INFO - no previously-included directories found matching 'docs/_build' 09:51:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:51:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:51:24 INFO - Cleaning up... 09:51:24 INFO - Return code: 0 09:51:24 INFO - Running post-action listener: _resource_record_post_action 09:51:24 INFO - Running post-action listener: _start_resource_monitoring 09:51:24 INFO - Starting resource monitoring. 09:51:24 INFO - ##### 09:51:24 INFO - ##### Running install step. 09:51:24 INFO - ##### 09:51:24 INFO - Running pre-action listener: _resource_record_pre_action 09:51:24 INFO - Running main action method: install 09:51:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:51:25 INFO - Reading from file tmpfile_stdout 09:51:25 INFO - Detecting whether we're running mozinstall >=1.0... 09:51:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:51:25 INFO - Reading from file tmpfile_stdout 09:51:25 INFO - Output received: 09:51:25 INFO - Usage: mozinstall [options] installer 09:51:25 INFO - Options: 09:51:25 INFO - -h, --help show this help message and exit 09:51:25 INFO - -d DEST, --destination=DEST 09:51:25 INFO - Directory to install application into. [default: 09:51:25 INFO - "/builds/slave/test"] 09:51:25 INFO - --app=APP Application being installed. [default: firefox] 09:51:25 INFO - mkdir: /builds/slave/test/build/application 09:51:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:51:58 INFO - Reading from file tmpfile_stdout 09:51:58 INFO - Output received: 09:51:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:51:58 INFO - Running post-action listener: _resource_record_post_action 09:51:58 INFO - ##### 09:51:58 INFO - ##### Running run-tests step. 09:51:58 INFO - ##### 09:51:58 INFO - Running pre-action listener: _resource_record_pre_action 09:51:58 INFO - Running main action method: run_tests 09:51:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:51:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:51:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:51:58 INFO - 2016-05-05 09:51:58.396 screenresolution[2179:15480] starting screenresolution argv=screenresolution get 09:51:58 INFO - 2016-05-05 09:51:58.399 screenresolution[2179:15480] Display 0: 1600x1200x32@60 09:51:58 INFO - 2016-05-05 09:51:58.405 screenresolution[2180:15482] starting screenresolution argv=screenresolution list 09:51:58 INFO - Available Modes on Display 0 09:51:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:51:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:51:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:51:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:51:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:51:58 INFO - Intel Iris: 09:51:58 INFO - Chipset Model: Intel Iris 09:51:58 INFO - Type: GPU 09:51:58 INFO - Bus: Built-In 09:51:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:51:58 INFO - Vendor: Intel (0x8086) 09:51:58 INFO - Device ID: 0x0a2e 09:51:58 INFO - Revision ID: 0x0009 09:51:58 INFO - Displays: 09:51:58 INFO - Display: 09:51:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:51:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:51:58 INFO - Display Serial Number: 1600x1200 60 09:51:58 INFO - Main Display: Yes 09:51:58 INFO - Mirror: Off 09:51:58 INFO - Online: Yes 09:51:58 INFO - Rotation: Supported 09:51:58 INFO - Return code: 0 09:51:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:51:58 INFO - Python 2.7.3 09:51:58 INFO - Return code: 0 09:51:58 INFO - grabbing minidump binary from tooltool 09:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:51:58 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 0x10449de00>, '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 0x1042c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104498b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:51:58 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 09:51:58 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 09:51:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:51:58 INFO - Return code: 0 09:51:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:51:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:51:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:51:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:51:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:51:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:51:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:51:59 INFO - Return code: 0 09:51:59 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/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 09:51:59 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/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 09:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g0uqDJJObY/Render', 09:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:59 INFO - 'HOME': '/Users/cltbld', 09:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:59 INFO - 'LOGNAME': 'cltbld', 09:51:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:51:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:59 INFO - 'MOZ_NO_REMOTE': '1', 09:51:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:51:59 INFO - 'NO_EM_RESTART': '1', 09:51:59 INFO - 'PAGER': '/bin/cat', 09:51:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:59 INFO - 'PWD': '/builds/slave/test', 09:51:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:59 INFO - 'SHELL': '/bin/bash', 09:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tI5DF0rmcf/Listeners', 09:51:59 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 09:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:59 INFO - 'USER': 'cltbld', 09:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:59 INFO - 'XPC_FLAGS': '0x0', 09:51:59 INFO - 'XPC_SERVICE_NAME': '0', 09:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:51:59 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/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 09:52:00 INFO - mozversion application_buildid: 20160505075213 09:52:00 INFO - mozversion application_changeset: e9149132e07a04f5bcaf5f5519d74d1079febe14 09:52:00 INFO - mozversion application_display_name: Nightly 09:52:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:52:00 INFO - mozversion application_name: Firefox 09:52:00 INFO - mozversion application_remotingname: firefox 09:52:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:52:00 INFO - mozversion application_vendor: Mozilla 09:52:00 INFO - mozversion application_version: 49.0a1 09:52:00 INFO - mozversion platform_buildid: 20160505075213 09:52:00 INFO - mozversion platform_changeset: e9149132e07a04f5bcaf5f5519d74d1079febe14 09:52:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:52:00 INFO - mozversion platform_version: 49.0a1 09:52:00 INFO - using testdate: 1462467120 09:52:00 INFO - actual date: 1462467120 09:52:00 INFO - starting webserver on 'localhost:52779' 09:52:00 INFO - SUITE-START | Running 2 tests 09:52:00 INFO - TEST-START | dromaeo_css 09:52:00 INFO - operating with platform_type : mac_ 09:52:00 INFO - Initialising browser for dromaeo_css test... 09:52:00 INFO - TEST-INFO | started process 2198 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp0xFvIm/profile http://localhost:52779/getInfo.html) 09:52:02 INFO - PROCESS | 2198 | __metrics Screen width/height:1600/1200 09:52:02 INFO - PROCESS | 2198 | colorDepth:24 09:52:02 INFO - PROCESS | 2198 | Browser inner width/height: 1024/689 09:52:02 INFO - PROCESS | 2198 | __metrics 09:52:02 INFO - PROCESS | 2198 | 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" 09:52:02 ERROR - PROCESS | 2198 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:52:03 INFO - TEST-INFO | 2198: exit 0 09:52:03 INFO - Browser initialized. 09:52:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:52:03 INFO - TEST-INFO | started process 2200 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp0xFvIm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:15 INFO - PROCESS | 2200 | RSS: Main: 239480832 09:52:15 INFO - PROCESS | 2200 | 09:53:06 INFO - PROCESS | 2200 | 1462467186226 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] 09:55:16 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:52779/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52779/tests/dromaeo/cssquery-ext.html) 09:55:17 INFO - PROCESS | 2200 | RSS: Main: 371830784 09:55:17 INFO - PROCESS | 2200 | 09:58:20 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:52779/tests/dromaeo/cssquery-ext.html (next: http://localhost:52779/tests/dromaeo/cssquery-jquery.html) 09:58:21 INFO - PROCESS | 2200 | RSS: Main: 377724928 09:58:21 INFO - PROCESS | 2200 | 10:01:23 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:52779/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52779/tests/dromaeo/cssquery-mootools.html) 10:01:24 INFO - PROCESS | 2200 | RSS: Main: 367370240 10:01:24 INFO - PROCESS | 2200 | 10:04:26 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:52779/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52779/tests/dromaeo/cssquery-prototype.html) 10:04:27 INFO - PROCESS | 2200 | RSS: Main: 370028544 10:04:27 INFO - PROCESS | 2200 | 10:07:30 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:52779/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52779/tests/dromaeo/cssquery-yui.html) 10:07:31 INFO - PROCESS | 2200 | RSS: Main: 342028288 10:07:31 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:52779/tests/dromaeo/cssquery-yui.html (next: http://localhost:52779/tests/dromaeo/cssquery-dojo.html) 10:10:34 INFO - PROCESS | 2200 | RSS: Main: 395370496 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | __start_tp_report 10:10:34 INFO - PROCESS | 2200 | _x_x_mozilla_page_load 10:10:34 INFO - PROCESS | 2200 | _x_x_mozilla_page_load_details 10:10:34 INFO - PROCESS | 2200 | |i|pagename|runs| 10:10:34 INFO - PROCESS | 2200 | |0;dojo.html;3052.755779537637;3359.9832000839992;3383.6108847482546;3416.1630400551867;3444.7416443766724;5432.13085906255;5476.2333273341765;5483.259759932408;5508.283923089998;5524.944750552493;5498.505134537891;5513.50378465938;5615.326160860693;5640.435956404358;5661.943380566193;5019.64862459628;5022.096022715909;5059.392872855244;5061.16490779021;5267.157254839227;5167.767450464719;5172.612054095939;5180.507851754096;5210.114280572312;5212.400573933989;4988.476209997971;5026.371703537058;5073.086844368012;5128.923066153992;5145.588352931794;5060.797568097271;5067.695938243717;5106.157484015133;5113.718745468997;5126.487351264881;5035.949640503622;5043.571296439807;5071.41678706946;5083.974580127113;5116;5350.491703288181;5381.67709937405;5415.891682166374;5427.864303392446;5453.23654688344;6054.667097769027;6066.181955620074;6074.145151778657;6084.6349219047;6096.1033937223965;6097.146399504073;6112.694365281718;6134.754609815602;6141.263048434172;6141.447269745744;5519.917201241985;5603.271574695264;5630.5214056805635;5648.548116150698;5661.320641523043;6161.7227224775;6166.568340216231;6175.011998080286;6179.28938172116;6186.4741496972365;5066;5955.583109182402;5971.731272092767;6003.459688628044;6012.579119461596;5875.3243377012195;6047.879042419215;6085.391460853968;6136.294326152461;6147.200863887755;6010.459058684739;6063.848403789941;6065.8180254592435;6070.392960703983;6124.265088189446;6081.878362432815;6086.208792856989;6087.452129308295;6100.176476175749;6102.298235702951;4720.575148236607;4780.115678599401;4786.712797232177;4809.591184749265;4810.855674329776;4362.956370436318;4364.934525982113;4390.2317094508335;4390.692651514363;4399.868003959887;5583.246261754693;5586.413426590149;5597.272354593877;5610.803621873215;5613.915791263134;6048.274207095177;6093.141993208895;6093.413838573086;6095.680160276974;6121.540884433685;5565.9443405566235;5568.49883510486;5586.13414920688;5606.131049687305;5607.8878422432135;5200.375954885362;5201.5838732901275;5203.219517072469;5207.531322181022;5272.99813035522;6105.732692890381;6115.362036198677;6150.261968563801;6184.103305020747;6184.319724830355;6134.723937422737;6138.7869253627705;6164.630122192682;6165.290991536031;6177.907331390123;6180.876382472416;6192.690365481798;6195.2255968004;6199.504039676905;6207.503399727921;5864.6481211125765;5959.4934430573885;5985.191999080069;5991.730372133177;6075.908861367171;6101.389861013863;6105.328413874559;6109.572329936862;6132.233470816148;6138.508919286357;5916.49709774674;5924.407559244041;5929.495992840657;5932.881342373042;5945.524358051432;5706.400827913009;5738.626989245686;5848.736806843617;5956.285245770535;5993.460588546877;5682.6306290091015;5719.3136823581435;5740.741666624928;5741.024025915521;5767.048437007847;5728.112142617901;5897.734601942837;5924.6445213285615;5937.079752638348;5952.494038006818;5664.376918538875;5720.771169153188;5728.971355143198;5740.1102829061565;5750.022496175577 10:10:34 INFO - PROCESS | 2200 | |1;ext.html;497.9452260251374;498.14568015852814;528.1285878300803;537.8785232789634;644.9903251451232;29752.95864644738;32390.866319678844;38191.809040954824;39040.60959390405;39327;36449.81775091127;43240.13519729603;44050.77974610122;44091.77954110225;44098.77950610242;8609.48343099416;9564.617415303379;9797.804043919117;9806.509674516246;9814.754631134243;8693.608787604573;11365.147613928946;11642.941785291061;11650.067994560415;11681.415929203547;11476.598319058834;11813.054955603575;11826.467808948575;11828.822567661491;11835.93476587107;8445.366597505212;11410.657680269605;11777.646670599854;11905.047596192284;11919.046476281877;11396.886031139666;11555.826662600068;11568.01671857893;11600.129990250765;11604.419779011016;33764.6623533764;37491.25017499635;39015.8049209755;39056;39097.60902390968;3451.361498122855;3537;3593.281343731238;3755.680767134797;3963.6829053675638;27455.725442745515;29170.854145729347;29571.556426653617;29637.407251855057;29674.851625741732;2660.8004399670103;2773.62556054932;2775.417162395899;2833.914982550506;2931.9884639799448;482523;482523;484007;484759;484983.99999999645;59547.70226148841;59867.40132598618;59918.70040649856;59950.40049599448;60024.39975600188;60932.781344373754;61151.69424152851;61171.882811718475;61192.38807611956;61280;14390.42438302477;15851.207439627973;16006.919965400331;16010.279537420844;16012.839871601134;11679.532818687327;11930.642080737593;12283.034714445646;12557.434915428828;12607.558735444216;14931.954763166474;15162.166080865438;15178.620534486505;15191.012584181994;15205.9355845092;4584.005896787539;7132.964335178291;7344.816379590447;7399.149097853816;7427.554346739213;872.5549969515592;975.8290051937703;1032.6282538286212;1033.1476531861244;1073.8281874900135;25392.984280628938;28116.71883281182;28290.71709282922;28444.715552844205;28854.278643034093;1097.412884106999;1098.6594155811724;1100.3342977498658;1119.9832002519968;1170.795110855597;13847.438223928606;14799;15126.848731512764;15262.16058116792;15433.614159646098;456607;593571;600082;601076;603610;58986;59278.70360648255;59833;59951.10073348903;60029.69985150133;1355.5391167003265;1369.6918193406404;1477.8226612806532;1494.2379386512957;1512.7428337182705;24071.398215044763;26944.461110778066;28481.572776408786;29347.70652293535;29392.11823645294;38474.422883657324;50333.49666503288;50345.46199918079;50375.992480150926;50415.49584504108;402330;418824;418884;419426;420645;30466.84766576153;30557.694423055484;34211.30112026539;39243.01892452712;39896;41216.96957576084;41501.58498414977;43186.136277274905;43690.56309436865;45769.54230457653;33246;35948.4607730879;36929.63070369369;38008.239835202585;38031.23937521289;36129.63870361368;36319.63680363268;36941.63058369382;37075.81462092672;37120.257594848496;36149;36253.27493450169;36305.81847090748;36568.45147322739;36935.815320923226;31758.52362214615;32311.51532727058;32397.838010809795;32417.35165296728;32486.862959796294;23651.64522532198;23793.881030594737;24028.51942961166;24050.87974560116;24317.027318907047 10:10:34 INFO - PROCESS | 2200 | |2;jquery.html;396.10282709662613;400.8476778824045;401.53823103431034;403.9616236457537;414.4922469974282;14843.332050057747;20013.899930500345;20706.171753129875;20734.688979665298;20849.895750521227;18970.05149742511;19031.42905712831;19103;19805.504862378446;20310.390688279374;5267.710275934822;5625.971870140654;6062;6064.302605200414;6069.544784141185;5691.601587888849;5772.826815195551;6023.397660233985;6052.061930400795;6064.605800622968;3904.2191561687778;4134.049168691203;5809.477147056764;6116.143739876421;6116.724747386378;5883.617564858313;6093.482054025413;6104.389561043882;6114.205153330083;6249.468795152417;5900.822975310747;5918.644881307092;5937.346891841894;5943.583949123563;5955.166276721263;13137.671558211026;13564.253966031863;13573.59279221625;13587.980901432433;22997.770022299897;2164.5779073080757;2176.32201579332;2260.8869556522272;2262.739784924722;2281.53228588139;9265.212456941168;9858.915519292872;10038;10080.294379393445;10194.388336699822;1598.4964736108088;1686.966260674792;1713.9485815425558;1748.5104170832187;1779.884307520007;115246;115587.42206289;116333.41833290864;117223.41388293004;117359.41320293429;11326.943365283121;11374.374409407397;11424.714382140346;11470.655880323604;11627.302361858316;14780.778288325684;14876.181810000337;14923.925380373028;14926.328315225841;14966.551003469913;8327.958360208282;8516.957415212884;8528.189821966904;8610.354223433267;8654.22112009922;8411.443882881686;8411.45325553837;8465.492070475791;8563.886694729645;8586.95706521476;4431.423914891044;4510.706804057726;4521.321801729701;4580.198465268565;4586.174488592019;1784.3843873863495;1900.451132327149;1969.9704504432445;1971.1819594867968;1972.654785412547;767.5087943716027;856.4005196362447;980.3823591137538;988.836841921075;1038.7039693687263;15283.694326113415;15655.530334089995;17667.911660441874;19352;19592.510187245432;931.7065124485765;950.5104870991445;977.3012296208245;980.2550061952825;993.4883534979491;10144.898551014396;10496.895031049591;10516.053555180071;10534.57861685524;10542.367457952547;571161;571183;587280;588058;588165.0591747013;109342;133027.33486332506;137909;138132;139474.3026284862;1013.5996281468779;1035.13566172247;1049.3703777733451;1051.348164138239;1055.287680815462;13740.359064100343;17594.29622815073;17936.641267174844;17979.10104494796;18107.279808418705;51050.48949510457;52268.47731522636;53122;53496.73251633717;54473.72763136159;76875.23124768681;80340.59829700814;81137;81479.59260203662;82370.29531841411;8033.959830200812;8179.83640327202;8332.791680208044;8506.191911768372;8636.827263454821;10170.338927969658;12398;12497.687557810763;12808.935955320163;12865.549705760253;5504.22940788282;8126.512409255463;8614.698485553226;10053.949730251303;10823.621173258898;9599.42403455795;10212.387256764618;10929.617463388744;10948.124150067817;11436.828447573127;11304.699959504762;11462.656120316404;11532.192746507999;11582.594609188638;11826.645200643994;8147.918520814953;8255.174482551696;8262;8450.323974081935;8471.61877715492;9647.421154730739;11282.379469129277;11341.829872551752;11555.711107222387;11712.355820429959 10:10:34 INFO - PROCESS | 2200 | |3;mootools.html;1458.423922550592;1623.1072909899551;1646.0370683150356;1933.1204302042572;2044.7035179898915;7839.608019599019;8646.221840034395;8852.8672069919;8855.690050848221;8883.95558022209;8439.662413503467;8510.48937063775;8682.305415566756;8685;8696.869546956785;2157.4714195022243;2160.7407111146686;2387.641853721947;2468.5556599812026;2468.7654672806057;2468.7531246875346;2490.2653717153485;2494.1270555305678;2494.900203991838;2495.625656151574;2403.495265994143;2456.4350199454047;2458;2458.606622940321;2460.0774709483944;2130.265058554804;2364.6334818103223;2426.4176597616533;2435.2207293666033;2437.756224377566;2103.5267064910427;2421.1525965911965;2443.218170185541;2444.9388765280833;2456.980353153439;8577.828443431097;8631.784205394915;8651.437656552303;8659.826803463895;8672.349573781928;5068.113080210986;5092.312537807423;5268.789248430058;5307.203919412081;5312.335958005249;3477.6870081692514;3549.716022718202;3618.077390265492;3618.6019537850816;3623.565172179329;1037.723599972031;1040.6097713357492;1041.3023274406166;1045.3362115056993;1058.8941105889426;11530.250533715282;11535.59625413115;11546.134039946954;11553.711157221052;11562.653120406401;4697.0840686065885;4738.881527961828;4781.856544303677;4832.226843705061;4846.5153484651255;9726.70819875405;9842.852357214648;9853.359531630422;9871.160951319074;9873.703788886345;5358.812441564604;5364.6512976656395;5370.597205209625;5378.462153784591;5390.946090539122;4206.810693518799;4372.8906777330185;4451.287793952952;4508.165989291926;4536.138133754576;3906.4140378943384;3939.527256729211;3998.260321840469;4027.637512623878;4035.4955630546183;1170.0464121740754;1290.4644572502307;1298.9283840831163;1320.8348956380453;1346.4614154338342;908.3459908865606;929.6606738540519;940.1632547033009;946.5503885654397;982.1210017034714;5764.538836893039;5782.6819524925695;5888.381719919433;5928.347881733006;6034.517238620988;435.98692039238875;457.27065330797325;457.79399270328486;459.2239115894128;474.4377912174016;3815.4467602197524;3853.4412510185866;3918.686505079587;3920.4903362563255;3946.4869566956113;10056.29605927578;10477.31897426665;10570.260081794355;10665.36007839532;10671;10483.266171367932;10614.681559553226;10629.30909490896;10632.840507392395;10638.521266543024;8058.234467725557;8075.434719569691;8093.3929955252415;8116.066652335057;8230;5660.490555849993;5682.431756824284;5744.6265992712015;5771;5787.8553036174435;9421.434713917188;9883.357581757164;10004.199664027006;10010.299279050396;10013.549390277602;7751.806204844924;9999;10004.599816007569;10084.344517606332;10118.19054475655;5933.517051326287;6797.014432907102;6916.688749006407;7298.6715597797165;7388.926110738824;8021.879671804811;8068.112507624274;8163;8166.836663266583;8170.01959764832;7094.893576596459;7134.536255143399;7425.108986921604;7428.73999410018;7445.143808462096;7145.535540189871;7188.5327453715345;7207.387372073223;7283.453740969342;7328.450366222662;5948.405159484017;6108.144859719554;6262.34245404226;6288.6855657217875;6300.464460520907;4879.853604391874;4913.582345500672;4916.213405855046;4918.827841025547;5010.824621138243;4920.384951881015;4955.785832471068;4996.400431948189;5124.666896651705;5131.589472842089 10:10:34 INFO - PROCESS | 2200 | |4;prototype.html;304.4322338838067;317.2212219002349;323.1710662151581;323.58419431031115;329.3051660995298;4540.432445944257;4920.360353154094;5295.973520132398;5319.148936170213;5342.412334643196;4780.880477988052;5206.843794686156;5244.875825290764;5252.52727254547;5266.947330526694;3191.5212718092357;3516.349475347069;4010.3984402339593;4095.4061661059127;4101.056756945904;3701.2597480503873;3859.4017927221325;3974.9006274843214;4052.9797351013203;4110.362893751473;4027.738197017199;4035.455213546163;4042.575529569382;4054.493188351456;4072.5586879087273;3617.421212605984;3925.9803700981597;3947.2894878921784;3955.881323560312;4104.527979282361;3507.2459421224303;3538.752287339887;3552.1281802834774;3554.1647712787476;3584.318979393907;4116.629503344713;4503.919059425747;4989.627852340599;5014;5050.772715227824;3874.9000799360397;4060.675145988314;4169.6038876307;4549.338519107819;4655.185342565072;5504.366997795265;5524.171374293848;5526.336839579236;5533.170024496318;5545.343749375615;3483.4252348362743;3507.9829098268597;3908.537621298592;3933.8591808375536;3938.468306778577;5014.671112155282;5270.39390470097;5307.28351672527;5504.669719816784;5840.912386314209;3103.9912028590797;3170.7621928355466;3983.143624120828;3995.182192102586;4005.2990726622725;3438.484227365865;3860.2665493556133;4051.2505186540584;4054.3715724062818;4064.9593504065174;4637.234856248681;4646.233371493666;4655.441347038377;4655.974651026814;4661.766911654471;4859.975700121477;5241.31862857826;5538.252335934597;5622.9916994407795;5662;1412.8304603447652;1436.1526699247495;1444.104655113836;1444.227338373986;1444.7182799354234;827.3381294964125;915.4095608332722;945.2863088368375;956.4739393333639;959.3236768078488;5064.265681476165;5097.64316497849;5113.488651134857;5201.973990130101;5279.275048831275;5019.870529130916;5435.130379139318;5709.74306156224;5751.913721294184;5771.480566748929;4481.910361792746;4992.701897506602;5129.179331306973;5247.816326428557;5268.894622107537;3253.235489659928;3279.2457734721147;3287.161773747727;3299.290652509722;3332.683395077464;5220.086484865134;6252.374762523802;6517.739290428424;6536.281009088996;6601.801945941629;4882.584980276716;6461.935380646228;6464.676766161673;6488.967555162194;6491.188601424822;6567;6588.53880228389;6595.274519802914;6601.933980660132;6621.768238111643;4620.514845941262;5849.824505264849;5851.664551205772;6286.748530058747;6297.275813281532;5164.431912489548;5468.484497131364;5716.79991200306;5766.107877182969;5777.74000169985;4971.552560269593;5305.949079279352;5939.881202376015;6075.605085669417;6122.479589234965;3301.4882693182594;3763.943540846835;3898.128898128857;3900.8439662413184;3905.6484916357663;5232.476752324737;5289.6032797539565;5350.545203657733;5594.188842617717;5798.82603521895;5503.587230957774;5671.206031155559;5684.147377893349;5699.629524080921;5717.485426311652;4606.170889239969;4845.466998630078;5592.608517403743;5603.545181580572;5614.494695477427;4764.356811830358;5470.480304371078;5477.917831232615;5510.228568000563;5526.336839579116;4236.661067114562;5262.9210561842365;5334.253204551288;5381.865453363697;5435.374931882884;4610.884727881696;4892.779824907817;5261.973690131525;5473.8357849264585;5506.889862202814 10:10:34 INFO - PROCESS | 2200 | |5;yui.html;657.6120089147403;680.3910500102409;727.9126504819423;745.772539375491;807.4509333653117;11153.330800151987;13910.30448477576;14691.33888974996;15059.472918447857;15148.924255378708;13679.384427700752;13970.511032113878;14011.299435028233;14042.087264327836;14375.281235938188;728.624758249502;923.1276443760648;941.5292353823088;943.2689665509216;945.3854994253721;754.4417131322837;902.7517432706026;943.485800238868;944.6079876851098;945.3524335829944;940.4921342475055;963.0658261486348;993.4287784523891;994.2890833054365;996.4170959988379;751.9661615227328;948.1087777489138;954.4654993203793;983.1839573154265;985.3447457440793;730.7369347035063;732.5458215906173;735.9411247100219;736.5801493148907;738.9889151662676;15220.847791522165;15369.077855328716;15390;15402.614934626612;15418.53744387659;12678.619641410773;13111.494430000956;13137.014723895745;13145.73708525824;13471.191728496224;9935.90064099357;10082.747931301703;10279.691609251735;10314.329568578018;10493.63272285474;3963.088200873495;4048.5748996355546;4064.410660454247;4184.121334519755;4294.7852607369505;59593.8487381517;64606.3539364605;71210.64394678046;72561.27438725598;72960.7983321413;9290;9352.971173170945;9408.12041123285;9534.713958581253;9580.952095239618;21063.8946805265;21065;21079.789202108088;21080.578388432452;21109.155633774782;7224.988501609779;7286.23494533077;7298.235103222704;7356.816079598053;7371.594562299125;3941.684665226775;4008.7795171265275;4013.5585085640555;4017.13631569214;4017.4978127734034;3595.9101022474124;3662.212624285791;3669.4679271506016;3679.632036796299;3679.8896033118513;601.7623038899698;603.2037710222552;608.8782243551308;624.1698540940629;625.5402279324693;514.3647595219901;514.4546780412775;582.4641329976432;585.3268740947859;590.498076634857;7250.2024777274455;7565.319121279112;7569.356604688553;7770.572618506037;7811.687532498637;586.3315819965243;612.5803824380314;616.4328817487922;619.8977168767103;625.2621906150695;3736.23407201523;3745.5318085239346;3894.941575876364;3943.4084887266567;4117.197146556393;80202.19797802065;82651.76022359669;82905.58547207226;83465;83663;66745;69644;70181;71984;75008.62495687486;49672;50383.24425133626;65615.35961600902;65977.67011164913;69096.30903690899;6841.555298905555;7610.315071643578;7790.805229869298;7814.609269536387;8330.625121869409;34192.46133924029;42185.94535136523;43919.12161756811;45190;45401.31898021598;62119.6894015527;62972.68513657402;63060.68469657806;63368.36631633624;63467.73064538775;11060.557577696802;11213.635909227361;11228.045616122394;11247.718807029889;11316.321020738782;11767.461096522398;11804.940975295069;11902.285862848257;11987.520499179935;12351.814722778985;11332.716682083013;11340.6030788922;11491.195616306779;11508.654740357802;11962;11275.210735248455;11353.641960502146;11411.486483108447;11435;11701.531938722695;11086.667399978014;11107.833382499108;11233.943830280796;11263.493142808757;11417.200795944203;9465.621375144918;9630.422174669542;9826.803463930824;9867.452865247762;10092.899071009197;9366.382942651706;9646.855297170689;9718.368306060367;9732.94715274918;9741.123298903132 10:10:34 INFO - PROCESS | 2200 | __end_tp_report 10:10:34 INFO - PROCESS | 2200 | __start_cc_report 10:10:34 INFO - PROCESS | 2200 | _x_x_mozilla_cycle_collect,1319 10:10:34 INFO - PROCESS | 2200 | __end_cc_report 10:10:34 INFO - PROCESS | 2200 | __startTimestamp1462468234285__endTimestamp 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | ------- Summary: start ------- 10:10:34 INFO - PROCESS | 2200 | Number of tests: 6 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | [#0] dojo.html Cycles:165 Average:5596.73 Median:5728.97 stddev:615.04 (10.7%) stddev-sans-first:583.65 10:10:34 INFO - PROCESS | 2200 | Values: 3052.8 3360.0 3383.6 3416.2 3444.7 5432.1 5476.2 5483.3 5508.3 5524.9 5498.5 5513.5 5615.3 5640.4 5661.9 5019.6 5022.1 5059.4 5061.2 5267.2 5167.8 5172.6 5180.5 5210.1 5212.4 4988.5 5026.4 5073.1 5128.9 5145.6 5060.8 5067.7 5106.2 5113.7 5126.5 5035.9 5043.6 5071.4 5084.0 5116.0 5350.5 5381.7 5415.9 5427.9 5453.2 6054.7 6066.2 6074.1 6084.6 6096.1 6097.1 6112.7 6134.8 6141.3 6141.4 5519.9 5603.3 5630.5 5648.5 5661.3 6161.7 6166.6 6175.0 6179.3 6186.5 5066.0 5955.6 5971.7 6003.5 6012.6 5875.3 6047.9 6085.4 6136.3 6147.2 6010.5 6063.8 6065.8 6070.4 6124.3 6081.9 6086.2 6087.5 6100.2 6102.3 4720.6 4780.1 4786.7 4809.6 4810.9 4363.0 4364.9 4390.2 4390.7 4399.9 5583.2 5586.4 5597.3 5610.8 5613.9 6048.3 6093.1 6093.4 6095.7 6121.5 5565.9 5568.5 5586.1 5606.1 5607.9 5200.4 5201.6 5203.2 5207.5 5273.0 6105.7 6115.4 6150.3 6184.1 6184.3 6134.7 6138.8 6164.6 6165.3 6177.9 6180.9 6192.7 6195.2 6199.5 6207.5 5864.6 5959.5 5985.2 5991.7 6075.9 6101.4 6105.3 6109.6 6132.2 6138.5 5916.5 5924.4 5929.5 5932.9 5945.5 5706.4 5738.6 5848.7 5956.3 5993.5 5682.6 5719.3 5740.7 5741.0 5767.0 5728.1 5897.7 5924.6 5937.1 5952.5 5664.4 5720.8 5729.0 5740.1 5750.0 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | [#1] ext.html Cycles:180 Average:63158.07 Median:19770.87 stddev:131940.06 (667.3%) stddev-sans-first:132226.31 10:10:34 INFO - PROCESS | 2200 | Values: 497.9 498.1 528.1 537.9 645.0 29753.0 32390.9 38191.8 39040.6 39327.0 36449.8 43240.1 44050.8 44091.8 44098.8 8609.5 9564.6 9797.8 9806.5 9814.8 8693.6 11365.1 11642.9 11650.1 11681.4 11476.6 11813.1 11826.5 11828.8 11835.9 8445.4 11410.7 11777.6 11905.0 11919.0 11396.9 11555.8 11568.0 11600.1 11604.4 33764.7 37491.3 39015.8 39056.0 39097.6 3451.4 3537.0 3593.3 3755.7 3963.7 27455.7 29170.9 29571.6 29637.4 29674.9 2660.8 2773.6 2775.4 2833.9 2932.0 482523.0 482523.0 484007.0 484759.0 484984.0 59547.7 59867.4 59918.7 59950.4 60024.4 60932.8 61151.7 61171.9 61192.4 61280.0 14390.4 15851.2 16006.9 16010.3 16012.8 11679.5 11930.6 12283.0 12557.4 12607.6 14932.0 15162.2 15178.6 15191.0 15205.9 4584.0 7133.0 7344.8 7399.1 7427.6 872.6 975.8 1032.6 1033.1 1073.8 25393.0 28116.7 28290.7 28444.7 28854.3 1097.4 1098.7 1100.3 1120.0 1170.8 13847.4 14799.0 15126.8 15262.2 15433.6 456607.0 593571.0 600082.0 601076.0 603610.0 58986.0 59278.7 59833.0 59951.1 60029.7 1355.5 1369.7 1477.8 1494.2 1512.7 24071.4 26944.5 28481.6 29347.7 29392.1 38474.4 50333.5 50345.5 50376.0 50415.5 402330.0 418824.0 418884.0 419426.0 420645.0 30466.8 30557.7 34211.3 39243.0 39896.0 41217.0 41501.6 43186.1 43690.6 45769.5 33246.0 35948.5 36929.6 38008.2 38031.2 36129.6 36319.6 36941.6 37075.8 37120.3 36149.0 36253.3 36305.8 36568.5 36935.8 31758.5 32311.5 32397.8 32417.4 32486.9 23651.6 23793.9 24028.5 24050.9 24317.0 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | [#2] jquery.html Cycles:180 Average:34120.33 Median:2261.81 stddev:97371.26 (4305.0%) stddev-sans-first:97611.48 10:10:34 INFO - PROCESS | 2200 | Values: 396.1 400.8 401.5 404.0 414.5 14843.3 20013.9 20706.2 20734.7 20849.9 18970.1 19031.4 19103.0 19805.5 20310.4 5267.7 5626.0 6062.0 6064.3 6069.5 5691.6 5772.8 6023.4 6052.1 6064.6 3904.2 4134.0 5809.5 6116.1 6116.7 5883.6 6093.5 6104.4 6114.2 6249.5 5900.8 5918.6 5937.3 5943.6 5955.2 13137.7 13564.3 13573.6 13588.0 22997.8 2164.6 2176.3 2260.9 2262.7 2281.5 9265.2 9858.9 10038.0 10080.3 10194.4 1598.5 1687.0 1713.9 1748.5 1779.9 115246.0 115587.4 116333.4 117223.4 117359.4 11326.9 11374.4 11424.7 11470.7 11627.3 14780.8 14876.2 14923.9 14926.3 14966.6 8328.0 8517.0 8528.2 8610.4 8654.2 8411.4 8411.5 8465.5 8563.9 8587.0 4431.4 4510.7 4521.3 4580.2 4586.2 1784.4 1900.5 1970.0 1971.2 1972.7 767.5 856.4 980.4 988.8 1038.7 15283.7 15655.5 17667.9 19352.0 19592.5 931.7 950.5 977.3 980.3 993.5 10144.9 10496.9 10516.1 10534.6 10542.4 571161.0 571183.0 587280.0 588058.0 588165.1 109342.0 133027.3 137909.0 138132.0 139474.3 1013.6 1035.1 1049.4 1051.3 1055.3 13740.4 17594.3 17936.6 17979.1 18107.3 51050.5 52268.5 53122.0 53496.7 54473.7 76875.2 80340.6 81137.0 81479.6 82370.3 8034.0 8179.8 8332.8 8506.2 8636.8 10170.3 12398.0 12497.7 12808.9 12865.5 5504.2 8126.5 8614.7 10053.9 10823.6 9599.4 10212.4 10929.6 10948.1 11436.8 11304.7 11462.7 11532.2 11582.6 11826.6 8147.9 8255.2 8262.0 8450.3 8471.6 9647.4 11282.4 11341.8 11555.7 11712.4 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | [#3] mootools.html Cycles:180 Average:5607.12 Median:4863.18 stddev:3135.07 (64.5%) stddev-sans-first:3128.36 10:10:34 INFO - PROCESS | 2200 | Values: 1458.4 1623.1 1646.0 1933.1 2044.7 7839.6 8646.2 8852.9 8855.7 8884.0 8439.7 8510.5 8682.3 8685.0 8696.9 2157.5 2160.7 2387.6 2468.6 2468.8 2468.8 2490.3 2494.1 2494.9 2495.6 2403.5 2456.4 2458.0 2458.6 2460.1 2130.3 2364.6 2426.4 2435.2 2437.8 2103.5 2421.2 2443.2 2444.9 2457.0 8577.8 8631.8 8651.4 8659.8 8672.3 5068.1 5092.3 5268.8 5307.2 5312.3 3477.7 3549.7 3618.1 3618.6 3623.6 1037.7 1040.6 1041.3 1045.3 1058.9 11530.3 11535.6 11546.1 11553.7 11562.7 4697.1 4738.9 4781.9 4832.2 4846.5 9726.7 9842.9 9853.4 9871.2 9873.7 5358.8 5364.7 5370.6 5378.5 5390.9 4206.8 4372.9 4451.3 4508.2 4536.1 3906.4 3939.5 3998.3 4027.6 4035.5 1170.0 1290.5 1298.9 1320.8 1346.5 908.3 929.7 940.2 946.6 982.1 5764.5 5782.7 5888.4 5928.3 6034.5 436.0 457.3 457.8 459.2 474.4 3815.4 3853.4 3918.7 3920.5 3946.5 10056.3 10477.3 10570.3 10665.4 10671.0 10483.3 10614.7 10629.3 10632.8 10638.5 8058.2 8075.4 8093.4 8116.1 8230.0 5660.5 5682.4 5744.6 5771.0 5787.9 9421.4 9883.4 10004.2 10010.3 10013.5 7751.8 9999.0 10004.6 10084.3 10118.2 5933.5 6797.0 6916.7 7298.7 7388.9 8021.9 8068.1 8163.0 8166.8 8170.0 7094.9 7134.5 7425.1 7428.7 7445.1 7145.5 7188.5 7207.4 7283.5 7328.5 5948.4 6108.1 6262.3 6288.7 6300.5 4879.9 4913.6 4916.2 4918.8 5010.8 4920.4 4955.8 4996.4 5124.7 5131.6 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | [#4] prototype.html Cycles:180 Average:4559.83 Median:5035.32 stddev:1424.49 (28.3%) stddev-sans-first:1392.22 10:10:34 INFO - PROCESS | 2200 | Values: 304.4 317.2 323.2 323.6 329.3 4540.4 4920.4 5296.0 5319.1 5342.4 4780.9 5206.8 5244.9 5252.5 5266.9 3191.5 3516.3 4010.4 4095.4 4101.1 3701.3 3859.4 3974.9 4053.0 4110.4 4027.7 4035.5 4042.6 4054.5 4072.6 3617.4 3926.0 3947.3 3955.9 4104.5 3507.2 3538.8 3552.1 3554.2 3584.3 4116.6 4503.9 4989.6 5014.0 5050.8 3874.9 4060.7 4169.6 4549.3 4655.2 5504.4 5524.2 5526.3 5533.2 5545.3 3483.4 3508.0 3908.5 3933.9 3938.5 5014.7 5270.4 5307.3 5504.7 5840.9 3104.0 3170.8 3983.1 3995.2 4005.3 3438.5 3860.3 4051.3 4054.4 4065.0 4637.2 4646.2 4655.4 4656.0 4661.8 4860.0 5241.3 5538.3 5623.0 5662.0 1412.8 1436.2 1444.1 1444.2 1444.7 827.3 915.4 945.3 956.5 959.3 5064.3 5097.6 5113.5 5202.0 5279.3 5019.9 5435.1 5709.7 5751.9 5771.5 4481.9 4992.7 5129.2 5247.8 5268.9 3253.2 3279.2 3287.2 3299.3 3332.7 5220.1 6252.4 6517.7 6536.3 6601.8 4882.6 6461.9 6464.7 6489.0 6491.2 6567.0 6588.5 6595.3 6601.9 6621.8 4620.5 5849.8 5851.7 6286.7 6297.3 5164.4 5468.5 5716.8 5766.1 5777.7 4971.6 5305.9 5939.9 6075.6 6122.5 3301.5 3763.9 3898.1 3900.8 3905.6 5232.5 5289.6 5350.5 5594.2 5798.8 5503.6 5671.2 5684.1 5699.6 5717.5 4606.2 4845.5 5592.6 5603.5 5614.5 4764.4 5470.5 5477.9 5510.2 5526.3 4236.7 5262.9 5334.3 5381.9 5435.4 4610.9 4892.8 5262.0 5473.8 5506.9 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - PROCESS | 2200 | [#5] yui.html Cycles:180 Average:16832.30 Median:606.04 stddev:22598.64 (3728.9%) stddev-sans-first:22629.40 10:10:34 INFO - PROCESS | 2200 | Values: 657.6 680.4 727.9 745.8 807.5 11153.3 13910.3 14691.3 15059.5 15148.9 13679.4 13970.5 14011.3 14042.1 14375.3 728.6 923.1 941.5 943.3 945.4 754.4 902.8 943.5 944.6 945.4 940.5 963.1 993.4 994.3 996.4 752.0 948.1 954.5 983.2 985.3 730.7 732.5 735.9 736.6 739.0 15220.8 15369.1 15390.0 15402.6 15418.5 12678.6 13111.5 13137.0 13145.7 13471.2 9935.9 10082.7 10279.7 10314.3 10493.6 3963.1 4048.6 4064.4 4184.1 4294.8 59593.8 64606.4 71210.6 72561.3 72960.8 9290.0 9353.0 9408.1 9534.7 9581.0 21063.9 21065.0 21079.8 21080.6 21109.2 7225.0 7286.2 7298.2 7356.8 7371.6 3941.7 4008.8 4013.6 4017.1 4017.5 3595.9 3662.2 3669.5 3679.6 3679.9 601.8 603.2 608.9 624.2 625.5 514.4 514.5 582.5 585.3 590.5 7250.2 7565.3 7569.4 7770.6 7811.7 586.3 612.6 616.4 619.9 625.3 3736.2 3745.5 3894.9 3943.4 4117.2 80202.2 82651.8 82905.6 83465.0 83663.0 66745.0 69644.0 70181.0 71984.0 75008.6 49672.0 50383.2 65615.4 65977.7 69096.3 6841.6 7610.3 7790.8 7814.6 8330.6 34192.5 42185.9 43919.1 45190.0 45401.3 62119.7 62972.7 63060.7 63368.4 63467.7 11060.6 11213.6 11228.0 11247.7 11316.3 11767.5 11804.9 11902.3 11987.5 12351.8 11332.7 11340.6 11491.2 11508.7 11962.0 11275.2 11353.6 11411.5 11435.0 11701.5 11086.7 11107.8 11233.9 11263.5 11417.2 9465.6 9630.4 9826.8 9867.5 10092.9 9366.4 9646.9 9718.4 9732.9 9741.1 10:10:34 INFO - PROCESS | 2200 | -------- Summary: end -------- 10:10:34 INFO - PROCESS | 2200 | 10:10:34 INFO - TEST-INFO | 2200: exit 0 10:10:34 INFO - TEST-OK | dromaeo_css | took 1114844ms 10:10:34 INFO - TEST-START | kraken 10:10:34 INFO - operating with platform_type : mac_ 10:10:34 INFO - Initialising browser for kraken test... 10:10:34 INFO - TEST-INFO | started process 2204 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpPxuLOP/profile http://localhost:52779/getInfo.html) 10:10:37 INFO - PROCESS | 2204 | __metrics Screen width/height:1600/1200 10:10:37 INFO - PROCESS | 2204 | colorDepth:24 10:10:37 INFO - PROCESS | 2204 | Browser inner width/height: 1024/689 10:10:37 INFO - PROCESS | 2204 | __metrics 10:10:37 INFO - PROCESS | 2204 | 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" 10:10:37 ERROR - PROCESS | 2204 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:10:37 INFO - TEST-INFO | 2204: exit 0 10:10:37 INFO - Browser initialized. 10:10:37 INFO - Running cycle 1/1 for kraken test... 10:10:37 INFO - TEST-INFO | started process 2206 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpPxuLOP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:10:49 INFO - PROCESS | 2206 | RSS: Main: 239677440 10:10:49 INFO - PROCESS | 2206 | 10:10:52 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52779/tests/kraken/driver.html?testName=audio-beat-detection) 10:10:53 INFO - PROCESS | 2206 | RSS: Main: 287432704 10:10:53 INFO - PROCESS | 2206 | 10:10:56 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52779/tests/kraken/driver.html?testName=audio-dft) 10:10:56 INFO - PROCESS | 2206 | RSS: Main: 295641088 10:10:56 INFO - PROCESS | 2206 | 10:11:00 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52779/tests/kraken/driver.html?testName=audio-fft) 10:11:00 INFO - PROCESS | 2206 | RSS: Main: 380452864 10:11:00 INFO - PROCESS | 2206 | 10:11:02 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52779/tests/kraken/driver.html?testName=audio-oscillator) 10:11:03 INFO - PROCESS | 2206 | RSS: Main: 279752704 10:11:03 INFO - PROCESS | 2206 | 10:11:05 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52779/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:11:06 INFO - PROCESS | 2206 | RSS: Main: 283979776 10:11:06 INFO - PROCESS | 2206 | 10:11:08 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52779/tests/kraken/driver.html?testName=imaging-darkroom) 10:11:09 INFO - PROCESS | 2206 | RSS: Main: 320659456 10:11:09 INFO - PROCESS | 2206 | 10:11:12 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52779/tests/kraken/driver.html?testName=imaging-desaturate) 10:11:13 INFO - PROCESS | 2206 | RSS: Main: 320897024 10:11:13 INFO - PROCESS | 2206 | 10:11:15 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52779/tests/kraken/driver.html?testName=json-parse-financial) 10:11:16 INFO - PROCESS | 2206 | RSS: Main: 320450560 10:11:16 INFO - PROCESS | 2206 | 10:11:18 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52779/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:11:18 INFO - PROCESS | 2206 | RSS: Main: 313094144 10:11:18 INFO - PROCESS | 2206 | 10:11:20 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:11:21 INFO - PROCESS | 2206 | RSS: Main: 320720896 10:11:21 INFO - PROCESS | 2206 | 10:11:23 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:11:24 INFO - PROCESS | 2206 | RSS: Main: 321900544 10:11:24 INFO - PROCESS | 2206 | 10:11:26 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:11:27 INFO - PROCESS | 2206 | RSS: Main: 327090176 10:11:27 INFO - PROCESS | 2206 | 10:11:30 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:11:31 INFO - PROCESS | 2206 | RSS: Main: 325656576 10:11:31 INFO - PROCESS | 2206 | 10:11:32 INFO - PROCESS | 2206 | Cycle 1(1): loaded http://localhost:52779/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52779/tests/kraken/driver.html?testName=ai-astar) 10:11:33 INFO - PROCESS | 2206 | RSS: Main: 391299072 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | __start_tp_report 10:11:33 INFO - PROCESS | 2206 | _x_x_mozilla_page_load 10:11:33 INFO - PROCESS | 2206 | _x_x_mozilla_page_load_details 10:11:33 INFO - PROCESS | 2206 | |i|pagename|runs| 10:11:33 INFO - PROCESS | 2206 | |0;ai-astar;113;112;108;112;112;113;112;113;106;113 10:11:33 INFO - PROCESS | 2206 | |1;audio-beat-detection;161;122;142;184;152;136;138;117;168;119 10:11:33 INFO - PROCESS | 2206 | |2;audio-dft;185;185;179;185;185;187;186;184;181;186 10:11:33 INFO - PROCESS | 2206 | |3;audio-fft;80;74;91;93;57;57;57;81;84;55 10:11:33 INFO - PROCESS | 2206 | |4;audio-oscillator;93;96;139;78;78;79;125;108;61;128 10:11:33 INFO - PROCESS | 2206 | |5;imaging-gaussian-blur;91;85;88;85;86;85;89;85;87;84 10:11:33 INFO - PROCESS | 2206 | |6;imaging-darkroom;92;91;94;92;91;93;92;93;99;92 10:11:33 INFO - PROCESS | 2206 | |7;imaging-desaturate;87;84;84;84;84;84;83;85;84;84 10:11:33 INFO - PROCESS | 2206 | |8;json-parse-financial;59;62;61;59;58;58;58;75;59;57 10:11:33 INFO - PROCESS | 2206 | |9;json-stringify-tinderbox;63;73;78;73;68;67;67;67;67;70 10:11:33 INFO - PROCESS | 2206 | |10;stanford-crypto-aes;83;83;83;89;85;90;83;82;82;84 10:11:33 INFO - PROCESS | 2206 | |11;stanford-crypto-ccm;149;153;141;136;136;136;135;134;179;164 10:11:33 INFO - PROCESS | 2206 | |12;stanford-crypto-pbkdf2;174;170;173;181;180;226;163;208;222;179 10:11:33 INFO - PROCESS | 2206 | |13;stanford-crypto-sha256-iterative;69;68;68;68;68;71;69;70;70;69 10:11:33 INFO - PROCESS | 2206 | __end_tp_report 10:11:33 INFO - PROCESS | 2206 | __start_cc_report 10:11:33 INFO - PROCESS | 2206 | _x_x_mozilla_cycle_collect,2028 10:11:33 INFO - PROCESS | 2206 | __end_cc_report 10:11:33 INFO - PROCESS | 2206 | __startTimestamp1462468293205__endTimestamp 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | ------- Summary: start ------- 10:11:33 INFO - PROCESS | 2206 | Number of tests: 14 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.50 stddev:2.41 (2.1%) stddev-sans-first:2.49 10:11:33 INFO - PROCESS | 2206 | Values: 113.0 112.0 108.0 112.0 112.0 113.0 112.0 113.0 106.0 113.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#1] audio-beat-detection Cycles:10 Average:143.90 Median:147.00 stddev:22.29 (15.2%) stddev-sans-first:22.77 10:11:33 INFO - PROCESS | 2206 | Values: 161.0 122.0 142.0 184.0 152.0 136.0 138.0 117.0 168.0 119.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.00 stddev:2.45 (1.3%) stddev-sans-first:2.59 10:11:33 INFO - PROCESS | 2206 | Values: 185.0 185.0 179.0 185.0 185.0 187.0 186.0 184.0 181.0 186.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#3] audio-fft Cycles:10 Average:72.90 Median:80.50 stddev:15.10 (18.8%) stddev-sans-first:15.79 10:11:33 INFO - PROCESS | 2206 | Values: 80.0 74.0 91.0 93.0 57.0 57.0 57.0 81.0 84.0 55.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#4] audio-oscillator Cycles:10 Average:98.50 Median:78.00 stddev:25.75 (33.0%) stddev-sans-first:27.23 10:11:33 INFO - PROCESS | 2206 | Values: 93.0 96.0 139.0 78.0 78.0 79.0 125.0 108.0 61.0 128.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:86.50 stddev:2.22 (2.6%) stddev-sans-first:1.66 10:11:33 INFO - PROCESS | 2206 | Values: 91.0 85.0 88.0 85.0 86.0 85.0 89.0 85.0 87.0 84.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:92.50 stddev:2.33 (2.5%) stddev-sans-first:2.45 10:11:33 INFO - PROCESS | 2206 | Values: 92.0 91.0 94.0 92.0 91.0 93.0 92.0 93.0 99.0 92.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:1.06 (1.3%) stddev-sans-first:0.50 10:11:33 INFO - PROCESS | 2206 | Values: 87.0 84.0 84.0 84.0 84.0 84.0 83.0 85.0 84.0 84.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:59.00 stddev:5.27 (8.9%) stddev-sans-first:5.56 10:11:33 INFO - PROCESS | 2206 | Values: 59.0 62.0 61.0 59.0 58.0 58.0 58.0 75.0 59.0 57.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:69.00 stddev:4.30 (6.2%) stddev-sans-first:3.91 10:11:33 INFO - PROCESS | 2206 | Values: 63.0 73.0 78.0 73.0 68.0 67.0 67.0 67.0 67.0 70.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.50 stddev:2.84 (3.4%) stddev-sans-first:2.96 10:11:33 INFO - PROCESS | 2206 | Values: 83.0 83.0 83.0 89.0 85.0 90.0 83.0 82.0 82.0 84.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:145.00 stddev:15.13 (10.4%) stddev-sans-first:16.02 10:11:33 INFO - PROCESS | 2206 | Values: 149.0 153.0 141.0 136.0 136.0 136.0 135.0 134.0 179.0 164.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.60 Median:180.50 stddev:22.52 (12.5%) stddev-sans-first:23.34 10:11:33 INFO - PROCESS | 2206 | Values: 174.0 170.0 173.0 181.0 180.0 226.0 163.0 208.0 222.0 179.0 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - PROCESS | 2206 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:1.05 (1.5%) stddev-sans-first:1.12 10:11:33 INFO - PROCESS | 2206 | Values: 69.0 68.0 68.0 68.0 68.0 71.0 69.0 70.0 70.0 69.0 10:11:33 INFO - PROCESS | 2206 | -------- Summary: end -------- 10:11:33 INFO - PROCESS | 2206 | 10:11:33 INFO - TEST-INFO | 2206: exit 0 10:11:34 INFO - TEST-OK | kraken | took 59125ms 10:11:34 INFO - SUITE-END | took 1173s 10:11:34 INFO - Completed test suite (00:19:34) 10:11:34 INFO - javascript benchmark 10:11:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3052.755779537637, 3359.9832000839992, 3383.6108847482546, 3416.1630400551867, 3444.7416443766724, 5432.13085906255, 5476.2333273341765, 5483.259759932408, 5508.283923089998, 5524.944750552493, 5498.505134537891, 5513.50378465938, 5615.326160860693, 5640.435956404358, 5661.943380566193, 5019.64862459628, 5022.096022715909, 5059.392872855244, 5061.16490779021, 5267.157254839227, 5167.767450464719, 5172.612054095939, 5180.507851754096, 5210.114280572312, 5212.400573933989, 4988.476209997971, 5026.371703537058, 5073.086844368012, 5128.923066153992, 5145.588352931794, 5060.797568097271, 5067.695938243717, 5106.157484015133, 5113.718745468997, 5126.487351264881, 5035.949640503622, 5043.571296439807, 5071.41678706946, 5083.974580127113, 5116.0, 5350.491703288181, 5381.67709937405, 5415.891682166374, 5427.864303392446, 5453.23654688344, 6054.667097769027, 6066.181955620074, 6074.145151778657, 6084.6349219047, 6096.1033937223965, 6097.146399504073, 6112.694365281718, 6134.754609815602, 6141.263048434172, 6141.447269745744, 5519.917201241985, 5603.271574695264, 5630.5214056805635, 5648.548116150698, 5661.320641523043, 6161.7227224775, 6166.568340216231, 6175.011998080286, 6179.28938172116, 6186.4741496972365, 5066.0, 5955.583109182402, 5971.731272092767, 6003.459688628044, 6012.579119461596, 5875.3243377012195, 6047.879042419215, 6085.391460853968, 6136.294326152461, 6147.200863887755, 6010.459058684739, 6063.848403789941, 6065.8180254592435, 6070.392960703983, 6124.265088189446, 6081.878362432815, 6086.208792856989, 6087.452129308295, 6100.176476175749, 6102.298235702951, 4720.575148236607, 4780.115678599401, 4786.712797232177, 4809.591184749265, 4810.855674329776, 4362.956370436318, 4364.934525982113, 4390.2317094508335, 4390.692651514363, 4399.868003959887, 5583.246261754693, 5586.413426590149, 5597.272354593877, 5610.803621873215, 5613.915791263134, 6048.274207095177, 6093.141993208895, 6093.413838573086, 6095.680160276974, 6121.540884433685, 5565.9443405566235, 5568.49883510486, 5586.13414920688, 5606.131049687305, 5607.8878422432135, 5200.375954885362, 5201.5838732901275, 5203.219517072469, 5207.531322181022, 5272.99813035522, 6105.732692890381, 6115.362036198677, 6150.261968563801, 6184.103305020747, 6184.319724830355, 6134.723937422737, 6138.7869253627705, 6164.630122192682, 6165.290991536031, 6177.907331390123, 6180.876382472416, 6192.690365481798, 6195.2255968004, 6199.504039676905, 6207.503399727921, 5864.6481211125765, 5959.4934430573885, 5985.191999080069, 5991.730372133177, 6075.908861367171, 6101.389861013863, 6105.328413874559, 6109.572329936862, 6132.233470816148, 6138.508919286357, 5916.49709774674, 5924.407559244041, 5929.495992840657, 5932.881342373042, 5945.524358051432, 5706.400827913009, 5738.626989245686, 5848.736806843617, 5956.285245770535, 5993.460588546877, 5682.6306290091015, 5719.3136823581435, 5740.741666624928, 5741.024025915521, 5767.048437007847, 5728.112142617901, 5897.734601942837, 5924.6445213285615, 5937.079752638348, 5952.494038006818, 5664.376918538875, 5720.771169153188, 5728.971355143198, 5740.1102829061565, 5750.022496175577], "value": 5557.842106493832, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.9452260251374, 498.14568015852814, 528.1285878300803, 537.8785232789634, 644.9903251451232, 29752.95864644738, 32390.866319678844, 38191.809040954824, 39040.60959390405, 39327.0, 36449.81775091127, 43240.13519729603, 44050.77974610122, 44091.77954110225, 44098.77950610242, 8609.48343099416, 9564.617415303379, 9797.804043919117, 9806.509674516246, 9814.754631134243, 8693.608787604573, 11365.147613928946, 11642.941785291061, 11650.067994560415, 11681.415929203547, 11476.598319058834, 11813.054955603575, 11826.467808948575, 11828.822567661491, 11835.93476587107, 8445.366597505212, 11410.657680269605, 11777.646670599854, 11905.047596192284, 11919.046476281877, 11396.886031139666, 11555.826662600068, 11568.01671857893, 11600.129990250765, 11604.419779011016, 33764.6623533764, 37491.25017499635, 39015.8049209755, 39056.0, 39097.60902390968, 3451.361498122855, 3537.0, 3593.281343731238, 3755.680767134797, 3963.6829053675638, 27455.725442745515, 29170.854145729347, 29571.556426653617, 29637.407251855057, 29674.851625741732, 2660.8004399670103, 2773.62556054932, 2775.417162395899, 2833.914982550506, 2931.9884639799448, 482523.0, 482523.0, 484007.0, 484759.0, 484983.99999999645, 59547.70226148841, 59867.40132598618, 59918.70040649856, 59950.40049599448, 60024.39975600188, 60932.781344373754, 61151.69424152851, 61171.882811718475, 61192.38807611956, 61280.0, 14390.42438302477, 15851.207439627973, 16006.919965400331, 16010.279537420844, 16012.839871601134, 11679.532818687327, 11930.642080737593, 12283.034714445646, 12557.434915428828, 12607.558735444216, 14931.954763166474, 15162.166080865438, 15178.620534486505, 15191.012584181994, 15205.9355845092, 4584.005896787539, 7132.964335178291, 7344.816379590447, 7399.149097853816, 7427.554346739213, 872.5549969515592, 975.8290051937703, 1032.6282538286212, 1033.1476531861244, 1073.8281874900135, 25392.984280628938, 28116.71883281182, 28290.71709282922, 28444.715552844205, 28854.278643034093, 1097.412884106999, 1098.6594155811724, 1100.3342977498658, 1119.9832002519968, 1170.795110855597, 13847.438223928606, 14799.0, 15126.848731512764, 15262.16058116792, 15433.614159646098, 456607.0, 593571.0, 600082.0, 601076.0, 603610.0, 58986.0, 59278.70360648255, 59833.0, 59951.10073348903, 60029.69985150133, 1355.5391167003265, 1369.6918193406404, 1477.8226612806532, 1494.2379386512957, 1512.7428337182705, 24071.398215044763, 26944.461110778066, 28481.572776408786, 29347.70652293535, 29392.11823645294, 38474.422883657324, 50333.49666503288, 50345.46199918079, 50375.992480150926, 50415.49584504108, 402330.0, 418824.0, 418884.0, 419426.0, 420645.0, 30466.84766576153, 30557.694423055484, 34211.30112026539, 39243.01892452712, 39896.0, 41216.96957576084, 41501.58498414977, 43186.136277274905, 43690.56309436865, 45769.54230457653, 33246.0, 35948.4607730879, 36929.63070369369, 38008.239835202585, 38031.23937521289, 36129.63870361368, 36319.63680363268, 36941.63058369382, 37075.81462092672, 37120.257594848496, 36149.0, 36253.27493450169, 36305.81847090748, 36568.45147322739, 36935.815320923226, 31758.52362214615, 32311.51532727058, 32397.838010809795, 32417.35165296728, 32486.862959796294, 23651.64522532198, 23793.881030594737, 24028.51942961166, 24050.87974560116, 24317.027318907047], "value": 19642.37147577426, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.10282709662613, 400.8476778824045, 401.53823103431034, 403.9616236457537, 414.4922469974282, 14843.332050057747, 20013.899930500345, 20706.171753129875, 20734.688979665298, 20849.895750521227, 18970.05149742511, 19031.42905712831, 19103.0, 19805.504862378446, 20310.390688279374, 5267.710275934822, 5625.971870140654, 6062.0, 6064.302605200414, 6069.544784141185, 5691.601587888849, 5772.826815195551, 6023.397660233985, 6052.061930400795, 6064.605800622968, 3904.2191561687778, 4134.049168691203, 5809.477147056764, 6116.143739876421, 6116.724747386378, 5883.617564858313, 6093.482054025413, 6104.389561043882, 6114.205153330083, 6249.468795152417, 5900.822975310747, 5918.644881307092, 5937.346891841894, 5943.583949123563, 5955.166276721263, 13137.671558211026, 13564.253966031863, 13573.59279221625, 13587.980901432433, 22997.770022299897, 2164.5779073080757, 2176.32201579332, 2260.8869556522272, 2262.739784924722, 2281.53228588139, 9265.212456941168, 9858.915519292872, 10038.0, 10080.294379393445, 10194.388336699822, 1598.4964736108088, 1686.966260674792, 1713.9485815425558, 1748.5104170832187, 1779.884307520007, 115246.0, 115587.42206289, 116333.41833290864, 117223.41388293004, 117359.41320293429, 11326.943365283121, 11374.374409407397, 11424.714382140346, 11470.655880323604, 11627.302361858316, 14780.778288325684, 14876.181810000337, 14923.925380373028, 14926.328315225841, 14966.551003469913, 8327.958360208282, 8516.957415212884, 8528.189821966904, 8610.354223433267, 8654.22112009922, 8411.443882881686, 8411.45325553837, 8465.492070475791, 8563.886694729645, 8586.95706521476, 4431.423914891044, 4510.706804057726, 4521.321801729701, 4580.198465268565, 4586.174488592019, 1784.3843873863495, 1900.451132327149, 1969.9704504432445, 1971.1819594867968, 1972.654785412547, 767.5087943716027, 856.4005196362447, 980.3823591137538, 988.836841921075, 1038.7039693687263, 15283.694326113415, 15655.530334089995, 17667.911660441874, 19352.0, 19592.510187245432, 931.7065124485765, 950.5104870991445, 977.3012296208245, 980.2550061952825, 993.4883534979491, 10144.898551014396, 10496.895031049591, 10516.053555180071, 10534.57861685524, 10542.367457952547, 571161.0, 571183.0, 587280.0, 588058.0, 588165.0591747013, 109342.0, 133027.33486332506, 137909.0, 138132.0, 139474.3026284862, 1013.5996281468779, 1035.13566172247, 1049.3703777733451, 1051.348164138239, 1055.287680815462, 13740.359064100343, 17594.29622815073, 17936.641267174844, 17979.10104494796, 18107.279808418705, 51050.48949510457, 52268.47731522636, 53122.0, 53496.73251633717, 54473.72763136159, 76875.23124768681, 80340.59829700814, 81137.0, 81479.59260203662, 82370.29531841411, 8033.959830200812, 8179.83640327202, 8332.791680208044, 8506.191911768372, 8636.827263454821, 10170.338927969658, 12398.0, 12497.687557810763, 12808.935955320163, 12865.549705760253, 5504.22940788282, 8126.512409255463, 8614.698485553226, 10053.949730251303, 10823.621173258898, 9599.42403455795, 10212.387256764618, 10929.617463388744, 10948.124150067817, 11436.828447573127, 11304.699959504762, 11462.656120316404, 11532.192746507999, 11582.594609188638, 11826.645200643994, 8147.918520814953, 8255.174482551696, 8262.0, 8450.323974081935, 8471.61877715492, 9647.421154730739, 11282.379469129277, 11341.829872551752, 11555.711107222387, 11712.355820429959], "value": 9277.433476853326, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.423922550592, 1623.1072909899551, 1646.0370683150356, 1933.1204302042572, 2044.7035179898915, 7839.608019599019, 8646.221840034395, 8852.8672069919, 8855.690050848221, 8883.95558022209, 8439.662413503467, 8510.48937063775, 8682.305415566756, 8685.0, 8696.869546956785, 2157.4714195022243, 2160.7407111146686, 2387.641853721947, 2468.5556599812026, 2468.7654672806057, 2468.7531246875346, 2490.2653717153485, 2494.1270555305678, 2494.900203991838, 2495.625656151574, 2403.495265994143, 2456.4350199454047, 2458.0, 2458.606622940321, 2460.0774709483944, 2130.265058554804, 2364.6334818103223, 2426.4176597616533, 2435.2207293666033, 2437.756224377566, 2103.5267064910427, 2421.1525965911965, 2443.218170185541, 2444.9388765280833, 2456.980353153439, 8577.828443431097, 8631.784205394915, 8651.437656552303, 8659.826803463895, 8672.349573781928, 5068.113080210986, 5092.312537807423, 5268.789248430058, 5307.203919412081, 5312.335958005249, 3477.6870081692514, 3549.716022718202, 3618.077390265492, 3618.6019537850816, 3623.565172179329, 1037.723599972031, 1040.6097713357492, 1041.3023274406166, 1045.3362115056993, 1058.8941105889426, 11530.250533715282, 11535.59625413115, 11546.134039946954, 11553.711157221052, 11562.653120406401, 4697.0840686065885, 4738.881527961828, 4781.856544303677, 4832.226843705061, 4846.5153484651255, 9726.70819875405, 9842.852357214648, 9853.359531630422, 9871.160951319074, 9873.703788886345, 5358.812441564604, 5364.6512976656395, 5370.597205209625, 5378.462153784591, 5390.946090539122, 4206.810693518799, 4372.8906777330185, 4451.287793952952, 4508.165989291926, 4536.138133754576, 3906.4140378943384, 3939.527256729211, 3998.260321840469, 4027.637512623878, 4035.4955630546183, 1170.0464121740754, 1290.4644572502307, 1298.9283840831163, 1320.8348956380453, 1346.4614154338342, 908.3459908865606, 929.6606738540519, 940.1632547033009, 946.5503885654397, 982.1210017034714, 5764.538836893039, 5782.6819524925695, 5888.381719919433, 5928.347881733006, 6034.517238620988, 435.98692039238875, 457.27065330797325, 457.79399270328486, 459.2239115894128, 474.4377912174016, 3815.4467602197524, 3853.4412510185866, 3918.686505079587, 3920.4903362563255, 3946.4869566956113, 10056.29605927578, 10477.31897426665, 10570.260081794355, 10665.36007839532, 10671.0, 10483.266171367932, 10614.681559553226, 10629.30909490896, 10632.840507392395, 10638.521266543024, 8058.234467725557, 8075.434719569691, 8093.3929955252415, 8116.066652335057, 8230.0, 5660.490555849993, 5682.431756824284, 5744.6265992712015, 5771.0, 5787.8553036174435, 9421.434713917188, 9883.357581757164, 10004.199664027006, 10010.299279050396, 10013.549390277602, 7751.806204844924, 9999.0, 10004.599816007569, 10084.344517606332, 10118.19054475655, 5933.517051326287, 6797.014432907102, 6916.688749006407, 7298.6715597797165, 7388.926110738824, 8021.879671804811, 8068.112507624274, 8163.0, 8166.836663266583, 8170.01959764832, 7094.893576596459, 7134.536255143399, 7425.108986921604, 7428.73999410018, 7445.143808462096, 7145.535540189871, 7188.5327453715345, 7207.387372073223, 7283.453740969342, 7328.450366222662, 5948.405159484017, 6108.144859719554, 6262.34245404226, 6288.6855657217875, 6300.464460520907, 4879.853604391874, 4913.582345500672, 4916.213405855046, 4918.827841025547, 5010.824621138243, 4920.384951881015, 4955.785832471068, 4996.400431948189, 5124.666896651705, 5131.589472842089], "value": 4464.9248700632925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.4322338838067, 317.2212219002349, 323.1710662151581, 323.58419431031115, 329.3051660995298, 4540.432445944257, 4920.360353154094, 5295.973520132398, 5319.148936170213, 5342.412334643196, 4780.880477988052, 5206.843794686156, 5244.875825290764, 5252.52727254547, 5266.947330526694, 3191.5212718092357, 3516.349475347069, 4010.3984402339593, 4095.4061661059127, 4101.056756945904, 3701.2597480503873, 3859.4017927221325, 3974.9006274843214, 4052.9797351013203, 4110.362893751473, 4027.738197017199, 4035.455213546163, 4042.575529569382, 4054.493188351456, 4072.5586879087273, 3617.421212605984, 3925.9803700981597, 3947.2894878921784, 3955.881323560312, 4104.527979282361, 3507.2459421224303, 3538.752287339887, 3552.1281802834774, 3554.1647712787476, 3584.318979393907, 4116.629503344713, 4503.919059425747, 4989.627852340599, 5014.0, 5050.772715227824, 3874.9000799360397, 4060.675145988314, 4169.6038876307, 4549.338519107819, 4655.185342565072, 5504.366997795265, 5524.171374293848, 5526.336839579236, 5533.170024496318, 5545.343749375615, 3483.4252348362743, 3507.9829098268597, 3908.537621298592, 3933.8591808375536, 3938.468306778577, 5014.671112155282, 5270.39390470097, 5307.28351672527, 5504.669719816784, 5840.912386314209, 3103.9912028590797, 3170.7621928355466, 3983.143624120828, 3995.182192102586, 4005.2990726622725, 3438.484227365865, 3860.2665493556133, 4051.2505186540584, 4054.3715724062818, 4064.9593504065174, 4637.234856248681, 4646.233371493666, 4655.441347038377, 4655.974651026814, 4661.766911654471, 4859.975700121477, 5241.31862857826, 5538.252335934597, 5622.9916994407795, 5662.0, 1412.8304603447652, 1436.1526699247495, 1444.104655113836, 1444.227338373986, 1444.7182799354234, 827.3381294964125, 915.4095608332722, 945.2863088368375, 956.4739393333639, 959.3236768078488, 5064.265681476165, 5097.64316497849, 5113.488651134857, 5201.973990130101, 5279.275048831275, 5019.870529130916, 5435.130379139318, 5709.74306156224, 5751.913721294184, 5771.480566748929, 4481.910361792746, 4992.701897506602, 5129.179331306973, 5247.816326428557, 5268.894622107537, 3253.235489659928, 3279.2457734721147, 3287.161773747727, 3299.290652509722, 3332.683395077464, 5220.086484865134, 6252.374762523802, 6517.739290428424, 6536.281009088996, 6601.801945941629, 4882.584980276716, 6461.935380646228, 6464.676766161673, 6488.967555162194, 6491.188601424822, 6567.0, 6588.53880228389, 6595.274519802914, 6601.933980660132, 6621.768238111643, 4620.514845941262, 5849.824505264849, 5851.664551205772, 6286.748530058747, 6297.275813281532, 5164.431912489548, 5468.484497131364, 5716.79991200306, 5766.107877182969, 5777.74000169985, 4971.552560269593, 5305.949079279352, 5939.881202376015, 6075.605085669417, 6122.479589234965, 3301.4882693182594, 3763.943540846835, 3898.128898128857, 3900.8439662413184, 3905.6484916357663, 5232.476752324737, 5289.6032797539565, 5350.545203657733, 5594.188842617717, 5798.82603521895, 5503.587230957774, 5671.206031155559, 5684.147377893349, 5699.629524080921, 5717.485426311652, 4606.170889239969, 4845.466998630078, 5592.608517403743, 5603.545181580572, 5614.494695477427, 4764.356811830358, 5470.480304371078, 5477.917831232615, 5510.228568000563, 5526.336839579116, 4236.661067114562, 5262.9210561842365, 5334.253204551288, 5381.865453363697, 5435.374931882884, 4610.884727881696, 4892.779824907817, 5261.973690131525, 5473.8357849264585, 5506.889862202814], "value": 4122.681625214758, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.6120089147403, 680.3910500102409, 727.9126504819423, 745.772539375491, 807.4509333653117, 11153.330800151987, 13910.30448477576, 14691.33888974996, 15059.472918447857, 15148.924255378708, 13679.384427700752, 13970.511032113878, 14011.299435028233, 14042.087264327836, 14375.281235938188, 728.624758249502, 923.1276443760648, 941.5292353823088, 943.2689665509216, 945.3854994253721, 754.4417131322837, 902.7517432706026, 943.485800238868, 944.6079876851098, 945.3524335829944, 940.4921342475055, 963.0658261486348, 993.4287784523891, 994.2890833054365, 996.4170959988379, 751.9661615227328, 948.1087777489138, 954.4654993203793, 983.1839573154265, 985.3447457440793, 730.7369347035063, 732.5458215906173, 735.9411247100219, 736.5801493148907, 738.9889151662676, 15220.847791522165, 15369.077855328716, 15390.0, 15402.614934626612, 15418.53744387659, 12678.619641410773, 13111.494430000956, 13137.014723895745, 13145.73708525824, 13471.191728496224, 9935.90064099357, 10082.747931301703, 10279.691609251735, 10314.329568578018, 10493.63272285474, 3963.088200873495, 4048.5748996355546, 4064.410660454247, 4184.121334519755, 4294.7852607369505, 59593.8487381517, 64606.3539364605, 71210.64394678046, 72561.27438725598, 72960.7983321413, 9290.0, 9352.971173170945, 9408.12041123285, 9534.713958581253, 9580.952095239618, 21063.8946805265, 21065.0, 21079.789202108088, 21080.578388432452, 21109.155633774782, 7224.988501609779, 7286.23494533077, 7298.235103222704, 7356.816079598053, 7371.594562299125, 3941.684665226775, 4008.7795171265275, 4013.5585085640555, 4017.13631569214, 4017.4978127734034, 3595.9101022474124, 3662.212624285791, 3669.4679271506016, 3679.632036796299, 3679.8896033118513, 601.7623038899698, 603.2037710222552, 608.8782243551308, 624.1698540940629, 625.5402279324693, 514.3647595219901, 514.4546780412775, 582.4641329976432, 585.3268740947859, 590.498076634857, 7250.2024777274455, 7565.319121279112, 7569.356604688553, 7770.572618506037, 7811.687532498637, 586.3315819965243, 612.5803824380314, 616.4328817487922, 619.8977168767103, 625.2621906150695, 3736.23407201523, 3745.5318085239346, 3894.941575876364, 3943.4084887266567, 4117.197146556393, 80202.19797802065, 82651.76022359669, 82905.58547207226, 83465.0, 83663.0, 66745.0, 69644.0, 70181.0, 71984.0, 75008.62495687486, 49672.0, 50383.24425133626, 65615.35961600902, 65977.67011164913, 69096.30903690899, 6841.555298905555, 7610.315071643578, 7790.805229869298, 7814.609269536387, 8330.625121869409, 34192.46133924029, 42185.94535136523, 43919.12161756811, 45190.0, 45401.31898021598, 62119.6894015527, 62972.68513657402, 63060.68469657806, 63368.36631633624, 63467.73064538775, 11060.557577696802, 11213.635909227361, 11228.045616122394, 11247.718807029889, 11316.321020738782, 11767.461096522398, 11804.940975295069, 11902.285862848257, 11987.520499179935, 12351.814722778985, 11332.716682083013, 11340.6030788922, 11491.195616306779, 11508.654740357802, 11962.0, 11275.210735248455, 11353.641960502146, 11411.486483108447, 11435.0, 11701.531938722695, 11086.667399978014, 11107.833382499108, 11233.943830280796, 11263.493142808757, 11417.200795944203, 9465.621375144918, 9630.422174669542, 9826.803463930824, 9867.452865247762, 10092.899071009197, 9366.382942651706, 9646.855297170689, 9718.368306060367, 9732.94715274918, 9741.123298903132], "value": 6765.410766952137, "unit": "score"}], "name": "dromaeo_css", "value": 7081.800146671405, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 108.0, 112.0, 112.0, 113.0, 112.0, 113.0, 106.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 122.0, 142.0, 184.0, 152.0, 136.0, 138.0, 117.0, 168.0, 119.0], "value": 143.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 179.0, 185.0, 185.0, 187.0, 186.0, 184.0, 181.0, 186.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 91.0, 93.0, 57.0, 57.0, 57.0, 81.0, 84.0, 55.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 139.0, 78.0, 78.0, 79.0, 125.0, 108.0, 61.0, 128.0], "value": 98.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 88.0, 85.0, 86.0, 85.0, 89.0, 85.0, 87.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 94.0, 92.0, 91.0, 93.0, 92.0, 93.0, 99.0, 92.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 84.0, 84.0, 84.0, 83.0, 85.0, 84.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 61.0, 59.0, 58.0, 58.0, 58.0, 75.0, 59.0, 57.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 73.0, 78.0, 73.0, 68.0, 67.0, 67.0, 67.0, 67.0, 70.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 89.0, 85.0, 90.0, 83.0, 82.0, 82.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 153.0, 141.0, 136.0, 136.0, 136.0, 135.0, 134.0, 179.0, 164.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 170.0, 173.0, 181.0, 180.0, 226.0, 163.0, 208.0, 222.0, 179.0], "value": 187.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 68.0, 68.0, 71.0, 69.0, 70.0, 70.0, 69.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1491.8999999999999, "alertThreshold": 2.0}]} 10:11:34 INFO - Return code: 0 10:11:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:11:34 ERROR - # TBPL SUCCESS # 10:11:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:11:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:11:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:11:34 INFO - 2016-05-05 10:11:34.536 screenresolution[2210:17355] starting screenresolution argv=screenresolution get 10:11:34 INFO - 2016-05-05 10:11:34.538 screenresolution[2210:17355] Display 0: 1600x1200x32@60 10:11:34 INFO - 2016-05-05 10:11:34.544 screenresolution[2211:17358] starting screenresolution argv=screenresolution list 10:11:34 INFO - Available Modes on Display 0 10:11:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:11:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:11:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:11:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:11:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:11:34 INFO - Intel Iris: 10:11:34 INFO - Chipset Model: Intel Iris 10:11:34 INFO - Type: GPU 10:11:34 INFO - Bus: Built-In 10:11:34 INFO - VRAM (Dynamic, Max): 1536 MB 10:11:34 INFO - Vendor: Intel (0x8086) 10:11:34 INFO - Device ID: 0x0a2e 10:11:34 INFO - Revision ID: 0x0009 10:11:34 INFO - Displays: 10:11:34 INFO - Display: 10:11:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:11:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:11:34 INFO - Display Serial Number: 1600x1200 60 10:11:34 INFO - Main Display: Yes 10:11:34 INFO - Mirror: Off 10:11:34 INFO - Online: Yes 10:11:34 INFO - Rotation: Supported 10:11:34 INFO - Return code: 0 10:11:34 INFO - Running post-action listener: _resource_record_post_action 10:11:34 INFO - Running post-run listener: _resource_record_post_run 10:11:35 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 745472; Write bytes: 695211008; Read time: 9; Write time: 2156 10:11:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 386585088; Write bytes: 381089792; Read time: 28416; Write time: 901 10:11:35 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 745472; Write bytes: 294534144; Read time: 9; Write time: 1215 10:11:35 INFO - Running post-run listener: _upload_blobber_files 10:11:35 INFO - Blob upload gear active. 10:11:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:11:35 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 10:11:35 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'] 10:11:35 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 10:11:36 INFO - (blobuploader) - INFO - Open directory for files ... 10:11:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:11:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:11:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:36 INFO - (blobuploader) - INFO - Done attempting. 10:11:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:11:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:11:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:11:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:11:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:11:37 INFO - (blobuploader) - INFO - Done attempting. 10:11:37 INFO - (blobuploader) - INFO - Iteration through files over. 10:11:37 INFO - Return code: 0 10:11:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:11:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:11:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88af7ee79d6801faeb71ae8711e7c496ab2ca1d3687d57c18f7ef707b52b0363433e099f9a9c620707b470dfa57e4efe51a795dc013c125a056eede90e3b3a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707e0671d7cdb805ccf8f33587480ee4c46296cb15e7cbce6699c6f1653884fbb78cd41f3bf9521c17e17ddf636127f25f239b41e2002fc7fa4d653a21e32988"} 10:11:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:11:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:11:37 INFO - Contents: 10:11:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88af7ee79d6801faeb71ae8711e7c496ab2ca1d3687d57c18f7ef707b52b0363433e099f9a9c620707b470dfa57e4efe51a795dc013c125a056eede90e3b3a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707e0671d7cdb805ccf8f33587480ee4c46296cb15e7cbce6699c6f1653884fbb78cd41f3bf9521c17e17ddf636127f25f239b41e2002fc7fa4d653a21e32988"} 10:11:37 INFO - Running post-run listener: copy_logs_to_upload_dir 10:11:37 INFO - Copying logs to upload dir... 10:11:37 INFO - mkdir: /builds/slave/test/build/upload/logs 10:11:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.742166 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-05 10:11:38.021677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 10:11:38.025425) ========= 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.g0uqDJJObY/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.tI5DF0rmcf/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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/88af7ee79d6801faeb71ae8711e7c496ab2ca1d3687d57c18f7ef707b52b0363433e099f9a9c620707b470dfa57e4efe51a795dc013c125a056eede90e3b3a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707e0671d7cdb805ccf8f33587480ee4c46296cb15e7cbce6699c6f1653884fbb78cd41f3bf9521c17e17ddf636127f25f239b41e2002fc7fa4d653a21e32988"} build_url:https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011172 build_url: 'https://queue.taskcluster.net/v1/task/cA1h-gvfQ1CVwzLHW6IY7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88af7ee79d6801faeb71ae8711e7c496ab2ca1d3687d57c18f7ef707b52b0363433e099f9a9c620707b470dfa57e4efe51a795dc013c125a056eede90e3b3a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/707e0671d7cdb805ccf8f33587480ee4c46296cb15e7cbce6699c6f1653884fbb78cd41f3bf9521c17e17ddf636127f25f239b41e2002fc7fa4d653a21e32988"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 10:11:38.054602) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 10:11:38.055035) ========= 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.g0uqDJJObY/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.tI5DF0rmcf/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 10:11:38.104210) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 10:11:38.104584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 10:11:38.104939) ========= ========= Total master_lag: 0.17 =========