builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0182 starttime: 1462838896.74 results: success (0) buildid: 20160509151608 builduid: fb9008fecaf946f99b5b59751abd7be3 revision: 687f1eb881d82a76ddc02e71c1778c82de125407 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.741592) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.742097) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.742419) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.761355) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.761621) ========= 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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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-09 17:08:16-- 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% 38.5M=0s 2016-05-09 17:08:16 (38.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095808 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.874473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.874777) ========= 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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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.027579 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:16.922344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 17:08:16.922641) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 687f1eb881d82a76ddc02e71c1778c82de125407 --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 687f1eb881d82a76ddc02e71c1778c82de125407 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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-09 17:08:16,995 truncating revision to first 12 chars 2016-05-09 17:08:16,995 Setting DEBUG logging. 2016-05-09 17:08:16,995 attempt 1/10 2016-05-09 17:08:16,995 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/687f1eb881d8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 17:08:18,068 unpacking tar archive at: fx-team-687f1eb881d8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 17:08:18.253993) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:18.254328) ========= script_repo_revision: 687f1eb881d82a76ddc02e71c1778c82de125407 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:18.254700) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:18.254965) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 17:08:18.267545) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-09 17:08:18.267822) ========= /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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:08:18 INFO - MultiFileLogger online at 20160509 17:08:18 in /builds/slave/test 17:08:18 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 17:08:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:08:18 INFO - {'append_to_log': False, 17:08:18 INFO - 'base_work_dir': '/builds/slave/test', 17:08:18 INFO - 'blob_upload_branch': 'Fx-Team', 17:08:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:08:18 INFO - 'branch': 'Fx-Team', 17:08:18 INFO - 'buildbot_json_path': 'buildprops.json', 17:08:18 INFO - 'config_files': ('talos/mac_config.py',), 17:08:18 INFO - 'default_actions': ('clobber', 17:08:18 INFO - 'read-buildbot-config', 17:08:18 INFO - 'download-and-extract', 17:08:18 INFO - 'populate-webroot', 17:08:18 INFO - 'create-virtualenv', 17:08:18 INFO - 'install', 17:08:18 INFO - 'run-tests'), 17:08:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:08:18 INFO - 'download_minidump_stackwalk': True, 17:08:18 INFO - 'download_symbols': 'ondemand', 17:08:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:08:18 INFO - 'tooltool.py': '/tools/tooltool.py', 17:08:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:08:18 INFO - '/tools/misc-python/virtualenv.py')}, 17:08:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:08:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:08:18 INFO - 'installer_path': 'installer.exe', 17:08:18 INFO - 'log_level': 'info', 17:08:18 INFO - 'log_name': 'talos', 17:08:18 INFO - 'log_to_console': True, 17:08:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:08:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:08:18 INFO - 'opt_config_files': (), 17:08:18 INFO - 'pip_index': False, 17:08:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:08:18 INFO - 'cmd': ('bash', 17:08:18 INFO - '-c', 17:08:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:08:18 INFO - 'enabled': True, 17:08:18 INFO - 'halt_on_failure': False, 17:08:18 INFO - 'name': 'check_screen_resolution'},), 17:08:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:08:18 INFO - 'cmd': ('bash', 17:08:18 INFO - '-c', 17:08:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:08:18 INFO - 'enabled': True, 17:08:18 INFO - 'halt_on_failure': False, 17:08:18 INFO - 'name': 'check_screen_resolution'},), 17:08:18 INFO - 'run_cmd_checks_enabled': True, 17:08:18 INFO - 'sps_profile': False, 17:08:18 INFO - 'sps_profile_interval': 0, 17:08:18 INFO - 'suite': 'dromaeojs', 17:08:18 INFO - 'system_bits': '32', 17:08:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:08:18 INFO - 'title': 't-yosemite-r7-0182', 17:08:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:08:18 INFO - 'use_talos_json': True, 17:08:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:08:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:08:18 INFO - 'work_dir': 'build'} 17:08:18 INFO - ##### 17:08:18 INFO - ##### Running clobber step. 17:08:18 INFO - ##### 17:08:18 INFO - Running pre-action listener: _resource_record_pre_action 17:08:18 INFO - Running main action method: clobber 17:08:18 INFO - rmtree: /builds/slave/test/build 17:08:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:08:20 INFO - Running post-action listener: _resource_record_post_action 17:08:20 INFO - ##### 17:08:20 INFO - ##### Finished clobber step (success) 17:08:20 INFO - ##### 17:08:20 INFO - ##### 17:08:20 INFO - ##### Running read-buildbot-config step. 17:08:20 INFO - ##### 17:08:20 INFO - Running pre-action listener: _resource_record_pre_action 17:08:20 INFO - Running main action method: read_buildbot_config 17:08:20 INFO - Using buildbot properties: 17:08:20 INFO - { 17:08:20 INFO - "project": "", 17:08:20 INFO - "product": "firefox", 17:08:20 INFO - "script_repo_revision": "production", 17:08:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:08:20 INFO - "repository": "", 17:08:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:08:20 INFO - "buildid": "20160509151608", 17:08:20 INFO - "pgo_build": "False", 17:08:20 INFO - "basedir": "/builds/slave/test", 17:08:20 INFO - "buildnumber": 3, 17:08:20 INFO - "slavename": "t-yosemite-r7-0182", 17:08:20 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:08:20 INFO - "platform": "yosemite_r7", 17:08:20 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:08:20 INFO - "branch": "fx-team", 17:08:20 INFO - "repo_path": "integration/fx-team", 17:08:20 INFO - "revision": "687f1eb881d82a76ddc02e71c1778c82de125407", 17:08:20 INFO - "stage_platform": "macosx64", 17:08:20 INFO - "builduid": "fb9008fecaf946f99b5b59751abd7be3", 17:08:20 INFO - "slavebuilddir": "test" 17:08:20 INFO - } 17:08:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:08:20 INFO - Running post-action listener: _resource_record_post_action 17:08:20 INFO - ##### 17:08:20 INFO - ##### Finished read-buildbot-config step (success) 17:08:20 INFO - ##### 17:08:20 INFO - ##### 17:08:20 INFO - ##### Running download-and-extract step. 17:08:20 INFO - ##### 17:08:20 INFO - Running pre-action listener: _resource_record_pre_action 17:08:20 INFO - Running main action method: download_and_extract 17:08:20 INFO - mkdir: /builds/slave/test/build/tests 17:08: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')]} 17:08:20 INFO - https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:08:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:08:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:08:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:25 INFO - Downloaded 1199 bytes. 17:08:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:08:25 INFO - Using the following test package requirements: 17:08:25 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:08:25 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:08:25 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:08:25 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'jsshell-mac.zip'], 17:08:25 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:08:25 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:08:25 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:08:25 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:08:25 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:08:25 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:08:25 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:08:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:08:25 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')]} 17:08:25 INFO - https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:08:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:08:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:08:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:29 INFO - Downloaded 18146584 bytes. 17:08:29 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'] 17:08:29 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 17:08:32 INFO - Return code: 0 17:08:32 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 17:08: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')]} 17:08:32 INFO - https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:08:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:08:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:08:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:33 INFO - Downloaded 18146584 bytes. 17:08:33 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'] 17:08:33 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 17:08:37 INFO - Return code: 0 17:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:37 INFO - https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:08:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:08:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:08:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:38 INFO - Downloaded 10840152 bytes. 17:08:38 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'] 17:08:38 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 17:08:38 INFO - Return code: 0 17:08:38 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')]} 17:08:38 INFO - https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:08:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:08:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:08:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:08:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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 17:08:42 INFO - Downloaded 110419471 bytes. 17:08:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:08:42 INFO - mkdir: /builds/slave/test/properties 17:08:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:08:42 INFO - Writing to file /builds/slave/test/properties/build_url 17:08:42 INFO - Contents: 17:08:42 INFO - build_url:https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:08:43 INFO - Running post-action listener: _resource_record_post_action 17:08:43 INFO - Running post-action listener: set_extra_try_arguments 17:08:43 INFO - ##### 17:08:43 INFO - ##### Finished download-and-extract step (success) 17:08:43 INFO - ##### 17:08:43 INFO - ##### 17:08:43 INFO - ##### Running populate-webroot step. 17:08:43 INFO - ##### 17:08:43 INFO - Running pre-action listener: _resource_record_pre_action 17:08:43 INFO - Running main action method: populate_webroot 17:08:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:08:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:08:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:08:43 INFO - '--tppagecycles', 17:08:43 INFO - '7'], 17:08:43 INFO - 'tests': ['tsvgm']}}, 17:08:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:08:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:08:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:08:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:08:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:08:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:08:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['damp', 'tps']}, 17:08:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'tests': ['damp', 'tps']}, 17:08:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['dromaeo_dom']}, 17:08:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:08:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['a11yr', 17:08:43 INFO - 'ts_paint', 17:08:43 INFO - 'tpaint', 17:08:43 INFO - 'sessionrestore', 17:08:43 INFO - 'sessionrestore_no_auto_restore', 17:08:43 INFO - 'tabpaint']}, 17:08:43 INFO - 'other-e10s': {'tests': ['a11yr', 17:08:43 INFO - 'ts_paint', 17:08:43 INFO - 'tpaint', 17:08:43 INFO - 'sessionrestore', 17:08:43 INFO - 'sessionrestore_no_auto_restore', 17:08:43 INFO - 'tabpaint']}, 17:08:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:08:43 INFO - 'ts_paint', 17:08:43 INFO - 'tpaint', 17:08:43 INFO - 'sessionrestore', 17:08:43 INFO - 'sessionrestore_no_auto_restore', 17:08:43 INFO - 'tabpaint']}, 17:08:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:08:43 INFO - 'ts_paint', 17:08:43 INFO - 'tpaint', 17:08:43 INFO - 'sessionrestore', 17:08:43 INFO - 'sessionrestore_no_auto_restore', 17:08:43 INFO - 'tabpaint']}, 17:08:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['a11yr', 17:08:43 INFO - 'ts_paint', 17:08:43 INFO - 'tpaint', 17:08:43 INFO - 'sessionrestore', 17:08:43 INFO - 'sessionrestore_no_auto_restore', 17:08:43 INFO - 'tabpaint']}, 17:08:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['a11yr', 17:08:43 INFO - 'ts_paint', 17:08:43 INFO - 'tpaint', 17:08:43 INFO - 'sessionrestore', 17:08:43 INFO - 'sessionrestore_no_auto_restore', 17:08:43 INFO - 'tabpaint']}, 17:08:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['tsvgx', 17:08:43 INFO - 'tsvgr_opacity', 17:08:43 INFO - 'tart', 17:08:43 INFO - 'tscrollx', 17:08:43 INFO - 'cart']}, 17:08:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:08:43 INFO - 'tsvgr_opacity', 17:08:43 INFO - 'tart', 17:08:43 INFO - 'tscrollx', 17:08:43 INFO - 'cart']}, 17:08:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'talos_options': ['--disable-e10s'], 17:08:43 INFO - 'tests': ['tp5o']}, 17:08:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'tests': ['tp5o']}, 17:08:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'talos_options': ['--disable-e10s', 17:08:43 INFO - '--xperf_path', 17:08:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:08:43 INFO - 'tests': ['tp5n']}, 17:08:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:08:43 INFO - 'talos_options': ['--xperf_path', 17:08:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:08:43 INFO - 'tests': ['tp5n']}}, 17:08:43 INFO - 'talos.zip': {'path': '', 17:08:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:08:43 INFO - Running post-action listener: _resource_record_post_action 17:08:43 INFO - ##### 17:08:43 INFO - ##### Finished populate-webroot step (success) 17:08:43 INFO - ##### 17:08:43 INFO - ##### 17:08:43 INFO - ##### Running create-virtualenv step. 17:08:43 INFO - ##### 17:08:43 INFO - Running pre-action listener: _resource_record_pre_action 17:08:43 INFO - Running main action method: create_virtualenv 17:08:43 INFO - Creating virtualenv /builds/slave/test/build/venv 17:08:43 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 17:08:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:08:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:08:43 INFO - Using real prefix '/tools/python27' 17:08:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:08:44 INFO - Installing distribute.............................................................................................................................................................................................done. 17:08:47 INFO - Installing pip.................done. 17:08:47 INFO - Return code: 0 17:08:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:08:47 INFO - 'HOME': '/Users/cltbld', 17:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:08:47 INFO - 'LOGNAME': 'cltbld', 17:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:47 INFO - 'MOZ_NO_REMOTE': '1', 17:08:47 INFO - 'NO_EM_RESTART': '1', 17:08:47 INFO - 'PAGER': '/bin/cat', 17:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:47 INFO - 'PWD': '/builds/slave/test', 17:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:08:47 INFO - 'SHELL': '/bin/bash', 17:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:08:47 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:08:47 INFO - 'USER': 'cltbld', 17:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:47 INFO - 'XPC_FLAGS': '0x0', 17:08:47 INFO - 'XPC_SERVICE_NAME': '0', 17:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:47 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 17:08:47 INFO - Cleaning up... 17:08:47 INFO - Return code: 0 17:08:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:08:47 INFO - 'HOME': '/Users/cltbld', 17:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:08:47 INFO - 'LOGNAME': 'cltbld', 17:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:47 INFO - 'MOZ_NO_REMOTE': '1', 17:08:47 INFO - 'NO_EM_RESTART': '1', 17:08:47 INFO - 'PAGER': '/bin/cat', 17:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:47 INFO - 'PWD': '/builds/slave/test', 17:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:08:47 INFO - 'SHELL': '/bin/bash', 17:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:08:47 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:08:47 INFO - 'USER': 'cltbld', 17:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:47 INFO - 'XPC_FLAGS': '0x0', 17:08:47 INFO - 'XPC_SERVICE_NAME': '0', 17:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:47 INFO - Downloading/unpacking psutil>=0.7.1 17:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:08:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:08:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:08:50 INFO - Installing collected packages: psutil 17:08:50 INFO - Running setup.py install for psutil 17:08:50 INFO - building 'psutil._psutil_osx' extension 17:08:50 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 17:08:51 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 17:08:51 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 17:08:51 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 17:08:51 INFO - building 'psutil._psutil_posix' extension 17:08:51 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 17:08:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:08:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:08:51 INFO - ^ 17:08:51 INFO - 1 warning generated. 17:08:51 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 17:08:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:08:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:08:51 INFO - Successfully installed psutil 17:08:51 INFO - Cleaning up... 17:08:52 INFO - Return code: 0 17:08:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:08:52 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')]} 17:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:52 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')]} 17:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:52 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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:08:52 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 17:08:52 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 17:08:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:08:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:08:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:08:52 INFO - 'HOME': '/Users/cltbld', 17:08:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:08:52 INFO - 'LOGNAME': 'cltbld', 17:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:52 INFO - 'MOZ_NO_REMOTE': '1', 17:08:52 INFO - 'NO_EM_RESTART': '1', 17:08:52 INFO - 'PAGER': '/bin/cat', 17:08:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:52 INFO - 'PWD': '/builds/slave/test', 17:08:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:08:52 INFO - 'SHELL': '/bin/bash', 17:08:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:08:52 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:08:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:08:52 INFO - 'USER': 'cltbld', 17:08:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:08:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:52 INFO - 'XPC_FLAGS': '0x0', 17:08:52 INFO - 'XPC_SERVICE_NAME': '0', 17:08:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:08:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:08:54 INFO - Installing collected packages: mozsystemmonitor 17:08:54 INFO - Running setup.py install for mozsystemmonitor 17:08:54 INFO - Successfully installed mozsystemmonitor 17:08:54 INFO - Cleaning up... 17:08:55 INFO - Return code: 0 17:08:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:08: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')]} 17:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08: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')]} 17:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08: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', '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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:08: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:08: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 blobuploader==1.2.4 17:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:08:55 INFO - 'HOME': '/Users/cltbld', 17:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:08:55 INFO - 'LOGNAME': 'cltbld', 17:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:55 INFO - 'MOZ_NO_REMOTE': '1', 17:08:55 INFO - 'NO_EM_RESTART': '1', 17:08:55 INFO - 'PAGER': '/bin/cat', 17:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:55 INFO - 'PWD': '/builds/slave/test', 17:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:08:55 INFO - 'SHELL': '/bin/bash', 17:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:08:55 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:08:55 INFO - 'USER': 'cltbld', 17:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:55 INFO - 'XPC_FLAGS': '0x0', 17:08:55 INFO - 'XPC_SERVICE_NAME': '0', 17:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:08:55 INFO - Downloading/unpacking blobuploader==1.2.4 17:08: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 17:08: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 17:08: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 17:08: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 17:08: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 17:08: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 17:08:57 INFO - Downloading blobuploader-1.2.4.tar.gz 17:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:08:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:08:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:08:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:08:58 INFO - Downloading docopt-0.6.1.tar.gz 17:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:08:58 INFO - Installing collected packages: blobuploader, requests, docopt 17:08:58 INFO - Running setup.py install for blobuploader 17:08:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:08:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:08:59 INFO - Running setup.py install for requests 17:08:59 INFO - Running setup.py install for docopt 17:08:59 INFO - Successfully installed blobuploader requests docopt 17:08:59 INFO - Cleaning up... 17:08:59 INFO - Return code: 0 17:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:08: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')]} 17:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08: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')]} 17:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:08:59 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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:08:59 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 17:08:59 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 17:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:08:59 INFO - 'HOME': '/Users/cltbld', 17:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:08:59 INFO - 'LOGNAME': 'cltbld', 17:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:08:59 INFO - 'MOZ_NO_REMOTE': '1', 17:08:59 INFO - 'NO_EM_RESTART': '1', 17:08:59 INFO - 'PAGER': '/bin/cat', 17:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:08:59 INFO - 'PWD': '/builds/slave/test', 17:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:08:59 INFO - 'SHELL': '/bin/bash', 17:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:08:59 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:08:59 INFO - 'USER': 'cltbld', 17:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:08:59 INFO - 'XPC_FLAGS': '0x0', 17:08:59 INFO - 'XPC_SERVICE_NAME': '0', 17:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-RoYUVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-nzt8rD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-O78H1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-bxVMtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-BoeSg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-DE3WH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-6C1FCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-OC_yuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:00 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-8RnUgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-6BTRan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-nR6gpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-ohEJ2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-FZQfzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-sLrJDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-mDCNAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-BZJABe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:01 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-shGQtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:09:01 INFO - Running setup.py install for manifestparser 17:09:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:09:01 INFO - Running setup.py install for mozcrash 17:09:02 INFO - Running setup.py install for mozdebug 17:09:02 INFO - Running setup.py install for mozdevice 17:09:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Running setup.py install for mozfile 17:09:02 INFO - Running setup.py install for mozhttpd 17:09:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Running setup.py install for mozinfo 17:09:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Running setup.py install for mozInstall 17:09:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:09:02 INFO - Running setup.py install for mozleak 17:09:03 INFO - Running setup.py install for mozlog 17:09:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:09:03 INFO - Running setup.py install for moznetwork 17:09:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:09:03 INFO - Running setup.py install for mozprocess 17:09:03 INFO - Running setup.py install for mozprofile 17:09:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:09:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:09:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:09:03 INFO - Running setup.py install for mozrunner 17:09:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:09:03 INFO - Running setup.py install for mozscreenshot 17:09:04 INFO - Running setup.py install for moztest 17:09:04 INFO - Running setup.py install for mozversion 17:09:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:09:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:09:04 INFO - Cleaning up... 17:09:04 INFO - Return code: 0 17:09:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:04 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')]} 17:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:04 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')]} 17:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:04 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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:09:04 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 17:09:04 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 17:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:04 INFO - 'HOME': '/Users/cltbld', 17:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:04 INFO - 'LOGNAME': 'cltbld', 17:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:04 INFO - 'MOZ_NO_REMOTE': '1', 17:09:04 INFO - 'NO_EM_RESTART': '1', 17:09:04 INFO - 'PAGER': '/bin/cat', 17:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:04 INFO - 'PWD': '/builds/slave/test', 17:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:04 INFO - 'SHELL': '/bin/bash', 17:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:09:04 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:04 INFO - 'USER': 'cltbld', 17:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:04 INFO - 'XPC_FLAGS': '0x0', 17:09:04 INFO - 'XPC_SERVICE_NAME': '0', 17:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:04 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-rn5VgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:04 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)) 17:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:04 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-FQCz2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:04 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)) 17:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:04 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-SdvHcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:04 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)) 17:09:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-DId7bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-7Qxpa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-MuIMo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-hpC_Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-nu1Q82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-_Aqr5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-AfsLjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-l4ChGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-9d4xFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:05 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)) 17:09:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:05 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-jWk6qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:06 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)) 17:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:06 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-2m83az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:06 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)) 17:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:06 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-yH4J3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:06 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)) 17:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:06 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-kLFbt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:06 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)) 17:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:06 INFO - Running setup.py (path:/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/pip-HV7Cjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:06 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)) 17:09:06 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)) 17:09:06 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)) 17:09:06 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)) 17:09:06 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)) 17:09:06 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)) 17:09:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09:06 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 17:09:06 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 17:09:06 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 17:09:06 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 17:09:06 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 17:09:06 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 17:09:08 INFO - Downloading blessings-1.6.tar.gz 17:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:09:08 INFO - Installing collected packages: blessings 17:09:08 INFO - Running setup.py install for blessings 17:09:09 INFO - Successfully installed blessings 17:09:09 INFO - Cleaning up... 17:09:09 INFO - Return code: 0 17:09:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:09:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:09 INFO - Reading from file tmpfile_stdout 17:09:09 INFO - Current package versions: 17:09:09 INFO - blessings == 1.6 17:09:09 INFO - blobuploader == 1.2.4 17:09:09 INFO - docopt == 0.6.1 17:09:09 INFO - manifestparser == 1.1 17:09:09 INFO - mozInstall == 1.12 17:09:09 INFO - mozcrash == 0.17 17:09:09 INFO - mozdebug == 0.1 17:09:09 INFO - mozdevice == 0.48 17:09:09 INFO - mozfile == 1.2 17:09:09 INFO - mozhttpd == 0.7 17:09:09 INFO - mozinfo == 0.9 17:09:09 INFO - mozleak == 0.1 17:09:09 INFO - mozlog == 3.1 17:09:09 INFO - moznetwork == 0.27 17:09:09 INFO - mozprocess == 0.22 17:09:09 INFO - mozprofile == 0.28 17:09:09 INFO - mozrunner == 6.11 17:09:09 INFO - mozscreenshot == 0.1 17:09:09 INFO - mozsystemmonitor == 0.0 17:09:09 INFO - moztest == 0.7 17:09:09 INFO - mozversion == 1.4 17:09:09 INFO - psutil == 3.1.1 17:09:09 INFO - requests == 1.2.3 17:09:09 INFO - wsgiref == 0.1.2 17:09:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:09 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')]} 17:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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')]} 17:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:09:09 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 17:09:09 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 17:09:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:09:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:09 INFO - 'HOME': '/Users/cltbld', 17:09:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:09 INFO - 'LOGNAME': 'cltbld', 17:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:09 INFO - 'MOZ_NO_REMOTE': '1', 17:09:09 INFO - 'NO_EM_RESTART': '1', 17:09:09 INFO - 'PAGER': '/bin/cat', 17:09:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:09 INFO - 'PWD': '/builds/slave/test', 17:09:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:09 INFO - 'SHELL': '/bin/bash', 17:09:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:09:09 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:09:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:09 INFO - 'USER': 'cltbld', 17:09:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:09 INFO - 'XPC_FLAGS': '0x0', 17:09:09 INFO - 'XPC_SERVICE_NAME': '0', 17:09:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 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)) 17:09:09 INFO - Cleaning up... 17:09:09 INFO - Return code: 0 17:09:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:09:09 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')]} 17:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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')]} 17:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:09 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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:09:09 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 17:09:09 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 17:09:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:09:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:09 INFO - 'HOME': '/Users/cltbld', 17:09:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:09 INFO - 'LOGNAME': 'cltbld', 17:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:09 INFO - 'MOZ_NO_REMOTE': '1', 17:09:09 INFO - 'NO_EM_RESTART': '1', 17:09:09 INFO - 'PAGER': '/bin/cat', 17:09:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:09 INFO - 'PWD': '/builds/slave/test', 17:09:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:09 INFO - 'SHELL': '/bin/bash', 17:09:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:09:09 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:09:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:09 INFO - 'USER': 'cltbld', 17:09:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:09 INFO - 'XPC_FLAGS': '0x0', 17:09:09 INFO - 'XPC_SERVICE_NAME': '0', 17:09:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:10 INFO - Downloading/unpacking jsonschema 17:09:10 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 17:09:10 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 17:09:10 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 17:09:10 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 17:09:10 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 17:09:10 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 17:09:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:09:12 INFO - Downloading/unpacking functools32 (from jsonschema) 17:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:12 INFO - Downloading functools32-3.2.3-2.tar.gz 17:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:09:13 INFO - warning: no files found matching '*.txt' 17:09:13 INFO - no previously-included directories found matching 'build' 17:09:13 INFO - no previously-included directories found matching 'dist' 17:09:13 INFO - no previously-included directories found matching '.git*' 17:09:13 INFO - Installing collected packages: jsonschema, functools32 17:09:13 INFO - Running setup.py install for functools32 17:09:13 INFO - warning: no files found matching '*.txt' 17:09:13 INFO - no previously-included directories found matching 'build' 17:09:13 INFO - no previously-included directories found matching 'dist' 17:09:13 INFO - no previously-included directories found matching '.git*' 17:09:13 INFO - Successfully installed jsonschema functools32 17:09:13 INFO - Cleaning up... 17:09:13 INFO - Return code: 0 17:09:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:09:13 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')]} 17:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:13 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')]} 17:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:13 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 0x10a788a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a78e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9b2d81430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, '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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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 17:09:13 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 17:09:13 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 17:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:13 INFO - 'HOME': '/Users/cltbld', 17:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:13 INFO - 'LOGNAME': 'cltbld', 17:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:13 INFO - 'MOZ_NO_REMOTE': '1', 17:09:13 INFO - 'NO_EM_RESTART': '1', 17:09:13 INFO - 'PAGER': '/bin/cat', 17:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:13 INFO - 'PWD': '/builds/slave/test', 17:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:13 INFO - 'SHELL': '/bin/bash', 17:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:09:13 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:13 INFO - 'USER': 'cltbld', 17:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:13 INFO - 'XPC_FLAGS': '0x0', 17:09:13 INFO - 'XPC_SERVICE_NAME': '0', 17:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:13 INFO - Downloading/unpacking flake8 17:09: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 17:09: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 17:09: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 17:09: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 17:09: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 17:09: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 17:09:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:09:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:09:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:09:17 INFO - no previously-included directories found matching 'docs/_build' 17:09:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:09:17 INFO - Running setup.py install for pep8 17:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:09:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:09:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:09:17 INFO - no previously-included directories found matching 'docs/_build' 17:09:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:09:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:09:17 INFO - Cleaning up... 17:09:17 INFO - Return code: 0 17:09:17 INFO - Running post-action listener: _resource_record_post_action 17:09:17 INFO - Running post-action listener: _start_resource_monitoring 17:09:17 INFO - Starting resource monitoring. 17:09:17 INFO - ##### 17:09:17 INFO - ##### Finished create-virtualenv step (success) 17:09:17 INFO - ##### 17:09:17 INFO - ##### 17:09:17 INFO - ##### Running install step. 17:09:17 INFO - ##### 17:09:17 INFO - Running pre-action listener: _resource_record_pre_action 17:09:17 INFO - Running main action method: install 17:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:18 INFO - Reading from file tmpfile_stdout 17:09:18 INFO - Detecting whether we're running mozinstall >=1.0... 17:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:09:18 INFO - Reading from file tmpfile_stdout 17:09:18 INFO - Output received: 17:09:18 INFO - Usage: mozinstall [options] installer 17:09:18 INFO - Options: 17:09:18 INFO - -h, --help show this help message and exit 17:09:18 INFO - -d DEST, --destination=DEST 17:09:18 INFO - Directory to install application into. [default: 17:09:18 INFO - "/builds/slave/test"] 17:09:18 INFO - --app=APP Application being installed. [default: firefox] 17:09:18 INFO - mkdir: /builds/slave/test/build/application 17:09:18 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'] 17:09:18 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 17:09:51 INFO - Reading from file tmpfile_stdout 17:09:51 INFO - Output received: 17:09:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:09:51 INFO - Running post-action listener: _resource_record_post_action 17:09:51 INFO - ##### 17:09:51 INFO - ##### Finished install step (success) 17:09:51 INFO - ##### 17:09:51 INFO - ##### 17:09:51 INFO - ##### Running run-tests step. 17:09:51 INFO - ##### 17:09:51 INFO - Running pre-action listener: _resource_record_pre_action 17:09:51 INFO - Running main action method: run_tests 17:09:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:09:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:09:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:09:51 INFO - 2016-05-09 17:09:51.837 screenresolution[1642:7687] starting screenresolution argv=screenresolution get 17:09:51 INFO - 2016-05-09 17:09:51.840 screenresolution[1642:7687] Display 0: 1600x1200x32@60 17:09:51 INFO - 2016-05-09 17:09:51.846 screenresolution[1643:7689] starting screenresolution argv=screenresolution list 17:09:51 INFO - Available Modes on Display 0 17:09:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:09:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:09:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:09:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:09:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:09:52 INFO - Intel Iris: 17:09:52 INFO - Chipset Model: Intel Iris 17:09:52 INFO - Type: GPU 17:09:52 INFO - Bus: Built-In 17:09:52 INFO - VRAM (Dynamic, Max): 1536 MB 17:09:52 INFO - Vendor: Intel (0x8086) 17:09:52 INFO - Device ID: 0x0a2e 17:09:52 INFO - Revision ID: 0x0009 17:09:52 INFO - Displays: 17:09:52 INFO - Display: 17:09:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:09:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:09:52 INFO - Display Serial Number: 1600x1200 60 17:09:52 INFO - Main Display: Yes 17:09:52 INFO - Mirror: Off 17:09:52 INFO - Online: Yes 17:09:52 INFO - Rotation: Supported 17:09:52 INFO - Return code: 0 17:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:09:52 INFO - Python 2.7.3 17:09:52 INFO - Return code: 0 17:09:52 INFO - grabbing minidump binary from tooltool 17:09:52 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')]} 17:09:52 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 0x10a62cd30>, '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 0x10a450db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a623b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:09:52 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 17:09:52 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 17:09:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:09:52 INFO - Return code: 0 17:09:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:09:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:09:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:09:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:09:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:09:53 INFO - Return code: 0 17:09:53 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/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0182', '--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 17:09:53 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/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0182 --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 17:09:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CDD0XD5zDG/Render', 17:09:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:53 INFO - 'HOME': '/Users/cltbld', 17:09:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:53 INFO - 'LOGNAME': 'cltbld', 17:09:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:09:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:53 INFO - 'MOZ_NO_REMOTE': '1', 17:09:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:09:53 INFO - 'NO_EM_RESTART': '1', 17:09:53 INFO - 'PAGER': '/bin/cat', 17:09:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:53 INFO - 'PWD': '/builds/slave/test', 17:09:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:09:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:53 INFO - 'SHELL': '/bin/bash', 17:09:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZwGDFKYq2u/Listeners', 17:09:53 INFO - 'TMPDIR': '/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/', 17:09:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:53 INFO - 'USER': 'cltbld', 17:09:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:53 INFO - 'XPC_FLAGS': '0x0', 17:09:53 INFO - 'XPC_SERVICE_NAME': '0', 17:09:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:53 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/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0182', '--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 17:09:53 INFO - mozversion application_buildid: 20160509151608 17:09:53 INFO - mozversion application_changeset: 687f1eb881d82a76ddc02e71c1778c82de125407 17:09:53 INFO - mozversion application_display_name: Nightly 17:09:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:09:53 INFO - mozversion application_name: Firefox 17:09:53 INFO - mozversion application_remotingname: firefox 17:09:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:09:53 INFO - mozversion application_vendor: Mozilla 17:09:53 INFO - mozversion application_version: 49.0a1 17:09:53 INFO - mozversion platform_buildid: 20160509151608 17:09:53 INFO - mozversion platform_changeset: 687f1eb881d82a76ddc02e71c1778c82de125407 17:09:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:09:53 INFO - mozversion platform_version: 49.0a1 17:09:53 INFO - using testdate: 1462838993 17:09:53 INFO - actual date: 1462838993 17:09:53 INFO - starting webserver on 'localhost:49275' 17:09:53 INFO - SUITE-START | Running 2 tests 17:09:53 INFO - TEST-START | dromaeo_css 17:09:53 INFO - operating with platform_type : mac_ 17:09:53 INFO - Initialising browser for dromaeo_css test... 17:09:53 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpGi3DhX/profile http://localhost:49275/getInfo.html) 17:09:55 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 17:09:55 INFO - PROCESS | 1661 | colorDepth:24 17:09:55 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 17:09:55 INFO - PROCESS | 1661 | __metrics 17:09:55 INFO - PROCESS | 1661 | 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" 17:09:55 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:09:56 INFO - TEST-INFO | 1661: exit 0 17:09:56 INFO - Browser initialized. 17:09:56 INFO - Running cycle 1/1 for dromaeo_css test... 17:09:56 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpGi3DhX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:08 INFO - PROCESS | 1663 | RSS: Main: 239185920 17:10:08 INFO - PROCESS | 1663 | 17:10:59 INFO - PROCESS | 1663 | 1462839059959 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] 17:13:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 17:13:11 INFO - PROCESS | 1663 | RSS: Main: 364453888 17:13:11 INFO - PROCESS | 1663 | 17:16:14 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 17:16:15 INFO - PROCESS | 1663 | RSS: Main: 375074816 17:16:15 INFO - PROCESS | 1663 | 17:19:17 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 17:19:18 INFO - PROCESS | 1663 | RSS: Main: 372613120 17:19:18 INFO - PROCESS | 1663 | 17:22:20 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 17:22:21 INFO - PROCESS | 1663 | RSS: Main: 369958912 17:22:21 INFO - PROCESS | 1663 | 17:25:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 17:25:25 INFO - PROCESS | 1663 | RSS: Main: 338604032 17:25:25 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 17:28:28 INFO - PROCESS | 1663 | RSS: Main: 376512512 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | __start_tp_report 17:28:28 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 17:28:28 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 17:28:28 INFO - PROCESS | 1663 | |i|pagename|runs| 17:28:28 INFO - PROCESS | 1663 | |0;dojo.html;3441.5353927219826;3446.5519482467275;3462.7056700180483;3464.5102605879492;3507.2634746703206;5662;5696.287964004499;5703.144528320749;5820.621659592129;5830.12548117782;5726.226959360485;5800.187973683677;5847.210626565412;5892.941070589292;5914.319853216882;5129.179331306992;5158.3552055993005;5225.6864588124645;5235.712035838027;5327.733613319338;5307.336582927134;5332.466753324656;5348.358197016344;5362.24928510007;5382.1926710993275;5181.01560703467;5236.057509648262;5247.370315562119;5267.631265811395;5299.046171689094;5203.661761985459;5203.7918483261;5220.086484865134;5275.683458992473;5300.151975683911;5193.987172501364;5203.4796520348045;5206.01085793698;5248.8425347239645;5312.946870531284;5534.418886016951;5706.572007099443;5737.598368114233;5742.196092547047;5759;5379.273798037254;6155.599886007395;6177.3822617738315;6177.413145751191;6179.443850053472;6115.235595550556;6180.783672571483;6195.19462469876;6210.844728881769;6213.813585592439;5773.624714393551;5793.826185214451;5929.9703501482645;5964.7912323068485;5992.880142397171;6274.247090349188;6296.905546416808;6309.400606942333;6312.684365781693;6316.30520642729;6095.969520152371;6132.325444201134;6134.650895842656;6137.263528376623;6138.508919286446;6100.023996160682;6122.8251069701855;6223.066540019034;6274.996000639876;6278.183836101278;6176.7838125665385;6260.123582698426;6264.624122552661;6273.686315684197;6285.120083188357;6203.596766210182;6239.313675495692;6278.466330361879;6296.150019747367;6305;4858.17411040117;4903.485134060944;4931.926021109686;4984.152694042022;5027.497250274943;4494.52807455219;4517.983453722952;4526.072155208174;4544.909101818012;4552.6130278926;5672.574556908248;5680.7727690892725;5683.318001839723;5687.345955215121;5699;6176.876462470816;6198.845028874314;6209.285932117774;6210.875782484415;6225.719842607003;5627.493525582717;5740.024195886709;5771.220885180531;5778.855528611818;5785.595008349375;5285.863539338984;5410.675359478444;5420.105682562412;5436.320459942507;5449.972750136303;5851.882962340729;6018.766152938637;6082.635041897501;6219.346968568235;6356.364363563699;6183.196184495987;6204.3795620437595;6217.067439884054;6315.084312774714;6316.652584107918;6301.306856245901;6304.810855674154;6307.085472606538;6321.810345689452;6329.050642403492;6057.333693293822;6109.725062372115;6152.0156774915795;6176.28972668131;6178.474829639351;6175.84560385994;6300.653464059521;6322.525810564318;6328.398802113792;6331.081993110881;6043.395660433922;6077.756889724362;6084.056971169476;6088.939110608837;6099.9390006099375;5914.822555323347;6013.6692481913915;6028.2766068072115;6040.426159514839;6058.636481811105;5816.243888294494;5953.642781433128;5954.434328738763;5956.970215148896;5979.940200597938;6082.42216989403;6097.554926111998;6118.242200336633;6124.199565122063;6125.248816407016;5787.797427090031;5904.232449781501;5913.615614985012;5966.433188847052;6033.487153591818 17:28:28 INFO - PROCESS | 1663 | |1;ext.html;489.3003005701845;524.2529395611256;530.4483337329178;533.7358007786146;584.0771580902174;29662.961796337106;38115.04712382188;42697;44040.67877963666;45930;43947.78026109873;44330.77834610822;44378.55621443785;44411.55588444114;44485.555144448546;8638.568071596395;8747.256483198937;8785.516796576187;9638.614455421792;9915.25635577331;8726.258268047224;11629.592964246252;11914.940425297862;11938.761224775499;12003.939980300087;10874.347539147637;11236.101111911068;11847.7630447391;11922.225055371417;11923.880761192408;8678.349123815677;10392.37645741258;11584.76830463386;11619.012383947374;11872.228305160137;11637.70905727355;11696.883031169664;11699.23954942926;11701.239419437708;11720.120990925629;34929.650703492895;40469.785906422854;40849.387259191135;40912.59087409118;40929;3125.3905488429764;3180.1927359477672;3245.383377158332;3444.7244220462303;4087.121268927165;28064;29012.564811527845;30598.69401305981;30607;30636.38727225443;2753.421781425903;2759.089500464862;2763.847988360639;2812.8874845006176;2823.4917714811327;500185;500831;501233;503786.48106759234;505890;35428.468572971426;35955.28089438197;63144.68427657832;64698.206688297054;64965.67517162478;37656;37834.72991620511;37989.62010379861;37990.810045950144;38025.80987095047;15134;15493.225338733244;15515.922420387826;15520.5343839685;15530.9223453882;11883.049356051495;12083.335416552174;12989.480420783251;13112.475500980045;13160.934195329153;8183.754487365389;8184.099749027602;8237.629306681214;8270.586470676562;8277.420580559423;3407.352603005421;3429.5884493860895;3431.330890476383;3434.6737059979264;3464.8960531184107;986.2455221755363;1038.6416686243233;1095.2114477576133;1097.544519024596;1133.4672703829187;26638.866805666235;27352.58971115435;29131.563026554617;29382.706172937997;29871.850640747092;1117.0951529261324;1122.2817396866012;1125.0999200639455;1155.6359746679768;1237.9690507737198;15344.462943796914;15377.384904603914;15423.305951232222;15592.171178588906;15680.76478852818;676855;713456;715524;717286;718494;59451;61211.38788612057;61230;61315;61435.6928215365;1316.8288122544009;1351.1487762709626;1451.9346629401914;1473.38854375433;1489.4637930345068;29354.853225733736;30178.396432070796;30498.932537361085;30573;30871.555756602622;49957;50448.747756260986;50454.99090018252;50550;50566.747166265406;456887;472478;472698;472742.27257728355;472772;23677.408064798517;24150.758492415553;24190.637140442555;24942.002319907002;24975.375615609755;39238.607613923494;40608;45620;50187.49812501828;50270;36298;37694.057648559006;39674.008149796486;39786.602133979446;40286.79856600698;37462;37494.06264843401;37570;38313.042173945876;38357.6164238354;37882.86351409462;38553.80723096366;38578;39041;39082.21835563216;31462.370752585277;33236.968466907965;33539;33640.66359336375;33643.663563364054;22832.885835570716;24912.62631060572;25046.998120074993;25117;25605.487890242464 17:28:28 INFO - PROCESS | 1663 | |2;jquery.html;392.3663283796668;394.97827619480915;395.78231972415165;396.7500474700938;419.9958000419997;14514;20058.197672093116;20937;21457.67813482797;21499.24752633658;15894.682106357895;18132;20497.795022049813;20991.845448500328;21075.578488430223;6054.697265136748;6267.62394256346;6273.05904114382;6283.026130949687;6302.275238347603;6172.506199504029;6260.092286618437;6260.405261500173;6265.185525881629;6319.810405687814;5996.130561068664;6142.938570614304;6203.348648391921;6204.162438070848;6212.751489940397;5989.281286245657;6056.091586262052;6190.0405437157315;6194.318624951252;6194.907076393858;5120.18077107661;5375.247465354853;5393.406725260218;5400.432954539756;5401.675899446046;26441.603375949377;26589;26591.734082659314;26870;27578.44843103147;1944.7277381166648;2166.891655417223;2216.1578600132007;2812;2926.1806694125466;9966.700998970042;9978.800423991552;10096.658491570373;10275.280730348879;10356.964303569657;1712.4263371770471;1757.1916918217635;1758.5603599100225;1800.4238643634042;1825.2881376263267;103027;119130.40434797858;119637.40181299124;121392.07471327623;121997;9970.30207885441;11529.365884876244;11635.709107272385;11708.238964467282;11710.65786842635;13675.863241367659;15066.698666026838;15101.093934363973;15144.848551514564;15154.545363639108;6881.346272104142;7820.569868657279;8123.04686526542;8606.096359882247;8801.779955501035;7265.673044713;8625.870611940825;8639.6976105836;8726.607302671395;8737.43206691563;4637.3275874997935;4642.210824159901;4668.603168730628;4683.531646835358;4716.103940251342;1727.8358555937166;1932.94201173965;1938.975198690048;2001.5896741168026;2004.3485867092961;785.7878372839301;925.726910561386;949.3117489819825;960.500539719342;964.2864280432569;17260.223289951984;20642.690359644617;20675.58648827015;20835;20912.790872091387;962.3696478806393;989.2086330935367;999.480270259461;1027.8304079826894;1039.6621098143135;10694.304870183412;11017.504212310465;11047.613333533449;11129.276597021168;11143.609973650884;598320;612742.4265908045;619630;626865;632248;133826;135328;136227;136269;140173.29913350573;1007.6876168387823;1025.564135242534;1051.395447617623;1054.1935419404163;1054.541274545575;16643.751718620908;16676.749848752523;18144.45566633003;18650.906745466185;18765.061746912328;51958.480415195365;52776.47223527716;52978.58299837949;53269;54264.18603721026;73459.63270183615;74069.18550294214;75185.24814751782;76299.61850190714;76301;8023.598820058857;8119.5534245617055;8269.669213231404;8430.030546487233;8637.179467950535;12101.75796484083;12526.436310365872;12578.685532861386;12928.612141635766;12948.417321220379;10529.05238528536;10643.254972151875;10683.57265709363;11316.321020738782;11366.488508016993;9712;10250.38497690141;10794.136469082612;10999.725006874573;11198.272112312674;5781.739821708117;9858.408495490006;11059.668209953714;11358.864113588575;11521.251118677259;8056.516609003478;8071.4753541019645;8111.675532978851;8366.24703776663;8412.284955778827;9652.855207172039;10797.190210734068;11046.834297485384;11068.893110688867;11080.501377438195 17:28:28 INFO - PROCESS | 1663 | |3;mootools.html;1401.9905667919095;1582.8338024507427;1592.697387496376;1852.6294741051793;2001.2495314257153;7159.606221657807;8378;8679.826403471927;8692.087330830258;8791.956040219806;8459.746207613765;8546.52994085325;8628.439151455152;8631.050584435707;8641.04948455671;2068.6276470235352;2271.954560908781;2296.0700916128953;2328.39461739948;2331.6152834782324;2354.8469349492316;2394.892229849661;2398.9160379386726;2399.5081008393327;2400.4238982644215;2273.7726227377298;2282.6119559674894;2296.1619009061656;2316.5598536278053;2317.362725250561;2009.9598008039904;2237.7650346713685;2307.169419009164;2310.976890231093;2356.2931120663816;2336.9649455258186;2359.00921612923;2364.5034542746043;2365.562370961378;2369.9052037918464;8481.618327175293;8569.828603427897;8577.01364343096;8578.485290882567;8581.613827377783;4999.500049995007;5182.481751824825;5186.766595503173;5188.299579556749;5190.558802501754;3210.983945080283;3285.3593549257903;3442.345954268706;3452.4993875887853;3455.4298540740747;1017.7455636090978;1047.261680515235;1048.7430579507993;1048.8479170520231;1053.9314944528658;11193.99254067138;11284.210105292634;11294.548218071268;11300.943495282554;11309;4247.68142389322;4461.196984542748;4533.637309015271;4598.965232822588;4690.976545117321;9458.43249405038;9557.952210238904;9561.235101191887;9568.09103135201;9600.231981441468;5211.4527974562125;5220.504052115043;5222.503862133091;5226.111561034557;5244.187150991603;4212.199682060399;4407.537208593116;4582.381378513858;4660.4407471103;4770.95229047712;3556.4309710446723;3908.5895980921596;3938.8424463021734;4012.8595499157973;4135.069609337875;1301.258282778807;1322.715616142534;1326.303690562443;1336.411978729356;1339.8325209348832;893.7050773244814;917.4907926100944;920.8987011428737;927.1655510040882;935.2985261054209;5752.942470575241;5875.764969401176;5918.654696877382;5955.493783028491;6011.639301641915;402.9738067025634;416.54388444653057;417.7472629059436;424.5012110769895;430.69851104227166;3309.4870295104297;3752.7935963521722;3813.904652383713;3821;3884.3008258513173;9951.452670103214;10293.845592316122;10440;10447.895521044844;10453.790924181474;10261.58953641846;10520.421376824195;10528.315279603652;10552.314099583502;10571.682849514527;7548.245175482518;7934.246246606564;8064.072631647436;8065;8113.59432028408;5821.70891455417;5917.674527900835;6014.939850601438;6024.638521688713;6058.454739073504;9597.712068637951;9875.358101723365;9950.502474876082;9952.353097048668;9963.850542241727;9096.727098187064;9758.75603109928;9945.751356216153;9947.353422027545;10065.69802905914;6794.218664853605;6989.440844732511;6991.986162006582;7451.18037015917;7613.505122167041;7639.465237433548;7716.035495563055;7996.720114795954;8295.460795048302;8309.95845020771;7315.8536829264185;7477.66350514239;7496.662650180646;7533.39732821362;7652.7704168875025;7258.165310989304;7276.636168191463;7279.599622020628;7351.816204594714;7434.628268586441;6956.443484521327;6984.092068031124;7053.118360204974;7059.364657180673;7059.788206353817;4799.776754569532;4913.803447862046;4932.334134891744;4966.155753521982;5113.821016264413;4596.333531637961;4856.174450343379;4980.252962055721;5030.170021946338;5198.324217851655 17:28:28 INFO - PROCESS | 1663 | |4;prototype.html;307.44199278309867;316.5663041632962;319.07468341808755;323.8202797447416;326.54936188060475;4926.729029903354;5202.673318303832;5421.993862270961;5437.564994800407;5466.972665136669;4955.975220123894;5284.957007888958;5343.438938911407;5360.571154307656;5393.892122157555;3463.6882680558683;3494.318607871466;3540.274243780019;3951.3677811550156;4164.458620379346;3539.433690609503;3874.7868867212433;4070.4504891839665;4103.384492326145;4128.5756414037105;3692.6307369262995;4079.2045551117394;4082.2447847148374;4110.445089912853;4119.155573107506;3858.672012878909;3878.1855810279876;4110.609492098246;4121.62905338518;4138;3562.946555801665;3583.157957879896;3586.4620306953907;3586.8744593939346;3587.2287458196615;5163.63854530183;5168.61235407346;5184.248283998837;5190.71451070191;5191.351081114861;3969.6625786807867;4072.2669919414493;4424;4715.528447155291;4756.571908528214;4976.651634385595;5578.24693666358;5604.971975140098;5624.184493248497;5648.774049038075;3758.342290099222;3782.2624588205313;3936.547297060846;3979.960200397988;4002.799860006988;5386.841829006743;5390.838274851761;5724.685142317129;5977.491913187384;5998.790042348541;3865.5168103987003;3888.9027774305305;3898.8830335089424;3905.1408690088133;3919.9020024499046;3505.544279243733;3921.8627348042683;3971.2923442919246;3976.4234186042863;3988.441618173458;4008;4047.8583249586713;4058.5941405859767;4629.611116664987;4635.7218566886095;5050.823221187241;5659.801906933238;5701.486866182064;5705.2012718219485;5706.3152421709665;1143;1154.6824623228679;1158.9188756787112;1274.6679719692943;1304.230504002643;778.2373274191211;807.2290962131151;822.5311572403792;863.2131487820277;885.3581153663671;4557.225271703818;4729.787159577827;4818.518148185153;4897.428849853855;5234.241035049897;5233.979374022029;5691.544676425876;5804.9419505805245;5871.64770113786;5897.380775018647;4701;4715.995701826779;4983.8504844854715;5219.660722053054;5316.548093412026;2515.666696650778;2695.8382497049847;2754.4628797384717;2820.252633052243;2912.8689208985224;5148.56237219833;5400.326838215109;5565.02612042899;5636.589863132827;5820.679862607597;5041.798328066836;5672.220611900139;5686.829395118153;5717.914231286534;5730.656160630376;5254.632175747661;5612.466815652507;6090.893644241469;6111.027831909206;6136.539759517965;5308.920366194588;5473.562115030868;5844.15259787337;5850.795222167204;5925.674087925206;5240.816571419982;5643.1535269709875;5800.6809625471005;5803.47768700819;5839.912401313899;5623.971880140737;6039.456448919618;6084.878302433838;6090.451859332681;6136.447719705248;3403.676650718178;3818.7135964802196;3937.5865534118507;3949.328614135661;3954.367301231789;4913.983063408419;5802.361740208489;5882.23530940975;5896.7936122235515;5911.7635294587735;5407.72832436386;5563.415841336763;5686.004949133802;5687.118496633028;5705.001624715575;4676.438827340605;4706.364640773589;5422.147178275501;5642.6614403135945;5671.40450252734;4942.901141977069;5500.1749737539685;5516.641418307797;5525.254090697865;5537.418571049981;5316.787328506971;5362.436944120967;5666.546676265806;5668.801591944262;5687;4955.529224723646;4968.801247950041;5544.445555444423;5557.694326811902;5568.582356323373 17:28:28 INFO - PROCESS | 1663 | |5;yui.html;639.2169592249494;652.954293199476;715.6207210178604;715.8926161075834;749.325606953742;14696.853031469682;15429.382824687027;15478.226088695577;15493.380264789395;15499.61250968725;14091.859081409182;14106.294685265748;14118.717625647507;14125.858741412583;14163.433462661507;715.8210447388153;741.5217184915726;901.1034021148965;909.6043221198767;933.2580598424236;911.421247507834;925.7778133248006;928.7074571509953;930.7580029192391;930.972070837876;988.85661579071;991.6281394477071;993.2997236947971;995.945223012734;996.0537489385077;756.8940348351235;783.6669415498391;784.6625950841136;786.2923368967975;984.517586382671;700.2577268095835;727.294524311417;728.5082569265777;729.2452311857203;729.9635018249066;14878.256087195598;14948.252587370587;14954.62613434662;14955.70088598222;14979.400823967027;12232.755344893052;12303.446344914411;12327.506899724;12551.874481255161;12680;10497.690508088208;10869.728256793564;10895.182861285435;11264.774704505946;11497.827532587018;3920.3193596878;3947.7039222058174;3986.7009974251755;3988.142549351903;4107.815148318333;79922;80353;80775;81211.59394203051;81237.37525249462;9403.4828084456;9482.762930926781;9489.145976861977;9668.419894806335;9722.61109555624;19672.803271967095;20263.986800659906;20317.390478285673;20942.685859712117;21794.346169614935;7470.813229669302;7506.24937506245;7612.027658455809;7790.026246719161;7879.054513458422;4069.8168582413864;4110.630043296058;4113.300738874398;4120.958790412057;4439.866803995886;3351.631320554786;3442.896713098611;3449.5515582974053;3449.6377880322707;3454.9654503455145;627.8587317853538;643.353429803045;644.2944975252092;649.2890285137767;654.6464908949209;566.64301490061;594.5095296380504;600.3756093662629;601.1883956658563;605.0863196573118;7701.768946931489;7819.687212511436;7905.56519391439;7984.7548352015365;8094.635741391651;531.5136649965316;537.4813305160538;560.6047736345866;562.7073921560766;564.9887002260014;3712.5173727415263;3807.7334586579227;3816.9427458588143;3843.5003449551377;3856.2287542491613;91329.54335228281;91516.6272505913;93679;96351;96375.51812240893;73693.5261294771;74894.62552687201;79885.20114798893;81817;82155.5892220547;67366;69900;70423.9436408444;72052;73137.26862731518;7287.635618218962;7325.743598974011;7377.557346559223;7520.172780993977;7718.459707820623;33650.99047028593;45656.062922875666;46657.3001404986;46676.7666161667;46822.29766553437;61762.0735688974;64599.67700161657;64883;64950.67524662346;65210;9738.83523093871;10015.849762253718;10217.795644087226;10355.689329320132;10570.10154136907;11292.661220163409;11308.717282068013;11501.252418592765;11535.653930382103;11824.940875295568;8847.336449766164;10159.542820572944;10283;10447.111995480469;10560.313579617301;10416.21878359111;10610.628627997983;10615.734606634896;10871.945640271748;10881.075108615856;10219.7445063874;10250.743731406774;10466.790664186827;10472.68581942543;10499.842502362319;8401.15988401155;8658.047614762498;8749.47503149813;8858.335624828034;9124.817503649758;7538;8590.527520986405;8733.388662793797;8814.162654547808;8825.470471771714 17:28:28 INFO - PROCESS | 1663 | __end_tp_report 17:28:28 INFO - PROCESS | 1663 | __start_cc_report 17:28:28 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1196 17:28:28 INFO - PROCESS | 1663 | __end_cc_report 17:28:28 INFO - PROCESS | 1663 | __startTimestamp1462840108745__endTimestamp 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | ------- Summary: start ------- 17:28:28 INFO - PROCESS | 1663 | Number of tests: 6 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5754.50 Median:5956.97 stddev:607.32 (10.2%) stddev-sans-first:581.45 17:28:28 INFO - PROCESS | 1663 | Values: 3441.5 3446.6 3462.7 3464.5 3507.3 5662.0 5696.3 5703.1 5820.6 5830.1 5726.2 5800.2 5847.2 5892.9 5914.3 5129.2 5158.4 5225.7 5235.7 5327.7 5307.3 5332.5 5348.4 5362.2 5382.2 5181.0 5236.1 5247.4 5267.6 5299.0 5203.7 5203.8 5220.1 5275.7 5300.2 5194.0 5203.5 5206.0 5248.8 5312.9 5534.4 5706.6 5737.6 5742.2 5759.0 5379.3 6155.6 6177.4 6177.4 6179.4 6115.2 6180.8 6195.2 6210.8 6213.8 5773.6 5793.8 5930.0 5964.8 5992.9 6274.2 6296.9 6309.4 6312.7 6316.3 6096.0 6132.3 6134.7 6137.3 6138.5 6100.0 6122.8 6223.1 6275.0 6278.2 6176.8 6260.1 6264.6 6273.7 6285.1 6203.6 6239.3 6278.5 6296.2 6305.0 4858.2 4903.5 4931.9 4984.2 5027.5 4494.5 4518.0 4526.1 4544.9 4552.6 5672.6 5680.8 5683.3 5687.3 5699.0 6176.9 6198.8 6209.3 6210.9 6225.7 5627.5 5740.0 5771.2 5778.9 5785.6 5285.9 5410.7 5420.1 5436.3 5450.0 5851.9 6018.8 6082.6 6219.3 6356.4 6183.2 6204.4 6217.1 6315.1 6316.7 6301.3 6304.8 6307.1 6321.8 6329.1 6057.3 6109.7 6152.0 6176.3 6178.5 6175.8 6300.7 6322.5 6328.4 6331.1 6043.4 6077.8 6084.1 6088.9 6099.9 5914.8 6013.7 6028.3 6040.4 6058.6 5816.2 5953.6 5954.4 5957.0 5979.9 6082.4 6097.6 6118.2 6124.2 6125.2 5787.8 5904.2 5913.6 5966.4 6033.5 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:68264.56 Median:3454.81 stddev:152786.31 (4422.4%) stddev-sans-first:153130.17 17:28:28 INFO - PROCESS | 1663 | Values: 489.3 524.3 530.4 533.7 584.1 29663.0 38115.0 42697.0 44040.7 45930.0 43947.8 44330.8 44378.6 44411.6 44485.6 8638.6 8747.3 8785.5 9638.6 9915.3 8726.3 11629.6 11914.9 11938.8 12003.9 10874.3 11236.1 11847.8 11922.2 11923.9 8678.3 10392.4 11584.8 11619.0 11872.2 11637.7 11696.9 11699.2 11701.2 11720.1 34929.7 40469.8 40849.4 40912.6 40929.0 3125.4 3180.2 3245.4 3444.7 4087.1 28064.0 29012.6 30598.7 30607.0 30636.4 2753.4 2759.1 2763.8 2812.9 2823.5 500185.0 500831.0 501233.0 503786.5 505890.0 35428.5 35955.3 63144.7 64698.2 64965.7 37656.0 37834.7 37989.6 37990.8 38025.8 15134.0 15493.2 15515.9 15520.5 15530.9 11883.0 12083.3 12989.5 13112.5 13160.9 8183.8 8184.1 8237.6 8270.6 8277.4 3407.4 3429.6 3431.3 3434.7 3464.9 986.2 1038.6 1095.2 1097.5 1133.5 26638.9 27352.6 29131.6 29382.7 29871.9 1117.1 1122.3 1125.1 1155.6 1238.0 15344.5 15377.4 15423.3 15592.2 15680.8 676855.0 713456.0 715524.0 717286.0 718494.0 59451.0 61211.4 61230.0 61315.0 61435.7 1316.8 1351.1 1451.9 1473.4 1489.5 29354.9 30178.4 30498.9 30573.0 30871.6 49957.0 50448.7 50455.0 50550.0 50566.7 456887.0 472478.0 472698.0 472742.3 472772.0 23677.4 24150.8 24190.6 24942.0 24975.4 39238.6 40608.0 45620.0 50187.5 50270.0 36298.0 37694.1 39674.0 39786.6 40286.8 37462.0 37494.1 37570.0 38313.0 38357.6 37882.9 38553.8 38578.0 39041.0 39082.2 31462.4 33237.0 33539.0 33640.7 33643.7 22832.9 24912.6 25047.0 25117.0 25605.5 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35621.38 Median:26730.87 stddev:103165.05 (385.9%) stddev-sans-first:103420.54 17:28:28 INFO - PROCESS | 1663 | Values: 392.4 395.0 395.8 396.8 420.0 14514.0 20058.2 20937.0 21457.7 21499.2 15894.7 18132.0 20497.8 20991.8 21075.6 6054.7 6267.6 6273.1 6283.0 6302.3 6172.5 6260.1 6260.4 6265.2 6319.8 5996.1 6142.9 6203.3 6204.2 6212.8 5989.3 6056.1 6190.0 6194.3 6194.9 5120.2 5375.2 5393.4 5400.4 5401.7 26441.6 26589.0 26591.7 26870.0 27578.4 1944.7 2166.9 2216.2 2812.0 2926.2 9966.7 9978.8 10096.7 10275.3 10357.0 1712.4 1757.2 1758.6 1800.4 1825.3 103027.0 119130.4 119637.4 121392.1 121997.0 9970.3 11529.4 11635.7 11708.2 11710.7 13675.9 15066.7 15101.1 15144.8 15154.5 6881.3 7820.6 8123.0 8606.1 8801.8 7265.7 8625.9 8639.7 8726.6 8737.4 4637.3 4642.2 4668.6 4683.5 4716.1 1727.8 1932.9 1939.0 2001.6 2004.3 785.8 925.7 949.3 960.5 964.3 17260.2 20642.7 20675.6 20835.0 20912.8 962.4 989.2 999.5 1027.8 1039.7 10694.3 11017.5 11047.6 11129.3 11143.6 598320.0 612742.4 619630.0 626865.0 632248.0 133826.0 135328.0 136227.0 136269.0 140173.3 1007.7 1025.6 1051.4 1054.2 1054.5 16643.8 16676.7 18144.5 18650.9 18765.1 51958.5 52776.5 52978.6 53269.0 54264.2 73459.6 74069.2 75185.2 76299.6 76301.0 8023.6 8119.6 8269.7 8430.0 8637.2 12101.8 12526.4 12578.7 12928.6 12948.4 10529.1 10643.3 10683.6 11316.3 11366.5 9712.0 10250.4 10794.1 10999.7 11198.3 5781.7 9858.4 11059.7 11358.9 11521.3 8056.5 8071.5 8111.7 8366.2 8412.3 9652.9 10797.2 11046.8 11068.9 11080.5 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5577.13 Median:4973.20 stddev:3128.53 (62.9%) stddev-sans-first:3121.57 17:28:28 INFO - PROCESS | 1663 | Values: 1402.0 1582.8 1592.7 1852.6 2001.2 7159.6 8378.0 8679.8 8692.1 8792.0 8459.7 8546.5 8628.4 8631.1 8641.0 2068.6 2272.0 2296.1 2328.4 2331.6 2354.8 2394.9 2398.9 2399.5 2400.4 2273.8 2282.6 2296.2 2316.6 2317.4 2010.0 2237.8 2307.2 2311.0 2356.3 2337.0 2359.0 2364.5 2365.6 2369.9 8481.6 8569.8 8577.0 8578.5 8581.6 4999.5 5182.5 5186.8 5188.3 5190.6 3211.0 3285.4 3442.3 3452.5 3455.4 1017.7 1047.3 1048.7 1048.8 1053.9 11194.0 11284.2 11294.5 11300.9 11309.0 4247.7 4461.2 4533.6 4599.0 4691.0 9458.4 9558.0 9561.2 9568.1 9600.2 5211.5 5220.5 5222.5 5226.1 5244.2 4212.2 4407.5 4582.4 4660.4 4771.0 3556.4 3908.6 3938.8 4012.9 4135.1 1301.3 1322.7 1326.3 1336.4 1339.8 893.7 917.5 920.9 927.2 935.3 5752.9 5875.8 5918.7 5955.5 6011.6 403.0 416.5 417.7 424.5 430.7 3309.5 3752.8 3813.9 3821.0 3884.3 9951.5 10293.8 10440.0 10447.9 10453.8 10261.6 10520.4 10528.3 10552.3 10571.7 7548.2 7934.2 8064.1 8065.0 8113.6 5821.7 5917.7 6014.9 6024.6 6058.5 9597.7 9875.4 9950.5 9952.4 9963.9 9096.7 9758.8 9945.8 9947.4 10065.7 6794.2 6989.4 6992.0 7451.2 7613.5 7639.5 7716.0 7996.7 8295.5 8310.0 7315.9 7477.7 7496.7 7533.4 7652.8 7258.2 7276.6 7279.6 7351.8 7434.6 6956.4 6984.1 7053.1 7059.4 7059.8 4799.8 4913.8 4932.3 4966.2 5113.8 4596.3 4856.2 4980.3 5030.2 5198.3 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4544.03 Median:5176.43 stddev:1420.36 (27.4%) stddev-sans-first:1388.30 17:28:28 INFO - PROCESS | 1663 | Values: 307.4 316.6 319.1 323.8 326.5 4926.7 5202.7 5422.0 5437.6 5467.0 4956.0 5285.0 5343.4 5360.6 5393.9 3463.7 3494.3 3540.3 3951.4 4164.5 3539.4 3874.8 4070.5 4103.4 4128.6 3692.6 4079.2 4082.2 4110.4 4119.2 3858.7 3878.2 4110.6 4121.6 4138.0 3562.9 3583.2 3586.5 3586.9 3587.2 5163.6 5168.6 5184.2 5190.7 5191.4 3969.7 4072.3 4424.0 4715.5 4756.6 4976.7 5578.2 5605.0 5624.2 5648.8 3758.3 3782.3 3936.5 3980.0 4002.8 5386.8 5390.8 5724.7 5977.5 5998.8 3865.5 3888.9 3898.9 3905.1 3919.9 3505.5 3921.9 3971.3 3976.4 3988.4 4008.0 4047.9 4058.6 4629.6 4635.7 5050.8 5659.8 5701.5 5705.2 5706.3 1143.0 1154.7 1158.9 1274.7 1304.2 778.2 807.2 822.5 863.2 885.4 4557.2 4729.8 4818.5 4897.4 5234.2 5234.0 5691.5 5804.9 5871.6 5897.4 4701.0 4716.0 4983.9 5219.7 5316.5 2515.7 2695.8 2754.5 2820.3 2912.9 5148.6 5400.3 5565.0 5636.6 5820.7 5041.8 5672.2 5686.8 5717.9 5730.7 5254.6 5612.5 6090.9 6111.0 6136.5 5308.9 5473.6 5844.2 5850.8 5925.7 5240.8 5643.2 5800.7 5803.5 5839.9 5624.0 6039.5 6084.9 6090.5 6136.4 3403.7 3818.7 3937.6 3949.3 3954.4 4914.0 5802.4 5882.2 5896.8 5911.8 5407.7 5563.4 5686.0 5687.1 5705.0 4676.4 4706.4 5422.1 5642.7 5671.4 4942.9 5500.2 5516.6 5525.3 5537.4 5316.8 5362.4 5666.5 5668.8 5687.0 4955.5 4968.8 5544.4 5557.7 5568.6 17:28:28 INFO - PROCESS | 1663 | 17:28:28 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17916.16 Median:641.29 stddev:25573.55 (3987.9%) stddev-sans-first:25612.39 17:28:28 INFO - PROCESS | 1663 | Values: 639.2 653.0 715.6 715.9 749.3 14696.9 15429.4 15478.2 15493.4 15499.6 14091.9 14106.3 14118.7 14125.9 14163.4 715.8 741.5 901.1 909.6 933.3 911.4 925.8 928.7 930.8 931.0 988.9 991.6 993.3 995.9 996.1 756.9 783.7 784.7 786.3 984.5 700.3 727.3 728.5 729.2 730.0 14878.3 14948.3 14954.6 14955.7 14979.4 12232.8 12303.4 12327.5 12551.9 12680.0 10497.7 10869.7 10895.2 11264.8 11497.8 3920.3 3947.7 3986.7 3988.1 4107.8 79922.0 80353.0 80775.0 81211.6 81237.4 9403.5 9482.8 9489.1 9668.4 9722.6 19672.8 20264.0 20317.4 20942.7 21794.3 7470.8 7506.2 7612.0 7790.0 7879.1 4069.8 4110.6 4113.3 4121.0 4439.9 3351.6 3442.9 3449.6 3449.6 3455.0 627.9 643.4 644.3 649.3 654.6 566.6 594.5 600.4 601.2 605.1 7701.8 7819.7 7905.6 7984.8 8094.6 531.5 537.5 560.6 562.7 565.0 3712.5 3807.7 3816.9 3843.5 3856.2 91329.5 91516.6 93679.0 96351.0 96375.5 73693.5 74894.6 79885.2 81817.0 82155.6 67366.0 69900.0 70423.9 72052.0 73137.3 7287.6 7325.7 7377.6 7520.2 7718.5 33651.0 45656.1 46657.3 46676.8 46822.3 61762.1 64599.7 64883.0 64950.7 65210.0 9738.8 10015.8 10217.8 10355.7 10570.1 11292.7 11308.7 11501.3 11535.7 11824.9 8847.3 10159.5 10283.0 10447.1 10560.3 10416.2 10610.6 10615.7 10871.9 10881.1 10219.7 10250.7 10466.8 10472.7 10499.8 8401.2 8658.0 8749.5 8858.3 9124.8 7538.0 8590.5 8733.4 8814.2 8825.5 17:28:28 INFO - PROCESS | 1663 | -------- Summary: end -------- 17:28:28 INFO - PROCESS | 1663 | 17:28:29 INFO - TEST-INFO | 1663: exit 0 17:28:29 INFO - TEST-OK | dromaeo_css | took 1115859ms 17:28:29 INFO - TEST-START | kraken 17:28:29 INFO - operating with platform_type : mac_ 17:28:29 INFO - Initialising browser for kraken test... 17:28:29 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpY3jbmH/profile http://localhost:49275/getInfo.html) 17:28:31 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 17:28:31 INFO - PROCESS | 1669 | colorDepth:24 17:28:31 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 17:28:31 INFO - PROCESS | 1669 | __metrics 17:28:31 INFO - PROCESS | 1669 | 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" 17:28:31 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:28:32 INFO - TEST-INFO | 1669: exit 0 17:28:32 INFO - Browser initialized. 17:28:32 INFO - Running cycle 1/1 for kraken test... 17:28:32 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/T/tmpY3jbmH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:28:44 INFO - PROCESS | 1671 | RSS: Main: 239382528 17:28:44 INFO - PROCESS | 1671 | 17:28:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 17:28:47 INFO - PROCESS | 1671 | RSS: Main: 287313920 17:28:47 INFO - PROCESS | 1671 | 17:28:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 17:28:51 INFO - PROCESS | 1671 | RSS: Main: 296407040 17:28:51 INFO - PROCESS | 1671 | 17:28:54 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 17:28:55 INFO - PROCESS | 1671 | RSS: Main: 386600960 17:28:55 INFO - PROCESS | 1671 | 17:28:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 17:28:58 INFO - PROCESS | 1671 | RSS: Main: 288575488 17:28:58 INFO - PROCESS | 1671 | 17:29:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:29:00 INFO - PROCESS | 1671 | RSS: Main: 290103296 17:29:00 INFO - PROCESS | 1671 | 17:29:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 17:29:04 INFO - PROCESS | 1671 | RSS: Main: 326676480 17:29:04 INFO - PROCESS | 1671 | 17:29:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 17:29:08 INFO - PROCESS | 1671 | RSS: Main: 328658944 17:29:08 INFO - PROCESS | 1671 | 17:29:11 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 17:29:11 INFO - PROCESS | 1671 | RSS: Main: 328916992 17:29:11 INFO - PROCESS | 1671 | 17:29:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:29:14 INFO - PROCESS | 1671 | RSS: Main: 316674048 17:29:14 INFO - PROCESS | 1671 | 17:29:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:29:16 INFO - PROCESS | 1671 | RSS: Main: 324378624 17:29:16 INFO - PROCESS | 1671 | 17:29:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:29:19 INFO - PROCESS | 1671 | RSS: Main: 325599232 17:29:19 INFO - PROCESS | 1671 | 17:29:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:29:23 INFO - PROCESS | 1671 | RSS: Main: 329900032 17:29:23 INFO - PROCESS | 1671 | 17:29:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:29:26 INFO - PROCESS | 1671 | RSS: Main: 327606272 17:29:26 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 17:29:28 INFO - PROCESS | 1671 | RSS: Main: 374345728 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | __start_tp_report 17:29:28 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 17:29:28 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 17:29:28 INFO - PROCESS | 1671 | |i|pagename|runs| 17:29:28 INFO - PROCESS | 1671 | |0;ai-astar;112;107;112;112;113;112;111;113;113;111 17:29:28 INFO - PROCESS | 1671 | |1;audio-beat-detection;158;118;144;217;137;136;142;134;190;122 17:29:28 INFO - PROCESS | 1671 | |2;audio-dft;186;186;186;186;185;185;184;184;185;189 17:29:28 INFO - PROCESS | 1671 | |3;audio-fft;81;73;56;126;57;61;56;60;111;56 17:29:28 INFO - PROCESS | 1671 | |4;audio-oscillator;94;100;134;79;79;77;101;80;89;100 17:29:28 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;83;87;83;86;84;86;84;86;83 17:29:28 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;93;93;93;93;94;94;93;92 17:29:28 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;84;85;85;86;99;129;128;80;124 17:29:28 INFO - PROCESS | 1671 | |8;json-parse-financial;60;59;61;58;57;60;57;122;64;57 17:29:28 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;75;75;70;69;69;67;68;68;67 17:29:28 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;86;86;86;83;85;85;80;83;83;87 17:29:28 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;151;148;137;135;136;136;135;132;210;151 17:29:28 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;169;168;167;166;166;165;167;167;166;161 17:29:28 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;67;68;66;66;65;65;65;64;65 17:29:28 INFO - PROCESS | 1671 | __end_tp_report 17:29:28 INFO - PROCESS | 1671 | __start_cc_report 17:29:28 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2266 17:29:28 INFO - PROCESS | 1671 | __end_cc_report 17:29:28 INFO - PROCESS | 1671 | __startTimestamp1462840168783__endTimestamp 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | ------- Summary: start ------- 17:29:28 INFO - PROCESS | 1671 | Number of tests: 14 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:1.78 (1.6%) stddev-sans-first:1.88 17:29:28 INFO - PROCESS | 1671 | Values: 112.0 107.0 112.0 112.0 113.0 112.0 111.0 113.0 113.0 111.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:143.00 stddev:31.06 (21.7%) stddev-sans-first:32.80 17:29:28 INFO - PROCESS | 1671 | Values: 158.0 118.0 144.0 217.0 137.0 136.0 142.0 134.0 190.0 122.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.00 stddev:1.43 (0.8%) stddev-sans-first:1.51 17:29:28 INFO - PROCESS | 1671 | Values: 186.0 186.0 186.0 186.0 185.0 185.0 184.0 184.0 185.0 189.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:73.70 Median:58.50 stddev:25.27 (43.2%) stddev-sans-first:26.67 17:29:28 INFO - PROCESS | 1671 | Values: 81.0 73.0 56.0 126.0 57.0 61.0 56.0 60.0 111.0 56.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:79.00 stddev:17.24 (21.8%) stddev-sans-first:18.29 17:29:28 INFO - PROCESS | 1671 | Values: 94.0 100.0 134.0 79.0 79.0 77.0 101.0 80.0 89.0 100.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.58 17:29:28 INFO - PROCESS | 1671 | Values: 87.0 83.0 87.0 83.0 86.0 84.0 86.0 84.0 86.0 83.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 17:29:28 INFO - PROCESS | 1671 | Values: 93.0 93.0 93.0 93.0 93.0 93.0 94.0 94.0 93.0 92.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:98.40 Median:84.50 stddev:20.37 (24.1%) stddev-sans-first:20.93 17:29:28 INFO - PROCESS | 1671 | Values: 84.0 84.0 85.0 85.0 86.0 99.0 129.0 128.0 80.0 124.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:59.50 stddev:19.97 (33.6%) stddev-sans-first:21.09 17:29:28 INFO - PROCESS | 1671 | Values: 60.0 59.0 61.0 58.0 57.0 60.0 57.0 122.0 64.0 57.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:3.46 (5.0%) stddev-sans-first:3.11 17:29:28 INFO - PROCESS | 1671 | Values: 64.0 75.0 75.0 70.0 69.0 69.0 67.0 68.0 68.0 67.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.50 stddev:2.12 (2.5%) stddev-sans-first:2.17 17:29:28 INFO - PROCESS | 1671 | Values: 86.0 86.0 86.0 83.0 85.0 85.0 80.0 83.0 83.0 87.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:142.50 stddev:23.23 (16.3%) stddev-sans-first:24.60 17:29:28 INFO - PROCESS | 1671 | Values: 151.0 148.0 137.0 135.0 136.0 136.0 135.0 132.0 210.0 151.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:167.00 stddev:2.15 (1.3%) stddev-sans-first:2.03 17:29:28 INFO - PROCESS | 1671 | Values: 169.0 168.0 167.0 166.0 166.0 165.0 167.0 167.0 166.0 161.0 17:29:28 INFO - PROCESS | 1671 | 17:29:28 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.16 (1.8%) stddev-sans-first:1.22 17:29:28 INFO - PROCESS | 1671 | Values: 66.0 67.0 68.0 66.0 66.0 65.0 65.0 65.0 64.0 65.0 17:29:28 INFO - PROCESS | 1671 | -------- Summary: end -------- 17:29:28 INFO - PROCESS | 1671 | 17:29:29 INFO - TEST-INFO | 1671: exit 0 17:29:29 INFO - TEST-OK | kraken | took 60317ms 17:29:29 INFO - SUITE-END | took 1176s 17:29:29 INFO - Completed test suite (00:19:36) 17:29:29 INFO - javascript benchmark 17:29:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.5353927219826, 3446.5519482467275, 3462.7056700180483, 3464.5102605879492, 3507.2634746703206, 5662.0, 5696.287964004499, 5703.144528320749, 5820.621659592129, 5830.12548117782, 5726.226959360485, 5800.187973683677, 5847.210626565412, 5892.941070589292, 5914.319853216882, 5129.179331306992, 5158.3552055993005, 5225.6864588124645, 5235.712035838027, 5327.733613319338, 5307.336582927134, 5332.466753324656, 5348.358197016344, 5362.24928510007, 5382.1926710993275, 5181.01560703467, 5236.057509648262, 5247.370315562119, 5267.631265811395, 5299.046171689094, 5203.661761985459, 5203.7918483261, 5220.086484865134, 5275.683458992473, 5300.151975683911, 5193.987172501364, 5203.4796520348045, 5206.01085793698, 5248.8425347239645, 5312.946870531284, 5534.418886016951, 5706.572007099443, 5737.598368114233, 5742.196092547047, 5759.0, 5379.273798037254, 6155.599886007395, 6177.3822617738315, 6177.413145751191, 6179.443850053472, 6115.235595550556, 6180.783672571483, 6195.19462469876, 6210.844728881769, 6213.813585592439, 5773.624714393551, 5793.826185214451, 5929.9703501482645, 5964.7912323068485, 5992.880142397171, 6274.247090349188, 6296.905546416808, 6309.400606942333, 6312.684365781693, 6316.30520642729, 6095.969520152371, 6132.325444201134, 6134.650895842656, 6137.263528376623, 6138.508919286446, 6100.023996160682, 6122.8251069701855, 6223.066540019034, 6274.996000639876, 6278.183836101278, 6176.7838125665385, 6260.123582698426, 6264.624122552661, 6273.686315684197, 6285.120083188357, 6203.596766210182, 6239.313675495692, 6278.466330361879, 6296.150019747367, 6305.0, 4858.17411040117, 4903.485134060944, 4931.926021109686, 4984.152694042022, 5027.497250274943, 4494.52807455219, 4517.983453722952, 4526.072155208174, 4544.909101818012, 4552.6130278926, 5672.574556908248, 5680.7727690892725, 5683.318001839723, 5687.345955215121, 5699.0, 6176.876462470816, 6198.845028874314, 6209.285932117774, 6210.875782484415, 6225.719842607003, 5627.493525582717, 5740.024195886709, 5771.220885180531, 5778.855528611818, 5785.595008349375, 5285.863539338984, 5410.675359478444, 5420.105682562412, 5436.320459942507, 5449.972750136303, 5851.882962340729, 6018.766152938637, 6082.635041897501, 6219.346968568235, 6356.364363563699, 6183.196184495987, 6204.3795620437595, 6217.067439884054, 6315.084312774714, 6316.652584107918, 6301.306856245901, 6304.810855674154, 6307.085472606538, 6321.810345689452, 6329.050642403492, 6057.333693293822, 6109.725062372115, 6152.0156774915795, 6176.28972668131, 6178.474829639351, 6175.84560385994, 6300.653464059521, 6322.525810564318, 6328.398802113792, 6331.081993110881, 6043.395660433922, 6077.756889724362, 6084.056971169476, 6088.939110608837, 6099.9390006099375, 5914.822555323347, 6013.6692481913915, 6028.2766068072115, 6040.426159514839, 6058.636481811105, 5816.243888294494, 5953.642781433128, 5954.434328738763, 5956.970215148896, 5979.940200597938, 6082.42216989403, 6097.554926111998, 6118.242200336633, 6124.199565122063, 6125.248816407016, 5787.797427090031, 5904.232449781501, 5913.615614985012, 5966.433188847052, 6033.487153591818], "value": 5717.3738560106995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.3003005701845, 524.2529395611256, 530.4483337329178, 533.7358007786146, 584.0771580902174, 29662.961796337106, 38115.04712382188, 42697.0, 44040.67877963666, 45930.0, 43947.78026109873, 44330.77834610822, 44378.55621443785, 44411.55588444114, 44485.555144448546, 8638.568071596395, 8747.256483198937, 8785.516796576187, 9638.614455421792, 9915.25635577331, 8726.258268047224, 11629.592964246252, 11914.940425297862, 11938.761224775499, 12003.939980300087, 10874.347539147637, 11236.101111911068, 11847.7630447391, 11922.225055371417, 11923.880761192408, 8678.349123815677, 10392.37645741258, 11584.76830463386, 11619.012383947374, 11872.228305160137, 11637.70905727355, 11696.883031169664, 11699.23954942926, 11701.239419437708, 11720.120990925629, 34929.650703492895, 40469.785906422854, 40849.387259191135, 40912.59087409118, 40929.0, 3125.3905488429764, 3180.1927359477672, 3245.383377158332, 3444.7244220462303, 4087.121268927165, 28064.0, 29012.564811527845, 30598.69401305981, 30607.0, 30636.38727225443, 2753.421781425903, 2759.089500464862, 2763.847988360639, 2812.8874845006176, 2823.4917714811327, 500185.0, 500831.0, 501233.0, 503786.48106759234, 505890.0, 35428.468572971426, 35955.28089438197, 63144.68427657832, 64698.206688297054, 64965.67517162478, 37656.0, 37834.72991620511, 37989.62010379861, 37990.810045950144, 38025.80987095047, 15134.0, 15493.225338733244, 15515.922420387826, 15520.5343839685, 15530.9223453882, 11883.049356051495, 12083.335416552174, 12989.480420783251, 13112.475500980045, 13160.934195329153, 8183.754487365389, 8184.099749027602, 8237.629306681214, 8270.586470676562, 8277.420580559423, 3407.352603005421, 3429.5884493860895, 3431.330890476383, 3434.6737059979264, 3464.8960531184107, 986.2455221755363, 1038.6416686243233, 1095.2114477576133, 1097.544519024596, 1133.4672703829187, 26638.866805666235, 27352.58971115435, 29131.563026554617, 29382.706172937997, 29871.850640747092, 1117.0951529261324, 1122.2817396866012, 1125.0999200639455, 1155.6359746679768, 1237.9690507737198, 15344.462943796914, 15377.384904603914, 15423.305951232222, 15592.171178588906, 15680.76478852818, 676855.0, 713456.0, 715524.0, 717286.0, 718494.0, 59451.0, 61211.38788612057, 61230.0, 61315.0, 61435.6928215365, 1316.8288122544009, 1351.1487762709626, 1451.9346629401914, 1473.38854375433, 1489.4637930345068, 29354.853225733736, 30178.396432070796, 30498.932537361085, 30573.0, 30871.555756602622, 49957.0, 50448.747756260986, 50454.99090018252, 50550.0, 50566.747166265406, 456887.0, 472478.0, 472698.0, 472742.27257728355, 472772.0, 23677.408064798517, 24150.758492415553, 24190.637140442555, 24942.002319907002, 24975.375615609755, 39238.607613923494, 40608.0, 45620.0, 50187.49812501828, 50270.0, 36298.0, 37694.057648559006, 39674.008149796486, 39786.602133979446, 40286.79856600698, 37462.0, 37494.06264843401, 37570.0, 38313.042173945876, 38357.6164238354, 37882.86351409462, 38553.80723096366, 38578.0, 39041.0, 39082.21835563216, 31462.370752585277, 33236.968466907965, 33539.0, 33640.66359336375, 33643.663563364054, 22832.885835570716, 24912.62631060572, 25046.998120074993, 25117.0, 25605.487890242464], "value": 18998.95362813757, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.3663283796668, 394.97827619480915, 395.78231972415165, 396.7500474700938, 419.9958000419997, 14514.0, 20058.197672093116, 20937.0, 21457.67813482797, 21499.24752633658, 15894.682106357895, 18132.0, 20497.795022049813, 20991.845448500328, 21075.578488430223, 6054.697265136748, 6267.62394256346, 6273.05904114382, 6283.026130949687, 6302.275238347603, 6172.506199504029, 6260.092286618437, 6260.405261500173, 6265.185525881629, 6319.810405687814, 5996.130561068664, 6142.938570614304, 6203.348648391921, 6204.162438070848, 6212.751489940397, 5989.281286245657, 6056.091586262052, 6190.0405437157315, 6194.318624951252, 6194.907076393858, 5120.18077107661, 5375.247465354853, 5393.406725260218, 5400.432954539756, 5401.675899446046, 26441.603375949377, 26589.0, 26591.734082659314, 26870.0, 27578.44843103147, 1944.7277381166648, 2166.891655417223, 2216.1578600132007, 2812.0, 2926.1806694125466, 9966.700998970042, 9978.800423991552, 10096.658491570373, 10275.280730348879, 10356.964303569657, 1712.4263371770471, 1757.1916918217635, 1758.5603599100225, 1800.4238643634042, 1825.2881376263267, 103027.0, 119130.40434797858, 119637.40181299124, 121392.07471327623, 121997.0, 9970.30207885441, 11529.365884876244, 11635.709107272385, 11708.238964467282, 11710.65786842635, 13675.863241367659, 15066.698666026838, 15101.093934363973, 15144.848551514564, 15154.545363639108, 6881.346272104142, 7820.569868657279, 8123.04686526542, 8606.096359882247, 8801.779955501035, 7265.673044713, 8625.870611940825, 8639.6976105836, 8726.607302671395, 8737.43206691563, 4637.3275874997935, 4642.210824159901, 4668.603168730628, 4683.531646835358, 4716.103940251342, 1727.8358555937166, 1932.94201173965, 1938.975198690048, 2001.5896741168026, 2004.3485867092961, 785.7878372839301, 925.726910561386, 949.3117489819825, 960.500539719342, 964.2864280432569, 17260.223289951984, 20642.690359644617, 20675.58648827015, 20835.0, 20912.790872091387, 962.3696478806393, 989.2086330935367, 999.480270259461, 1027.8304079826894, 1039.6621098143135, 10694.304870183412, 11017.504212310465, 11047.613333533449, 11129.276597021168, 11143.609973650884, 598320.0, 612742.4265908045, 619630.0, 626865.0, 632248.0, 133826.0, 135328.0, 136227.0, 136269.0, 140173.29913350573, 1007.6876168387823, 1025.564135242534, 1051.395447617623, 1054.1935419404163, 1054.541274545575, 16643.751718620908, 16676.749848752523, 18144.45566633003, 18650.906745466185, 18765.061746912328, 51958.480415195365, 52776.47223527716, 52978.58299837949, 53269.0, 54264.18603721026, 73459.63270183615, 74069.18550294214, 75185.24814751782, 76299.61850190714, 76301.0, 8023.598820058857, 8119.5534245617055, 8269.669213231404, 8430.030546487233, 8637.179467950535, 12101.75796484083, 12526.436310365872, 12578.685532861386, 12928.612141635766, 12948.417321220379, 10529.05238528536, 10643.254972151875, 10683.57265709363, 11316.321020738782, 11366.488508016993, 9712.0, 10250.38497690141, 10794.136469082612, 10999.725006874573, 11198.272112312674, 5781.739821708117, 9858.408495490006, 11059.668209953714, 11358.864113588575, 11521.251118677259, 8056.516609003478, 8071.4753541019645, 8111.675532978851, 8366.24703776663, 8412.284955778827, 9652.855207172039, 10797.190210734068, 11046.834297485384, 11068.893110688867, 11080.501377438195], "value": 9588.929299170333, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.9905667919095, 1582.8338024507427, 1592.697387496376, 1852.6294741051793, 2001.2495314257153, 7159.606221657807, 8378.0, 8679.826403471927, 8692.087330830258, 8791.956040219806, 8459.746207613765, 8546.52994085325, 8628.439151455152, 8631.050584435707, 8641.04948455671, 2068.6276470235352, 2271.954560908781, 2296.0700916128953, 2328.39461739948, 2331.6152834782324, 2354.8469349492316, 2394.892229849661, 2398.9160379386726, 2399.5081008393327, 2400.4238982644215, 2273.7726227377298, 2282.6119559674894, 2296.1619009061656, 2316.5598536278053, 2317.362725250561, 2009.9598008039904, 2237.7650346713685, 2307.169419009164, 2310.976890231093, 2356.2931120663816, 2336.9649455258186, 2359.00921612923, 2364.5034542746043, 2365.562370961378, 2369.9052037918464, 8481.618327175293, 8569.828603427897, 8577.01364343096, 8578.485290882567, 8581.613827377783, 4999.500049995007, 5182.481751824825, 5186.766595503173, 5188.299579556749, 5190.558802501754, 3210.983945080283, 3285.3593549257903, 3442.345954268706, 3452.4993875887853, 3455.4298540740747, 1017.7455636090978, 1047.261680515235, 1048.7430579507993, 1048.8479170520231, 1053.9314944528658, 11193.99254067138, 11284.210105292634, 11294.548218071268, 11300.943495282554, 11309.0, 4247.68142389322, 4461.196984542748, 4533.637309015271, 4598.965232822588, 4690.976545117321, 9458.43249405038, 9557.952210238904, 9561.235101191887, 9568.09103135201, 9600.231981441468, 5211.4527974562125, 5220.504052115043, 5222.503862133091, 5226.111561034557, 5244.187150991603, 4212.199682060399, 4407.537208593116, 4582.381378513858, 4660.4407471103, 4770.95229047712, 3556.4309710446723, 3908.5895980921596, 3938.8424463021734, 4012.8595499157973, 4135.069609337875, 1301.258282778807, 1322.715616142534, 1326.303690562443, 1336.411978729356, 1339.8325209348832, 893.7050773244814, 917.4907926100944, 920.8987011428737, 927.1655510040882, 935.2985261054209, 5752.942470575241, 5875.764969401176, 5918.654696877382, 5955.493783028491, 6011.639301641915, 402.9738067025634, 416.54388444653057, 417.7472629059436, 424.5012110769895, 430.69851104227166, 3309.4870295104297, 3752.7935963521722, 3813.904652383713, 3821.0, 3884.3008258513173, 9951.452670103214, 10293.845592316122, 10440.0, 10447.895521044844, 10453.790924181474, 10261.58953641846, 10520.421376824195, 10528.315279603652, 10552.314099583502, 10571.682849514527, 7548.245175482518, 7934.246246606564, 8064.072631647436, 8065.0, 8113.59432028408, 5821.70891455417, 5917.674527900835, 6014.939850601438, 6024.638521688713, 6058.454739073504, 9597.712068637951, 9875.358101723365, 9950.502474876082, 9952.353097048668, 9963.850542241727, 9096.727098187064, 9758.75603109928, 9945.751356216153, 9947.353422027545, 10065.69802905914, 6794.218664853605, 6989.440844732511, 6991.986162006582, 7451.18037015917, 7613.505122167041, 7639.465237433548, 7716.035495563055, 7996.720114795954, 8295.460795048302, 8309.95845020771, 7315.8536829264185, 7477.66350514239, 7496.662650180646, 7533.39732821362, 7652.7704168875025, 7258.165310989304, 7276.636168191463, 7279.599622020628, 7351.816204594714, 7434.628268586441, 6956.443484521327, 6984.092068031124, 7053.118360204974, 7059.364657180673, 7059.788206353817, 4799.776754569532, 4913.803447862046, 4932.334134891744, 4966.155753521982, 5113.821016264413, 4596.333531637961, 4856.174450343379, 4980.252962055721, 5030.170021946338, 5198.324217851655], "value": 4414.673619127133, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.44199278309867, 316.5663041632962, 319.07468341808755, 323.8202797447416, 326.54936188060475, 4926.729029903354, 5202.673318303832, 5421.993862270961, 5437.564994800407, 5466.972665136669, 4955.975220123894, 5284.957007888958, 5343.438938911407, 5360.571154307656, 5393.892122157555, 3463.6882680558683, 3494.318607871466, 3540.274243780019, 3951.3677811550156, 4164.458620379346, 3539.433690609503, 3874.7868867212433, 4070.4504891839665, 4103.384492326145, 4128.5756414037105, 3692.6307369262995, 4079.2045551117394, 4082.2447847148374, 4110.445089912853, 4119.155573107506, 3858.672012878909, 3878.1855810279876, 4110.609492098246, 4121.62905338518, 4138.0, 3562.946555801665, 3583.157957879896, 3586.4620306953907, 3586.8744593939346, 3587.2287458196615, 5163.63854530183, 5168.61235407346, 5184.248283998837, 5190.71451070191, 5191.351081114861, 3969.6625786807867, 4072.2669919414493, 4424.0, 4715.528447155291, 4756.571908528214, 4976.651634385595, 5578.24693666358, 5604.971975140098, 5624.184493248497, 5648.774049038075, 3758.342290099222, 3782.2624588205313, 3936.547297060846, 3979.960200397988, 4002.799860006988, 5386.841829006743, 5390.838274851761, 5724.685142317129, 5977.491913187384, 5998.790042348541, 3865.5168103987003, 3888.9027774305305, 3898.8830335089424, 3905.1408690088133, 3919.9020024499046, 3505.544279243733, 3921.8627348042683, 3971.2923442919246, 3976.4234186042863, 3988.441618173458, 4008.0, 4047.8583249586713, 4058.5941405859767, 4629.611116664987, 4635.7218566886095, 5050.823221187241, 5659.801906933238, 5701.486866182064, 5705.2012718219485, 5706.3152421709665, 1143.0, 1154.6824623228679, 1158.9188756787112, 1274.6679719692943, 1304.230504002643, 778.2373274191211, 807.2290962131151, 822.5311572403792, 863.2131487820277, 885.3581153663671, 4557.225271703818, 4729.787159577827, 4818.518148185153, 4897.428849853855, 5234.241035049897, 5233.979374022029, 5691.544676425876, 5804.9419505805245, 5871.64770113786, 5897.380775018647, 4701.0, 4715.995701826779, 4983.8504844854715, 5219.660722053054, 5316.548093412026, 2515.666696650778, 2695.8382497049847, 2754.4628797384717, 2820.252633052243, 2912.8689208985224, 5148.56237219833, 5400.326838215109, 5565.02612042899, 5636.589863132827, 5820.679862607597, 5041.798328066836, 5672.220611900139, 5686.829395118153, 5717.914231286534, 5730.656160630376, 5254.632175747661, 5612.466815652507, 6090.893644241469, 6111.027831909206, 6136.539759517965, 5308.920366194588, 5473.562115030868, 5844.15259787337, 5850.795222167204, 5925.674087925206, 5240.816571419982, 5643.1535269709875, 5800.6809625471005, 5803.47768700819, 5839.912401313899, 5623.971880140737, 6039.456448919618, 6084.878302433838, 6090.451859332681, 6136.447719705248, 3403.676650718178, 3818.7135964802196, 3937.5865534118507, 3949.328614135661, 3954.367301231789, 4913.983063408419, 5802.361740208489, 5882.23530940975, 5896.7936122235515, 5911.7635294587735, 5407.72832436386, 5563.415841336763, 5686.004949133802, 5687.118496633028, 5705.001624715575, 4676.438827340605, 4706.364640773589, 5422.147178275501, 5642.6614403135945, 5671.40450252734, 4942.901141977069, 5500.1749737539685, 5516.641418307797, 5525.254090697865, 5537.418571049981, 5316.787328506971, 5362.436944120967, 5666.546676265806, 5668.801591944262, 5687.0, 4955.529224723646, 4968.801247950041, 5544.445555444423, 5557.694326811902, 5568.582356323373], "value": 4083.758680338337, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.2169592249494, 652.954293199476, 715.6207210178604, 715.8926161075834, 749.325606953742, 14696.853031469682, 15429.382824687027, 15478.226088695577, 15493.380264789395, 15499.61250968725, 14091.859081409182, 14106.294685265748, 14118.717625647507, 14125.858741412583, 14163.433462661507, 715.8210447388153, 741.5217184915726, 901.1034021148965, 909.6043221198767, 933.2580598424236, 911.421247507834, 925.7778133248006, 928.7074571509953, 930.7580029192391, 930.972070837876, 988.85661579071, 991.6281394477071, 993.2997236947971, 995.945223012734, 996.0537489385077, 756.8940348351235, 783.6669415498391, 784.6625950841136, 786.2923368967975, 984.517586382671, 700.2577268095835, 727.294524311417, 728.5082569265777, 729.2452311857203, 729.9635018249066, 14878.256087195598, 14948.252587370587, 14954.62613434662, 14955.70088598222, 14979.400823967027, 12232.755344893052, 12303.446344914411, 12327.506899724, 12551.874481255161, 12680.0, 10497.690508088208, 10869.728256793564, 10895.182861285435, 11264.774704505946, 11497.827532587018, 3920.3193596878, 3947.7039222058174, 3986.7009974251755, 3988.142549351903, 4107.815148318333, 79922.0, 80353.0, 80775.0, 81211.59394203051, 81237.37525249462, 9403.4828084456, 9482.762930926781, 9489.145976861977, 9668.419894806335, 9722.61109555624, 19672.803271967095, 20263.986800659906, 20317.390478285673, 20942.685859712117, 21794.346169614935, 7470.813229669302, 7506.24937506245, 7612.027658455809, 7790.026246719161, 7879.054513458422, 4069.8168582413864, 4110.630043296058, 4113.300738874398, 4120.958790412057, 4439.866803995886, 3351.631320554786, 3442.896713098611, 3449.5515582974053, 3449.6377880322707, 3454.9654503455145, 627.8587317853538, 643.353429803045, 644.2944975252092, 649.2890285137767, 654.6464908949209, 566.64301490061, 594.5095296380504, 600.3756093662629, 601.1883956658563, 605.0863196573118, 7701.768946931489, 7819.687212511436, 7905.56519391439, 7984.7548352015365, 8094.635741391651, 531.5136649965316, 537.4813305160538, 560.6047736345866, 562.7073921560766, 564.9887002260014, 3712.5173727415263, 3807.7334586579227, 3816.9427458588143, 3843.5003449551377, 3856.2287542491613, 91329.54335228281, 91516.6272505913, 93679.0, 96351.0, 96375.51812240893, 73693.5261294771, 74894.62552687201, 79885.20114798893, 81817.0, 82155.5892220547, 67366.0, 69900.0, 70423.9436408444, 72052.0, 73137.26862731518, 7287.635618218962, 7325.743598974011, 7377.557346559223, 7520.172780993977, 7718.459707820623, 33650.99047028593, 45656.062922875666, 46657.3001404986, 46676.7666161667, 46822.29766553437, 61762.0735688974, 64599.67700161657, 64883.0, 64950.67524662346, 65210.0, 9738.83523093871, 10015.849762253718, 10217.795644087226, 10355.689329320132, 10570.10154136907, 11292.661220163409, 11308.717282068013, 11501.252418592765, 11535.653930382103, 11824.940875295568, 8847.336449766164, 10159.542820572944, 10283.0, 10447.111995480469, 10560.313579617301, 10416.21878359111, 10610.628627997983, 10615.734606634896, 10871.945640271748, 10881.075108615856, 10219.7445063874, 10250.743731406774, 10466.790664186827, 10472.68581942543, 10499.842502362319, 8401.15988401155, 8658.047614762498, 8749.47503149813, 8858.335624828034, 9124.817503649758, 7538.0, 8590.527520986405, 8733.388662793797, 8814.162654547808, 8825.470471771714], "value": 6735.153697862164, "unit": "score"}], "name": "dromaeo_css", "value": 7085.024368843682, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 112.0, 112.0, 113.0, 112.0, 111.0, 113.0, 113.0, 111.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 118.0, 144.0, 217.0, 137.0, 136.0, 142.0, 134.0, 190.0, 122.0], "value": 149.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 186.0, 186.0, 185.0, 185.0, 184.0, 184.0, 185.0, 189.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 56.0, 126.0, 57.0, 61.0, 56.0, 60.0, 111.0, 56.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 100.0, 134.0, 79.0, 79.0, 77.0, 101.0, 80.0, 89.0, 100.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 83.0, 86.0, 84.0, 86.0, 84.0, 86.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 94.0, 93.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 85.0, 86.0, 99.0, 129.0, 128.0, 80.0, 124.0], "value": 98.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 61.0, 58.0, 57.0, 60.0, 57.0, 122.0, 64.0, 57.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 75.0, 70.0, 69.0, 69.0, 67.0, 68.0, 68.0, 67.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 83.0, 85.0, 85.0, 80.0, 83.0, 83.0, 87.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 137.0, 135.0, 136.0, 136.0, 135.0, 132.0, 210.0, 151.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 167.0, 166.0, 166.0, 165.0, 167.0, 167.0, 166.0, 161.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 66.0, 66.0, 65.0, 65.0, 65.0, 64.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1488.5, "alertThreshold": 2.0}]} 17:29:29 INFO - Return code: 0 17:29:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:29:30 ERROR - # TBPL SUCCESS # 17:29:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:29:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:29:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:29:30 INFO - 2016-05-09 17:29:30.068 screenresolution[1675:9762] starting screenresolution argv=screenresolution get 17:29:30 INFO - 2016-05-09 17:29:30.070 screenresolution[1675:9762] Display 0: 1600x1200x32@60 17:29:30 INFO - 2016-05-09 17:29:30.076 screenresolution[1676:9765] starting screenresolution argv=screenresolution list 17:29:30 INFO - Available Modes on Display 0 17:29:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:29:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:29:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:29:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:29:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:29:30 INFO - Intel Iris: 17:29:30 INFO - Chipset Model: Intel Iris 17:29:30 INFO - Type: GPU 17:29:30 INFO - Bus: Built-In 17:29:30 INFO - VRAM (Dynamic, Max): 1536 MB 17:29:30 INFO - Vendor: Intel (0x8086) 17:29:30 INFO - Device ID: 0x0a2e 17:29:30 INFO - Revision ID: 0x0009 17:29:30 INFO - Displays: 17:29:30 INFO - Display: 17:29:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:29:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:29:30 INFO - Display Serial Number: 1600x1200 60 17:29:30 INFO - Main Display: Yes 17:29:30 INFO - Mirror: Off 17:29:30 INFO - Online: Yes 17:29:30 INFO - Rotation: Supported 17:29:30 INFO - Return code: 0 17:29:30 INFO - Running post-action listener: _resource_record_post_action 17:29:30 INFO - ##### 17:29:30 INFO - ##### Finished run-tests step (success) 17:29:30 INFO - ##### 17:29:30 INFO - Running post-run listener: _resource_record_post_run 17:29:31 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37570048; Write bytes: 694902272; Read time: 393; Write time: 1819 17:29:31 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 379187712; Write bytes: 369780736; Read time: 27950; Write time: 664 17:29:31 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31444480; Write bytes: 295056896; Read time: 307; Write time: 1095 17:29:31 INFO - Running post-run listener: _upload_blobber_files 17:29:31 INFO - Blob upload gear active. 17:29:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:29:31 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 17:29:31 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'] 17:29:31 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 17:29:31 INFO - (blobuploader) - INFO - Open directory for files ... 17:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:32 INFO - (blobuploader) - INFO - Done attempting. 17:29:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:29:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:29:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:29:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:29:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:29:33 INFO - (blobuploader) - INFO - Done attempting. 17:29:33 INFO - (blobuploader) - INFO - Iteration through files over. 17:29:33 INFO - Return code: 0 17:29:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:29:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:29:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/306ac554973be8c7d5a32d97595250473f2b3aba25aa5a90a72a985d73fe3b7a146b5ca8805af20aa81003d936e638fb31b29b8647c791d3fbc0d4232a9111f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07c6ebfd8eebd00251a5e7550665290bad02e4f01f83e8912951b86f53dae47cecb497d9a8f584689c84f91f22123f6a5692a1f0fd248e53a24eaa45646e524f"} 17:29:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:29:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:29:33 INFO - Contents: 17:29:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/306ac554973be8c7d5a32d97595250473f2b3aba25aa5a90a72a985d73fe3b7a146b5ca8805af20aa81003d936e638fb31b29b8647c791d3fbc0d4232a9111f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07c6ebfd8eebd00251a5e7550665290bad02e4f01f83e8912951b86f53dae47cecb497d9a8f584689c84f91f22123f6a5692a1f0fd248e53a24eaa45646e524f"} 17:29:33 INFO - Running post-run listener: copy_logs_to_upload_dir 17:29:33 INFO - Copying logs to upload dir... 17:29:33 INFO - mkdir: /builds/slave/test/build/upload/logs 17:29:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.424783 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-09 17:29:33.724447) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 17:29:33.728453) ========= 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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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/306ac554973be8c7d5a32d97595250473f2b3aba25aa5a90a72a985d73fe3b7a146b5ca8805af20aa81003d936e638fb31b29b8647c791d3fbc0d4232a9111f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07c6ebfd8eebd00251a5e7550665290bad02e4f01f83e8912951b86f53dae47cecb497d9a8f584689c84f91f22123f6a5692a1f0fd248e53a24eaa45646e524f"} build_url:https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/C_Lm80WVSWigxnwB4ZlEpg/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/306ac554973be8c7d5a32d97595250473f2b3aba25aa5a90a72a985d73fe3b7a146b5ca8805af20aa81003d936e638fb31b29b8647c791d3fbc0d4232a9111f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/07c6ebfd8eebd00251a5e7550665290bad02e4f01f83e8912951b86f53dae47cecb497d9a8f584689c84f91f22123f6a5692a1f0fd248e53a24eaa45646e524f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 17:29:33.759814) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 17:29:33.760144) ========= 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.CDD0XD5zDG/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.ZwGDFKYq2u/Listeners TMPDIR=/var/folders/fp/3qw0q0_13p72c8h456gldc9c00000w/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.005133 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 17:29:33.808790) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 17:29:33.809106) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 17:29:33.809403) ========= ========= Total master_lag: 0.15 =========