builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0143 starttime: 1462872999.61 results: success (0) buildid: 20160510014205 builduid: 2b9ef505fdaf4b6dba114cbc4a6d89fd revision: f612200731ad2e125e9ae23ecc4f4a6e7110d8d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.606487) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.606890) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.607162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.625604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.625878) ========= 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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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-10 02:36:39-- 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% 9.11M=0.001s 2016-05-10 02:36:39 (9.11 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081192 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.724431) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.724749) ========= 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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.034902 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.778981) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:39.779352) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f612200731ad2e125e9ae23ecc4f4a6e7110d8d7 --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 f612200731ad2e125e9ae23ecc4f4a6e7110d8d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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-10 02:36:39,854 truncating revision to first 12 chars 2016-05-10 02:36:39,854 Setting DEBUG logging. 2016-05-10 02:36:39,854 attempt 1/10 2016-05-10 02:36:39,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f612200731ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 02:36:40,497 unpacking tar archive at: fx-team-f612200731ad/testing/mozharness/ program finished with exit code 0 elapsedTime=0.880161 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:40.682439) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:40.682798) ========= script_repo_revision: f612200731ad2e125e9ae23ecc4f4a6e7110d8d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:40.689269) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:40.689705) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 02:36:40.708144) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-10 02:36:40.708549) ========= /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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:36:40 INFO - MultiFileLogger online at 20160510 02:36:40 in /builds/slave/test 02:36:40 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 02:36:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:36:40 INFO - {'append_to_log': False, 02:36:40 INFO - 'base_work_dir': '/builds/slave/test', 02:36:40 INFO - 'blob_upload_branch': 'Fx-Team', 02:36:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:36:40 INFO - 'branch': 'Fx-Team', 02:36:40 INFO - 'buildbot_json_path': 'buildprops.json', 02:36:40 INFO - 'config_files': ('talos/mac_config.py',), 02:36:40 INFO - 'default_actions': ('clobber', 02:36:40 INFO - 'read-buildbot-config', 02:36:40 INFO - 'download-and-extract', 02:36:40 INFO - 'populate-webroot', 02:36:40 INFO - 'create-virtualenv', 02:36:40 INFO - 'install', 02:36:40 INFO - 'run-tests'), 02:36:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:36:40 INFO - 'download_minidump_stackwalk': True, 02:36:40 INFO - 'download_symbols': 'ondemand', 02:36:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:36:40 INFO - 'tooltool.py': '/tools/tooltool.py', 02:36:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:36:40 INFO - '/tools/misc-python/virtualenv.py')}, 02:36:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:36:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:36:40 INFO - 'installer_path': 'installer.exe', 02:36:40 INFO - 'log_level': 'info', 02:36:40 INFO - 'log_name': 'talos', 02:36:40 INFO - 'log_to_console': True, 02:36:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:36:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:36:40 INFO - 'opt_config_files': (), 02:36:40 INFO - 'pip_index': False, 02:36:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:36:40 INFO - 'cmd': ('bash', 02:36:40 INFO - '-c', 02:36:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:36:40 INFO - 'enabled': True, 02:36:40 INFO - 'halt_on_failure': False, 02:36:40 INFO - 'name': 'check_screen_resolution'},), 02:36:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:36:40 INFO - 'cmd': ('bash', 02:36:40 INFO - '-c', 02:36:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:36:40 INFO - 'enabled': True, 02:36:40 INFO - 'halt_on_failure': False, 02:36:40 INFO - 'name': 'check_screen_resolution'},), 02:36:40 INFO - 'run_cmd_checks_enabled': True, 02:36:40 INFO - 'sps_profile': False, 02:36:40 INFO - 'sps_profile_interval': 0, 02:36:40 INFO - 'suite': 'dromaeojs', 02:36:40 INFO - 'system_bits': '32', 02:36:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:36:40 INFO - 'title': 't-yosemite-r7-0143', 02:36:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:36:40 INFO - 'use_talos_json': True, 02:36:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:36:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:36:40 INFO - 'work_dir': 'build'} 02:36:40 INFO - ##### 02:36:40 INFO - ##### Running clobber step. 02:36:40 INFO - ##### 02:36:40 INFO - Running pre-action listener: _resource_record_pre_action 02:36:40 INFO - Running main action method: clobber 02:36:40 INFO - rmtree: /builds/slave/test/build 02:36:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:36:42 INFO - Running post-action listener: _resource_record_post_action 02:36:42 INFO - ##### 02:36:42 INFO - ##### Finished clobber step (success) 02:36:42 INFO - ##### 02:36:42 INFO - ##### 02:36:42 INFO - ##### Running read-buildbot-config step. 02:36:42 INFO - ##### 02:36:42 INFO - Running pre-action listener: _resource_record_pre_action 02:36:42 INFO - Running main action method: read_buildbot_config 02:36:42 INFO - Using buildbot properties: 02:36:42 INFO - { 02:36:42 INFO - "project": "", 02:36:42 INFO - "product": "firefox", 02:36:42 INFO - "script_repo_revision": "production", 02:36:42 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:36:42 INFO - "repository": "", 02:36:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:36:42 INFO - "buildid": "20160510014205", 02:36:42 INFO - "pgo_build": "False", 02:36:42 INFO - "basedir": "/builds/slave/test", 02:36:42 INFO - "buildnumber": 4, 02:36:42 INFO - "slavename": "t-yosemite-r7-0143", 02:36:42 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:36:42 INFO - "platform": "yosemite_r7", 02:36:42 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:36:42 INFO - "branch": "fx-team", 02:36:42 INFO - "repo_path": "integration/fx-team", 02:36:42 INFO - "revision": "f612200731ad2e125e9ae23ecc4f4a6e7110d8d7", 02:36:42 INFO - "stage_platform": "macosx64", 02:36:42 INFO - "builduid": "2b9ef505fdaf4b6dba114cbc4a6d89fd", 02:36:42 INFO - "slavebuilddir": "test" 02:36:42 INFO - } 02:36:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 02:36:42 INFO - Running post-action listener: _resource_record_post_action 02:36:42 INFO - ##### 02:36:42 INFO - ##### Finished read-buildbot-config step (success) 02:36:42 INFO - ##### 02:36:42 INFO - ##### 02:36:42 INFO - ##### Running download-and-extract step. 02:36:42 INFO - ##### 02:36:42 INFO - Running pre-action listener: _resource_record_pre_action 02:36:42 INFO - Running main action method: download_and_extract 02:36:42 INFO - mkdir: /builds/slave/test/build/tests 02:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:36:42 INFO - https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:36:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:36:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:36:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:43 INFO - Downloaded 1199 bytes. 02:36:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 02:36:43 INFO - Using the following test package requirements: 02:36:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:36:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 02:36:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 02:36:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'jsshell-mac.zip'], 02:36:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 02:36:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:36:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 02:36:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 02:36:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 02:36:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:36:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 02:36:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:36:43 INFO - https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:36:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:36:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:36:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:45 INFO - Downloaded 18146509 bytes. 02:36:45 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'] 02:36:45 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 02:36:48 INFO - Return code: 0 02:36:48 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 02:36:48 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')]} 02:36:48 INFO - https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:36:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:36:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:36:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:50 INFO - Downloaded 18146509 bytes. 02:36:50 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'] 02:36:50 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 02:36:54 INFO - Return code: 0 02:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:36:54 INFO - https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:36:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:36:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:36:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:55 INFO - Downloaded 10840152 bytes. 02:36:55 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'] 02:36:55 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 02:36:55 INFO - Return code: 0 02:36: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')]} 02:36:55 INFO - https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:36:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:36:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:36:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 02:36:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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 02:36:59 INFO - Downloaded 110413446 bytes. 02:36:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:36:59 INFO - mkdir: /builds/slave/test/properties 02:36:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:36:59 INFO - Writing to file /builds/slave/test/properties/build_url 02:36:59 INFO - Contents: 02:36:59 INFO - build_url:https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:37:00 INFO - Running post-action listener: _resource_record_post_action 02:37:00 INFO - Running post-action listener: set_extra_try_arguments 02:37:00 INFO - ##### 02:37:00 INFO - ##### Finished download-and-extract step (success) 02:37:00 INFO - ##### 02:37:00 INFO - ##### 02:37:00 INFO - ##### Running populate-webroot step. 02:37:00 INFO - ##### 02:37:00 INFO - Running pre-action listener: _resource_record_pre_action 02:37:00 INFO - Running main action method: populate_webroot 02:37:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:37:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:37:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:37:00 INFO - '--tppagecycles', 02:37:00 INFO - '7'], 02:37:00 INFO - 'tests': ['tsvgm']}}, 02:37:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:37:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:37:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:37:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:37:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:37:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:37:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['damp', 'tps']}, 02:37:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'tests': ['damp', 'tps']}, 02:37:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['dromaeo_dom']}, 02:37:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:37:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['a11yr', 02:37:00 INFO - 'ts_paint', 02:37:00 INFO - 'tpaint', 02:37:00 INFO - 'sessionrestore', 02:37:00 INFO - 'sessionrestore_no_auto_restore', 02:37:00 INFO - 'tabpaint']}, 02:37:00 INFO - 'other-e10s': {'tests': ['a11yr', 02:37:00 INFO - 'ts_paint', 02:37:00 INFO - 'tpaint', 02:37:00 INFO - 'sessionrestore', 02:37:00 INFO - 'sessionrestore_no_auto_restore', 02:37:00 INFO - 'tabpaint']}, 02:37:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:37:00 INFO - 'ts_paint', 02:37:00 INFO - 'tpaint', 02:37:00 INFO - 'sessionrestore', 02:37:00 INFO - 'sessionrestore_no_auto_restore', 02:37:00 INFO - 'tabpaint']}, 02:37:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:37:00 INFO - 'ts_paint', 02:37:00 INFO - 'tpaint', 02:37:00 INFO - 'sessionrestore', 02:37:00 INFO - 'sessionrestore_no_auto_restore', 02:37:00 INFO - 'tabpaint']}, 02:37:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['a11yr', 02:37:00 INFO - 'ts_paint', 02:37:00 INFO - 'tpaint', 02:37:00 INFO - 'sessionrestore', 02:37:00 INFO - 'sessionrestore_no_auto_restore', 02:37:00 INFO - 'tabpaint']}, 02:37:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['a11yr', 02:37:00 INFO - 'ts_paint', 02:37:00 INFO - 'tpaint', 02:37:00 INFO - 'sessionrestore', 02:37:00 INFO - 'sessionrestore_no_auto_restore', 02:37:00 INFO - 'tabpaint']}, 02:37:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['tsvgx', 02:37:00 INFO - 'tsvgr_opacity', 02:37:00 INFO - 'tart', 02:37:00 INFO - 'tscrollx', 02:37:00 INFO - 'cart']}, 02:37:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:37:00 INFO - 'tsvgr_opacity', 02:37:00 INFO - 'tart', 02:37:00 INFO - 'tscrollx', 02:37:00 INFO - 'cart']}, 02:37:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'talos_options': ['--disable-e10s'], 02:37:00 INFO - 'tests': ['tp5o']}, 02:37:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'tests': ['tp5o']}, 02:37:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'talos_options': ['--disable-e10s', 02:37:00 INFO - '--xperf_path', 02:37:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:37:00 INFO - 'tests': ['tp5n']}, 02:37:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:37:00 INFO - 'talos_options': ['--xperf_path', 02:37:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:37:00 INFO - 'tests': ['tp5n']}}, 02:37:00 INFO - 'talos.zip': {'path': '', 02:37:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:37:00 INFO - Running post-action listener: _resource_record_post_action 02:37:00 INFO - ##### 02:37:00 INFO - ##### Finished populate-webroot step (success) 02:37:00 INFO - ##### 02:37:00 INFO - ##### 02:37:00 INFO - ##### Running create-virtualenv step. 02:37:00 INFO - ##### 02:37:00 INFO - Running pre-action listener: _resource_record_pre_action 02:37:00 INFO - Running main action method: create_virtualenv 02:37:00 INFO - Creating virtualenv /builds/slave/test/build/venv 02:37:00 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 02:37:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:37:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:37:00 INFO - Using real prefix '/tools/python27' 02:37:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:37:01 INFO - Installing distribute.............................................................................................................................................................................................done. 02:37:04 INFO - Installing pip.................done. 02:37:04 INFO - Return code: 0 02:37:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:37: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')]} 02:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37: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')]} 02:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:04 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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:04 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 02:37:04 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 02:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:04 INFO - 'HOME': '/Users/cltbld', 02:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:04 INFO - 'LOGNAME': 'cltbld', 02:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:04 INFO - 'MOZ_NO_REMOTE': '1', 02:37:04 INFO - 'NO_EM_RESTART': '1', 02:37:04 INFO - 'PAGER': '/bin/cat', 02:37:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:04 INFO - 'PWD': '/builds/slave/test', 02:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:04 INFO - 'SHELL': '/bin/bash', 02:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:04 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:04 INFO - 'USER': 'cltbld', 02:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:04 INFO - 'XPC_FLAGS': '0x0', 02:37:04 INFO - 'XPC_SERVICE_NAME': '0', 02:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:04 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 02:37:04 INFO - Cleaning up... 02:37:04 INFO - Return code: 0 02:37:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:37: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')]} 02:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37: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')]} 02:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:04 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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:04 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 02:37:04 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 02:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:04 INFO - 'HOME': '/Users/cltbld', 02:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:04 INFO - 'LOGNAME': 'cltbld', 02:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:04 INFO - 'MOZ_NO_REMOTE': '1', 02:37:04 INFO - 'NO_EM_RESTART': '1', 02:37:04 INFO - 'PAGER': '/bin/cat', 02:37:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:04 INFO - 'PWD': '/builds/slave/test', 02:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:04 INFO - 'SHELL': '/bin/bash', 02:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:04 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:04 INFO - 'USER': 'cltbld', 02:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:04 INFO - 'XPC_FLAGS': '0x0', 02:37:04 INFO - 'XPC_SERVICE_NAME': '0', 02:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:04 INFO - Downloading/unpacking psutil>=0.7.1 02:37:04 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 02:37:04 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 02:37:04 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 02:37:04 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 02:37:04 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 02:37:04 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 02:37:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:37:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:37:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:37:07 INFO - Installing collected packages: psutil 02:37:07 INFO - Running setup.py install for psutil 02:37:08 INFO - building 'psutil._psutil_osx' extension 02:37:08 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 02:37:08 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 02:37:08 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 02:37:08 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 02:37:08 INFO - building 'psutil._psutil_posix' extension 02:37:08 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 02:37:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:37:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:37:08 INFO - ^ 02:37:08 INFO - 1 warning generated. 02:37:08 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 02:37:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:37:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:37:09 INFO - Successfully installed psutil 02:37:09 INFO - Cleaning up... 02:37:09 INFO - Return code: 0 02:37:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:37: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')]} 02:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37: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')]} 02:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37: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', '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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:37: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 mozsystemmonitor==0.0.0 02:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:09 INFO - 'HOME': '/Users/cltbld', 02:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:09 INFO - 'LOGNAME': 'cltbld', 02:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:09 INFO - 'MOZ_NO_REMOTE': '1', 02:37:09 INFO - 'NO_EM_RESTART': '1', 02:37:09 INFO - 'PAGER': '/bin/cat', 02:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:09 INFO - 'PWD': '/builds/slave/test', 02:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:09 INFO - 'SHELL': '/bin/bash', 02:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:09 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:09 INFO - 'USER': 'cltbld', 02:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:09 INFO - 'XPC_FLAGS': '0x0', 02:37:09 INFO - 'XPC_SERVICE_NAME': '0', 02:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:37:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:37:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:37:11 INFO - Installing collected packages: mozsystemmonitor 02:37:11 INFO - Running setup.py install for mozsystemmonitor 02:37:12 INFO - Successfully installed mozsystemmonitor 02:37:12 INFO - Cleaning up... 02:37:12 INFO - Return code: 0 02:37:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:37:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:37:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:12 INFO - 'HOME': '/Users/cltbld', 02:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:12 INFO - 'LOGNAME': 'cltbld', 02:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:12 INFO - 'MOZ_NO_REMOTE': '1', 02:37:12 INFO - 'NO_EM_RESTART': '1', 02:37:12 INFO - 'PAGER': '/bin/cat', 02:37:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:12 INFO - 'PWD': '/builds/slave/test', 02:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:12 INFO - 'SHELL': '/bin/bash', 02:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:12 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:12 INFO - 'USER': 'cltbld', 02:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:12 INFO - 'XPC_FLAGS': '0x0', 02:37:12 INFO - 'XPC_SERVICE_NAME': '0', 02:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:12 INFO - Downloading/unpacking blobuploader==1.2.4 02:37: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 02:37: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 02:37: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 02:37: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 02:37: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 02:37: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 02:37:14 INFO - Downloading blobuploader-1.2.4.tar.gz 02:37:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:37:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:37:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:15 INFO - Downloading docopt-0.6.1.tar.gz 02:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:37:16 INFO - Installing collected packages: blobuploader, requests, docopt 02:37:16 INFO - Running setup.py install for blobuploader 02:37:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:37:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:37:16 INFO - Running setup.py install for requests 02:37:16 INFO - Running setup.py install for docopt 02:37:16 INFO - Successfully installed blobuploader requests docopt 02:37:16 INFO - Cleaning up... 02:37:16 INFO - Return code: 0 02:37:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:16 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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:16 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 02:37:16 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 02:37:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:16 INFO - 'HOME': '/Users/cltbld', 02:37:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:16 INFO - 'LOGNAME': 'cltbld', 02:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:16 INFO - 'MOZ_NO_REMOTE': '1', 02:37:16 INFO - 'NO_EM_RESTART': '1', 02:37:16 INFO - 'PAGER': '/bin/cat', 02:37:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:16 INFO - 'PWD': '/builds/slave/test', 02:37:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:16 INFO - 'SHELL': '/bin/bash', 02:37:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:16 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:16 INFO - 'USER': 'cltbld', 02:37:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:16 INFO - 'XPC_FLAGS': '0x0', 02:37:16 INFO - 'XPC_SERVICE_NAME': '0', 02:37:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-a73422-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-VstOFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-nmkt4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Vw8qXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-3gmewp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-WkyBbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-roYpY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:37:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:37:17 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-X3_6GJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-pogDhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-RZ7EIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-LAwwPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-erL10L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-b5VpQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-CL9FaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-k65wnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-uVfOgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:37:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:37:18 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-jhUg2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:37:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:37:18 INFO - Running setup.py install for manifestparser 02:37:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:37:19 INFO - Running setup.py install for mozcrash 02:37:19 INFO - Running setup.py install for mozdebug 02:37:19 INFO - Running setup.py install for mozdevice 02:37:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:37:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:37:19 INFO - Running setup.py install for mozfile 02:37:19 INFO - Running setup.py install for mozhttpd 02:37:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:37:19 INFO - Running setup.py install for mozinfo 02:37:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:37:19 INFO - Running setup.py install for mozInstall 02:37:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Running setup.py install for mozleak 02:37:20 INFO - Running setup.py install for mozlog 02:37:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Running setup.py install for moznetwork 02:37:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Running setup.py install for mozprocess 02:37:20 INFO - Running setup.py install for mozprofile 02:37:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:37:20 INFO - Running setup.py install for mozrunner 02:37:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:37:21 INFO - Running setup.py install for mozscreenshot 02:37:21 INFO - Running setup.py install for moztest 02:37:21 INFO - Running setup.py install for mozversion 02:37:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:37:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:37:21 INFO - Cleaning up... 02:37:21 INFO - Return code: 0 02:37:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:37:21 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')]} 02:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:21 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')]} 02:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:21 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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:21 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 02:37:21 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 02:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:21 INFO - 'HOME': '/Users/cltbld', 02:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:21 INFO - 'LOGNAME': 'cltbld', 02:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:21 INFO - 'MOZ_NO_REMOTE': '1', 02:37:21 INFO - 'NO_EM_RESTART': '1', 02:37:21 INFO - 'PAGER': '/bin/cat', 02:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:21 INFO - 'PWD': '/builds/slave/test', 02:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:21 INFO - 'SHELL': '/bin/bash', 02:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:21 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:21 INFO - 'USER': 'cltbld', 02:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:21 INFO - 'XPC_FLAGS': '0x0', 02:37:21 INFO - 'XPC_SERVICE_NAME': '0', 02:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:37:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-eIxRnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:37:21 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)) 02:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:37:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-md0Qzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:37:21 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)) 02:37:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:37:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Hnb2Hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-YeLy6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-LyOJTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-HjXb0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-DVnGF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-O9eNnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-7hNlca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-BKQFY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-elJYzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:37:22 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)) 02:37:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:37:22 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-TqZITy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:37:23 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)) 02:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:37:23 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-I36zA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:37:23 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)) 02:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:37:23 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-acDTWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:37:23 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)) 02:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:23 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-9Km9Xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:23 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)) 02:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:37:23 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-iagaRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:37:23 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)) 02:37:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:37:23 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-nfU4Hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:37:23 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)) 02:37:23 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)) 02:37:23 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)) 02:37:23 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)) 02:37:23 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)) 02:37:23 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)) 02:37:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:25 INFO - Downloading blessings-1.6.tar.gz 02:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:37:26 INFO - Installing collected packages: blessings 02:37:26 INFO - Running setup.py install for blessings 02:37:26 INFO - Successfully installed blessings 02:37:26 INFO - Cleaning up... 02:37:26 INFO - Return code: 0 02:37:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:37:26 INFO - Reading from file tmpfile_stdout 02:37:26 INFO - Current package versions: 02:37:26 INFO - blessings == 1.6 02:37:26 INFO - blobuploader == 1.2.4 02:37:26 INFO - docopt == 0.6.1 02:37:26 INFO - manifestparser == 1.1 02:37:26 INFO - mozInstall == 1.12 02:37:26 INFO - mozcrash == 0.17 02:37:26 INFO - mozdebug == 0.1 02:37:26 INFO - mozdevice == 0.48 02:37:26 INFO - mozfile == 1.2 02:37:26 INFO - mozhttpd == 0.7 02:37:26 INFO - mozinfo == 0.9 02:37:26 INFO - mozleak == 0.1 02:37:26 INFO - mozlog == 3.1 02:37:26 INFO - moznetwork == 0.27 02:37:26 INFO - mozprocess == 0.22 02:37:26 INFO - mozprofile == 0.28 02:37:26 INFO - mozrunner == 6.11 02:37:26 INFO - mozscreenshot == 0.1 02:37:26 INFO - mozsystemmonitor == 0.0 02:37:26 INFO - moztest == 0.7 02:37:26 INFO - mozversion == 1.4 02:37:26 INFO - psutil == 3.1.1 02:37:26 INFO - requests == 1.2.3 02:37:26 INFO - wsgiref == 0.1.2 02:37:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:37:26 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')]} 02:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:26 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')]} 02:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:26 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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:26 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 02:37:26 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 02:37:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:26 INFO - 'HOME': '/Users/cltbld', 02:37:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:26 INFO - 'LOGNAME': 'cltbld', 02:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:26 INFO - 'MOZ_NO_REMOTE': '1', 02:37:26 INFO - 'NO_EM_RESTART': '1', 02:37:26 INFO - 'PAGER': '/bin/cat', 02:37:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:26 INFO - 'PWD': '/builds/slave/test', 02:37:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:26 INFO - 'SHELL': '/bin/bash', 02:37:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:26 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:26 INFO - 'USER': 'cltbld', 02:37:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:26 INFO - 'XPC_FLAGS': '0x0', 02:37:26 INFO - 'XPC_SERVICE_NAME': '0', 02:37:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 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)) 02:37:26 INFO - Cleaning up... 02:37:26 INFO - Return code: 0 02:37:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:37:26 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')]} 02:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:26 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')]} 02:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:26 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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:26 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 02:37:26 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 02:37:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:26 INFO - 'HOME': '/Users/cltbld', 02:37:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:26 INFO - 'LOGNAME': 'cltbld', 02:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:26 INFO - 'MOZ_NO_REMOTE': '1', 02:37:26 INFO - 'NO_EM_RESTART': '1', 02:37:26 INFO - 'PAGER': '/bin/cat', 02:37:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:26 INFO - 'PWD': '/builds/slave/test', 02:37:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:26 INFO - 'SHELL': '/bin/bash', 02:37:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:26 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:26 INFO - 'USER': 'cltbld', 02:37:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:26 INFO - 'XPC_FLAGS': '0x0', 02:37:26 INFO - 'XPC_SERVICE_NAME': '0', 02:37:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:27 INFO - Downloading/unpacking jsonschema 02:37:27 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 02:37:27 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 02:37:27 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 02:37:27 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 02:37:27 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 02:37:27 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 02:37:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:37:29 INFO - Downloading/unpacking functools32 (from jsonschema) 02:37:29 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 02:37:29 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 02:37:29 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 02:37:29 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 02:37:29 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 02:37:29 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 02:37:29 INFO - Downloading functools32-3.2.3-2.tar.gz 02:37:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:37:29 INFO - warning: no files found matching '*.txt' 02:37:29 INFO - no previously-included directories found matching 'build' 02:37:29 INFO - no previously-included directories found matching 'dist' 02:37:29 INFO - no previously-included directories found matching '.git*' 02:37:29 INFO - Installing collected packages: jsonschema, functools32 02:37:30 INFO - Running setup.py install for functools32 02:37:30 INFO - warning: no files found matching '*.txt' 02:37:30 INFO - no previously-included directories found matching 'build' 02:37:30 INFO - no previously-included directories found matching 'dist' 02:37:30 INFO - no previously-included directories found matching '.git*' 02:37:30 INFO - Successfully installed jsonschema functools32 02:37:30 INFO - Cleaning up... 02:37:30 INFO - Return code: 0 02:37:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:37:30 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')]} 02:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:30 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')]} 02:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:30 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 0x109fc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109fcf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f837849a1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, '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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 02:37:30 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 02:37:30 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 02:37:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:37:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:30 INFO - 'HOME': '/Users/cltbld', 02:37:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:30 INFO - 'LOGNAME': 'cltbld', 02:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:30 INFO - 'MOZ_NO_REMOTE': '1', 02:37:30 INFO - 'NO_EM_RESTART': '1', 02:37:30 INFO - 'PAGER': '/bin/cat', 02:37:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:30 INFO - 'PWD': '/builds/slave/test', 02:37:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:30 INFO - 'SHELL': '/bin/bash', 02:37:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:37:30 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:37:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:30 INFO - 'USER': 'cltbld', 02:37:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:30 INFO - 'XPC_FLAGS': '0x0', 02:37:30 INFO - 'XPC_SERVICE_NAME': '0', 02:37:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:30 INFO - Downloading/unpacking flake8 02:37:30 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 02:37:30 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 02:37:30 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 02:37:30 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 02:37:30 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 02:37:30 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 02:37:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:37:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:37:32 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 02:37:32 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 02:37:32 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 02:37:32 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 02:37:32 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 02:37:32 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 02:37:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:37:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:37:34 INFO - no previously-included directories found matching 'docs/_build' 02:37:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:37:34 INFO - Running setup.py install for pep8 02:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:37:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:37:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:37:34 INFO - no previously-included directories found matching 'docs/_build' 02:37:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:37:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:37:34 INFO - Cleaning up... 02:37:34 INFO - Return code: 0 02:37:34 INFO - Running post-action listener: _resource_record_post_action 02:37:34 INFO - Running post-action listener: _start_resource_monitoring 02:37:34 INFO - Starting resource monitoring. 02:37:34 INFO - ##### 02:37:34 INFO - ##### Finished create-virtualenv step (success) 02:37:34 INFO - ##### 02:37:34 INFO - ##### 02:37:34 INFO - ##### Running install step. 02:37:34 INFO - ##### 02:37:34 INFO - Running pre-action listener: _resource_record_pre_action 02:37:34 INFO - Running main action method: install 02:37:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:37:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:37:34 INFO - Reading from file tmpfile_stdout 02:37:34 INFO - Detecting whether we're running mozinstall >=1.0... 02:37:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:37:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:37:34 INFO - Reading from file tmpfile_stdout 02:37:34 INFO - Output received: 02:37:34 INFO - Usage: mozinstall [options] installer 02:37:34 INFO - Options: 02:37:34 INFO - -h, --help show this help message and exit 02:37:34 INFO - -d DEST, --destination=DEST 02:37:34 INFO - Directory to install application into. [default: 02:37:34 INFO - "/builds/slave/test"] 02:37:34 INFO - --app=APP Application being installed. [default: firefox] 02:37:34 INFO - mkdir: /builds/slave/test/build/application 02:37:34 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'] 02:37:34 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 02:38:08 INFO - Reading from file tmpfile_stdout 02:38:08 INFO - Output received: 02:38:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:38:08 INFO - Running post-action listener: _resource_record_post_action 02:38:08 INFO - ##### 02:38:08 INFO - ##### Finished install step (success) 02:38:08 INFO - ##### 02:38:08 INFO - ##### 02:38:08 INFO - ##### Running run-tests step. 02:38:08 INFO - ##### 02:38:08 INFO - Running pre-action listener: _resource_record_pre_action 02:38:08 INFO - Running main action method: run_tests 02:38:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:38:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:38:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:38:08 INFO - 2016-05-10 02:38:08.368 screenresolution[1646:7605] starting screenresolution argv=screenresolution get 02:38:08 INFO - 2016-05-10 02:38:08.371 screenresolution[1646:7605] Display 0: 1600x1200x32@60 02:38:08 INFO - 2016-05-10 02:38:08.376 screenresolution[1647:7607] starting screenresolution argv=screenresolution list 02:38:08 INFO - Available Modes on Display 0 02:38:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:38:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:38:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:38:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:38:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:38:08 INFO - Intel Iris: 02:38:08 INFO - Chipset Model: Intel Iris 02:38:08 INFO - Type: GPU 02:38:08 INFO - Bus: Built-In 02:38:08 INFO - VRAM (Dynamic, Max): 1536 MB 02:38:08 INFO - Vendor: Intel (0x8086) 02:38:08 INFO - Device ID: 0x0a2e 02:38:08 INFO - Revision ID: 0x0009 02:38:08 INFO - Displays: 02:38:08 INFO - Display: 02:38:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:38:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:38:08 INFO - Display Serial Number: 1600x1200 60 02:38:08 INFO - Main Display: Yes 02:38:08 INFO - Mirror: Off 02:38:08 INFO - Online: Yes 02:38:08 INFO - Rotation: Supported 02:38:08 INFO - Return code: 0 02:38:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:38:08 INFO - Python 2.7.3 02:38:08 INFO - Return code: 0 02:38:08 INFO - grabbing minidump binary from tooltool 02:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:38:08 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 0x109e6dd30>, '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 0x109c91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e64b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:38:08 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 02:38:08 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 02:38:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:38:08 INFO - Return code: 0 02:38:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:38:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:38:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:38:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:38:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:38:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:38:09 INFO - Return code: 0 02:38:09 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/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 02:38:09 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/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0143 --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 02:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9V7oyV6WfF/Render', 02:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:38:09 INFO - 'HOME': '/Users/cltbld', 02:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:38:09 INFO - 'LOGNAME': 'cltbld', 02:38:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:38:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:38:09 INFO - 'MOZ_NO_REMOTE': '1', 02:38:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:38:09 INFO - 'NO_EM_RESTART': '1', 02:38:09 INFO - 'PAGER': '/bin/cat', 02:38:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:38:09 INFO - 'PWD': '/builds/slave/test', 02:38:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:38:09 INFO - 'SHELL': '/bin/bash', 02:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DQ8OOu0Qpn/Listeners', 02:38:09 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 02:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:38:09 INFO - 'USER': 'cltbld', 02:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:38:09 INFO - 'XPC_FLAGS': '0x0', 02:38:09 INFO - 'XPC_SERVICE_NAME': '0', 02:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:38:09 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/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0143', '--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 02:38:10 INFO - mozversion application_buildid: 20160510014205 02:38:10 INFO - mozversion application_changeset: f612200731ad2e125e9ae23ecc4f4a6e7110d8d7 02:38:10 INFO - mozversion application_display_name: Nightly 02:38:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:38:10 INFO - mozversion application_name: Firefox 02:38:10 INFO - mozversion application_remotingname: firefox 02:38:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:38:10 INFO - mozversion application_vendor: Mozilla 02:38:10 INFO - mozversion application_version: 49.0a1 02:38:10 INFO - mozversion platform_buildid: 20160510014205 02:38:10 INFO - mozversion platform_changeset: f612200731ad2e125e9ae23ecc4f4a6e7110d8d7 02:38:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:38:10 INFO - mozversion platform_version: 49.0a1 02:38:10 INFO - using testdate: 1462873090 02:38:10 INFO - actual date: 1462873090 02:38:10 INFO - starting webserver on 'localhost:49270' 02:38:10 INFO - SUITE-START | Running 2 tests 02:38:10 INFO - TEST-START | dromaeo_css 02:38:10 INFO - operating with platform_type : mac_ 02:38:10 INFO - Initialising browser for dromaeo_css test... 02:38:10 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpdHV88t/profile http://localhost:49270/getInfo.html) 02:38:12 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 02:38:12 INFO - PROCESS | 1665 | colorDepth:24 02:38:12 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 02:38:12 INFO - PROCESS | 1665 | __metrics 02:38:12 INFO - PROCESS | 1665 | 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" 02:38:12 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:38:13 INFO - TEST-INFO | 1665: exit 0 02:38:13 INFO - Browser initialized. 02:38:13 INFO - Running cycle 1/1 for dromaeo_css test... 02:38:13 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpdHV88t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:38:25 INFO - PROCESS | 1667 | RSS: Main: 238931968 02:38:25 INFO - PROCESS | 1667 | 02:39:16 INFO - PROCESS | 1667 | 1462873156440 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] 02:41:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 02:41:28 INFO - PROCESS | 1667 | RSS: Main: 358649856 02:41:28 INFO - PROCESS | 1667 | 02:44:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 02:44:32 INFO - PROCESS | 1667 | RSS: Main: 365281280 02:44:32 INFO - PROCESS | 1667 | 02:47:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 02:47:34 INFO - PROCESS | 1667 | RSS: Main: 368934912 02:47:34 INFO - PROCESS | 1667 | 02:50:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 02:50:37 INFO - PROCESS | 1667 | RSS: Main: 368906240 02:50:37 INFO - PROCESS | 1667 | 02:53:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 02:53:41 INFO - PROCESS | 1667 | RSS: Main: 335233024 02:53:41 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 02:56:44 INFO - PROCESS | 1667 | RSS: Main: 398831616 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | __start_tp_report 02:56:44 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 02:56:44 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 02:56:44 INFO - PROCESS | 1667 | |i|pagename|runs| 02:56:44 INFO - PROCESS | 1667 | |0;dojo.html;3010.6688264290924;3449.7067749241314;3463.376592213402;3472.618012018679;3517.9648203517972;5559.722013899299;5617.38208797032;5651.660900345983;5704.743286552105;5717.971410142949;5621.269234999455;5720.141978703186;5748.540116790647;5753.942460575393;5791.82624521264;5133.30700355451;5139.434662187156;5164.070467315882;5191.948080519203;5236;5208;5232.398274198459;5248.6588371755715;5270.5520030797425;5283.603729720267;5089.262057001751;5108.182690769496;5145.279660847485;5183.585313174937;5213.713245771519;5143.279940808253;5171.560417364529;5196.402413722432;5223.529882310572;5237.633365664405;5170.586353091744;5176.534111929906;5178.948210517922;5180.6891586504935;5203.817866374697;5447.455254474521;5554.0835762099605;5603.663780173202;5617.859553511195;5703.828885133411;6121.816345509642;6137.6624285664275;6176.042713379434;6204.410580994843;6267.310595834455;6295.307516173218;6301.180846489973;6337.334579869093;6339.556231063827;6356.745730170787;4946.3817022872145;5674.120511320752;5815.82552523425;5890.70546472679;5896.6756828374855;6241.21984751906;6314.368563143741;6318.304986451487;6326.841828954313;6329.525285603599;6095.573309868355;6122.7550897964475;6139.140520327158;6168.784092556829;6194.721237544321;6134.8346010261785;6159.753609855556;6279.215098112736;6283.277423096402;6303.653299068504;6154.815355539341;6190.907136392958;6221.4089661482085;6227.564070515111;6239.095331176954;6251.906221406683;6253.249610046823;6257.530685198628;6262.12330273762;6269.435750782361;4255.468066491689;4887.218045112765;4911.4106307243355;4940.481249468754;4987.925181122286;4443.155800397914;4443.844465443694;4451.198784218872;4461.866144015685;4490.730556166641;5712.971435142797;5715.371309155989;5726.1410788381245;5733.426657334317;5747.339056008529;6149.938500615026;6228.096925945712;6274.968625156845;6283.685815709287;6315.842103947438;5719.19931209631;5746.339170995306;5758.740856661377;5759.942400576025;5767.567432442584;5391.946080539223;5399.514043736082;5406.648567843077;5419.674819510842;5426.538744206787;5389.946100538945;6247.937520624827;6249.0626406039455;6332.11350410952;6364.204474440695;6333.050042493663;6343.270523889627;6345.524085693499;6345.904811427925;6351;6365.2361716594305;6372.6495042773095;6375.936240637534;6384.776532821329;6390.648514331757;4355.324924636687;5782.653040817564;6098.9466267744565;6111;6197.349278325711;6290.528210384331;6305.464035557029;6308.211473565804;6310.84222894413;6315.747370105144;6079.088136779518;6105.389461053859;6114.510839132948;6150.7539698411565;6152.723127459186;5979.073243647242;6016.969915150396;6039.214902062704;6044.184035155197;6100.267967843887;5936.020556608111;5963.701814909324;5964.165016897725;5999.6700181488695;6002.909956350571;5969.671668058299;6035.414909974774;6098.324177734433;6119.896141176215;6123.730452203222;5811.680357580204;5871.559633027455;5933.821985340274;5972.611780234271;5991.400859913973 02:56:44 INFO - PROCESS | 1667 | |1;ext.html;520.2040877457489;531.2907268796157;536.8872536767284;540.3812634533459;575.6776205325019;22032.88983555082;42807;43779.34330985038;44050.33924491131;46292.76853615735;44298.778506107505;44448.33327500082;44537.55462445375;44550.55449445505;44612.776936115355;8553.059163492042;9499.667511637093;9680.90319096807;9689.370190937567;9693.806123877519;8639.308855291592;11330.603428879991;11591.188616796828;11613.419329033515;11677.416129193549;11614.593489227878;11688.357140357277;11704.648860534198;11714.355710435922;11725.530978760838;8644.222020018165;10029.59881604735;11362.545498180063;11612.535498580046;11742.001929835931;11500.424978751029;11584.826227606593;11597.478113484913;11602.129840262014;11602.709932251677;39091.8045409774;39313;39432.80283598592;39490.802545987375;39505.012374690865;3210.7912985655857;3246.4643333849885;3338.382399256145;3799.563050249229;4093.406456063854;28086.859565702245;30066.24834379136;30097.548536771967;30141;32052.83973580117;2561.3084467193753;2683.825551339157;2685.0468083830156;2688.9193324200305;2743.931401714973;544239;552155;553244;553865;555419;35064.64935350614;64740.676296618214;66242.00636990448;66359.33640663531;66456;34273.48589771155;36147;36220.45669314962;36315.63684363176;36332.455013174826;15230.543083707507;15454;15497.380104795908;15503;15514.767278490832;11744.530218791324;11976.700582485508;12821;12944.417501212469;12991.415386307639;8140.633671484679;8143.552104634184;8151.225633564802;8207.835843283101;8230.341572674171;3392.1519620094978;3409.4544872820725;3411.3347897159815;3415.64135765746;3444.689977902001;988.2637435110888;1031.8297480915714;1080.8919108089128;1085.2674444749762;1086.8424078508572;26417.603735943976;29124.271893202415;29241.760911493697;29481.410371792874;29828.701712983027;1072.9034386905216;1141.2809929744374;1147.6098126636816;1150.9366984815915;1161.5005547614437;13682.52111176104;14479.348429320706;14484;14820.777688334683;14921.552353429415;681025;682491;686395;688248;688636;55020.724896376065;57143.42856571464;57203.71398143066;57273.71363143158;57332.85334293374;1316.1379296560767;1363.9659008524668;1440.3014537948877;1472.138798802691;1507.811523559555;28585.714142858305;29645.703542964293;29660;30483.69516304897;30508.084757457313;49800.50598482051;50358.49641503685;50384;50562.49437505578;50642.493575063774;544828;552155;553657;554374;554631;22828.201012964466;25249.74750252474;25833.483330333664;26096.608550871373;27187.320316992234;44774.3283850749;46200;46818.82952926204;47512;50277.49722502875;37897.62102378941;38014.06455781086;38044.80977595094;38400.80799595984;38998.805005975926;29319;37260.06849828776;38037.42943855789;38198.61801382062;38351;37396.2520749578;37668.81165594154;38264.80867595644;38329.23341533209;38639;32610.184745381554;33051.33897321992;33602.83198583991;33703.66296337005;34062.31875362429;24796.56916648348;24804;25127.24618261455;25206.369840753396;25667.229983100537 02:56:44 INFO - PROCESS | 1667 | |2;jquery.html;350.6493506493506;388.7337174035785;393.36752477219534;404.304596094717;404.88865561970454;19899.801001989992;21881.890590547024;22252.55494890101;22316.77683223169;22369.55260894785;20389.28637497688;21054.631448955806;21419.250326238584;21425.250116245934;22081.668774968388;5334.333208348957;5539.9169012464845;5549.750261238234;5552.611317207797;5558.749856256458;3915.099527108767;4121.113960498492;4768.332433459318;5151.330327057496;6189.90715139271;6126.846828829271;6168.475679567242;6172.4444799968;6180.660063696495;6182.536309776785;5953.58324917256;6068.180795592582;6116.571839971204;6169.352218017111;6170.814875553718;3818.618138186187;5652.971735141298;5813.040848260032;5979.431953964367;5988.9101663475085;13825;13934.93032534841;13943.16341019542;24172.879135604384;25376.619350709752;2158.9676154857534;2177.825773938081;2213.3581261434165;2343.4492894169857;2756.699548704028;9371.812563748756;9728.878747720722;10048.095671389538;10255.384676919408;10436.060754532053;1670.4905003973784;1707;1783.2777725021388;1813.86396020299;1827.4608990347879;114907.42546287215;119135.40432297783;119176;119991.80008199981;120700.39649801783;11745.647630571097;11766.647000589996;11802.88197118035;11810.704732381588;11840.703982400337;15221.391144354102;15306.158161301013;15314.617134571725;15340.616484587974;15432.614184645474;7774.922250777533;8749.212570868525;8805.119488051143;8805.955970220108;8898.86651700225;7077.504574679826;8069.193080691884;8613.138686131337;8624.568771561397;8783.34124940632;4035.9394609080887;4584.6561507887045;4662.6269898408045;4690.577847993697;4750.192467280571;1595.7207488689432;1802.6304607555683;1843.3363789324565;1928.5660726336744;1968.4094771568473;875.6349597257665;875.7898104455012;950.8763860698065;985.8525688756515;1013.2957594471771;18336.633267334844;19626.803731962784;19680.90159549193;19886.0435786105;19897.900510497355;898.9235914947303;933.3839665820526;941.0448394879203;949.7198326493701;953.423178976723;10379.96200379956;10537.367757934548;10551.630692925712;10599.258051936291;10629.681109566725;570296;622346;628489;632034;640484;125709.37145314398;128130;128825;130508.347458264;131367.3431632855;840.8906842110608;944.3955868244329;957.4111921168432;1073.3988966178965;1076.1060533461164;13844.10013349129;14249.50126745559;14293.57119286423;14542.709145817236;14570.562883113524;49394.50605493899;49791.50208497869;50364;50431.99136017333;52749.7362513185;73367.03013213736;74352.88470672836;74893;75055.87416188871;76515;6945.756898508528;7422.777316680508;8031.477953932976;8089.069756977788;8426.41015128935;11392.25950313227;12313;12391.008719302614;12541.561045363369;12590.11869169186;9547.42715437076;9760.51197440111;10143.036411540892;10973.725656858642;11123.221374503708;10552.261341706007;10615.469226538797;10705;10925.617603383844;10998.395088270221;10696.197785165989;11040.282381645167;11268.718282043015;11338.433078345884;11358.318500889973;7370.742024029133;7430.368418684472;7502.287282708134;7533.54798712079;7708.997830282252;8712.607932643174;9433.764155896157;10848.511816968414;11077.723056923642;11159.72100697489 02:56:44 INFO - PROCESS | 1667 | |3;mootools.html;1432.05484380309;1475.6089636246393;1618.6969489560906;1813.3743858368857;1971.5563998100429;7717.266859648332;8509.404341696083;8770.6930257441;8779.648814047438;8888.20006199442;8496.61765220565;8719.738407847759;8742.519161446118;8752.29981601472;8785.516796576187;2141.550274442369;2227.6881236626928;2348.988255058722;2350.588646986779;2354.4231663242444;2320.8027317678016;2348.729896061958;2361.8781078987518;2371.3715865295626;2374.442006128558;2335.439494521311;2354.6232602783557;2355.49356888269;2378.8334816562847;2379.9643005354933;2028.3712049264627;2307.699999000136;2336.2407217654245;2340.765923407654;2343.0627748900406;2335.112657190257;2336.427575244059;2339.9532009359887;2341.976580234193;2350.294911526535;8459.280961118313;8557.743267701979;8605.924259467567;8650.87023694645;8786.428882122642;5164.767585458663;5349.545288650469;5360.758765855507;5365.731713414314;5372.1941708744;3061.6479104902774;3415.8633654653786;3454.671806178434;3545.85816567337;3589.6948759355487;1010.5957616953204;1056.4453661827524;1056.9630062947838;1063.4682658670665;1063.5745701719375;11495.482703278289;11611.535538578446;11613.012893904028;11613.245139065933;11655.242409243372;4391.978040109843;4418.690691651554;4496.988177660066;4540.614047805973;4544.613707834871;9062.410943288665;9070.501122438198;9078.954605226932;9083.046280140623;9087.136722011399;5058.317127187856;5143.022825663112;5146.125158723026;5147.279380886679;5151.97424012885;4257.595528424795;4440.6447484201835;4645.628349732014;4734.4081989751285;4788.664793464493;3718.535183102112;3742.1580144467275;3795.35478968576;3824.120452295932;3845.942310865339;1182.296533562529;1184.59131599598;1185.6265276438066;1193.254216114928;1207.5290636651712;629.8362425769333;784.7796676168499;893.946363218209;937.6952490440634;946.5409276500892;5639.971800141055;5811.9709401453565;5876.44173803488;6001.979663457223;6013.789517366871;484.1503161950754;506.02842542318837;512.423523536022;513.5788653304329;651.9471054247451;3746.756460830037;3874.9612503875164;3920.882373528799;3995.9200815983518;3996.86010989614;10287.434191119411;10741.301815381976;10867.347959122479;10870.565177392973;10885.67342979696;10769.138468922467;10769.299995500267;10788.298760580537;10792.352458852494;10818.945905270422;7236.674349654279;7614.466987310946;7914.564698941498;8096.595170241464;8151.633176506953;5921;5941.792037278848;6043.607165534227;6064.939350606437;6080.878382432238;8671;10050.396976181453;10178.643747468803;10183.13443357323;10241.334313269614;9972.252081094091;10172.694819155437;10323.122534584645;10380.169586433218;10422.687319380431;7400.222976587596;7469.58917259556;7544.8491030180185;7557.05536807899;7558.3953283738265;6794.558353706993;8098.51408915467;8103;8116.594170291343;8198.934138562186;7306.378957788647;7327.157376901511;7357.153927298429;7489.13874904393;7501.38720175165;6904.378605925491;7159.821004474929;7180.3537681608905;7238.6380680964685;7262.237465066092;6857.55425897315;6865.244823069358;6942.993265976506;6943.930560694328;6948.5483443576;4403.955960440355;4724.763761811964;4826.348442960156;4907.312976183409;4922.138625740553;4247.256730072287;4806.639502037291;4880.072786170616;4941.061198372297;4951.752412379438 02:56:44 INFO - PROCESS | 1667 | |4;prototype.html;305.7599784169427;316.5915968400763;319.6691424375771;320.5736370627065;327.20489211216733;5163.751860249094;5270.367555893294;5284.2866213061225;5289.8942021159555;5395.811146609864;4732.337472753818;5116.437191908887;5135.922961155577;5289.408189986898;5295.073362161616;3609.5488063992;3630.2376500934834;3664.4136938089846;3823.961760382402;3846.8653597124107;3090.1965488972805;3965.187136636997;3995.3607422812356;3999.4200840878057;4027.932597861569;3509.4560343146854;3944.5660977292473;3996.240714264295;4007.5591684914634;4018.6939244745427;3715.0620342436832;3728.440733889911;3787.6780473659774;3936.877989772901;4009.957411073113;2867.813592116506;3462.4113900636953;3481.147118955872;3487.7035451986612;3521.154922818531;4189.748615083105;4964.776585053682;4965.528274813887;5002.59979201663;5020.924686129711;4085.937656209377;4202.768847713374;4524.841630542948;4555.931661025087;4572.199865023607;5434.130539113762;5475.835724928219;5478.91781623276;5496.560275177976;5523.337199536041;3601.2617413430185;3651.233241019389;3802.5817160112365;3803.885883423474;3830.942535861964;5403.730433218057;5771.567132465082;5820.185174075633;5821.15593238978;5835.474807267324;3480.3387356402204;3801.809909504569;3832.3101841668768;3836.1176929306416;3845.7501312073687;3358.865645374158;3735.51438313023;3807.904802379963;3857.4213867920034;3881.7865017424315;4510.233260345749;4539.319102134707;4547.386102876069;4565.269556870885;4575.5424457554645;5280.910667259619;5513.9448605514235;5542.972285138549;5546.86132846674;5563.276774019351;1147.5409836065473;1219.9634010979685;1245.2030700351781;1255.813256472643;1271.707507273314;800.6509032280626;819.7499762572353;845.446232717571;853.3898262742046;857.7726902370877;4454.175977444183;4831.589314908272;5032.370953630796;5034.748262586856;5189.273501709764;4929.457759646436;5676;5742.28221472316;5760.74076666551;5765.596408251382;4148.896277593084;4620.821690468976;4834.903301933941;5212.296339994128;5328.254044433782;2447.559439300943;2678.531257030012;2832.362718388346;2861.9680027180934;2872.8023062357315;4821.54195351441;5183.766730497136;5290.4766424707095;5629.198656429903;5719.28508936383;4689.882752931136;4737.976310118496;5305.840824775263;5534.976029434568;5699.74351154199;5271.6309858310315;5618.85952851182;5632.436756324499;5674.602777805514;5737;4654.858764968152;4892.3884514435695;4902.901941961212;5207.947920520746;5327.840164795062;5080.339555857862;5365.25175219941;5428.914217156505;5465.508104270635;5479.589030822784;5195.350581177353;5524.475174858382;5615.129654903497;5658.9151162733415;5664.603477756682;3603.9639603603628;3683.834227459717;3713.2573485303587;3750.4749335092565;3763.9247215056093;4175.645070168948;4726.109556177556;4965.106280869334;5309.814156504503;5366.7584958677735;4620.861374158781;4850.957044235506;5194.831162988297;5223.242629818579;5261.973690131525;4419.535948725466;4566.219174522005;5261.7111556473055;5262.947370526399;5270.446603106619;4302.698811083319;4661.836835710734;4720.268358404453;4774.116788394158;5024.472430394756;4831.613470922248;4854.126257273725;5164.896702066013;5183.611229157904;5378.811741589026;4466.57567531084;4667.712820717892;5391;5403.486668766461;5429.837104886859 02:56:44 INFO - PROCESS | 1667 | |5;yui.html;609.5954096371763;650.3301599352666;756.9356604688603;762.8474305138974;763.3015790551646;13628.659283517916;14160.150390976549;14687.632809179775;15469.690606187869;15501.37994480221;15062.246887655629;15506.457273995413;15519.689606207869;15952.680946381095;15960.76058859118;940.666063547441;943.2925306020502;951.3435729346763;951.6954574536152;954.9522523873813;737.1375490675895;747.5850902748972;950.8336041192764;952.6379975609259;952.6665667016558;991.1872264742914;992.6773798515511;998.4059484606647;998.7503124218945;998.8701468809045;989.7624570103195;990.0544979544518;992.4789485520088;992.9602815887392;995.2088089968493;737.8561180569791;739.985200295991;742.6583771465132;743.5984568333093;746.947713660044;14773.852261477356;14836.406543738238;14848.480303189337;14884.851151488454;14914.552563423114;12414.62756117318;12431.689207769788;12510.74978500425;12544.372781360895;12569.74860502794;8490.40567160299;9830.21358291335;9999.75000624983;10032.592177877257;10218.540165692562;3320.236345640492;3484.407650699362;3497.9300413991577;3543.3799085159994;3651.5618125824803;81029;83158.58420707857;83401;83431.582842086;84213.15786842114;9369.859452108087;9413.48225847585;9448.826769842975;9617.27870409722;9890.109890109925;21511;21560.13759449636;21568.89215553912;21589.56820863574;21604.13583456644;7367.5211111277595;7435.553866768011;7527.096748390229;7644.885326720104;7712.035995500562;3989.9601003989587;4001.8399264029695;4034.4150098236178;4080.63274305308;4140.399642051885;3447.6552344765323;3564.1267889367273;3568.4647302904255;3571.62497937718;3572.7856328620364;641.0832509511444;644.4844124700221;653.5915053091818;653.8398092467281;655.583704347733;543.3344153412039;581.8138195777395;587.0050264801206;592.0290723213933;629.7071861584385;7542.811429714191;7603.505772124794;7663.770086897402;7838.804029899298;8042.477238979448;571.3600767140829;598.4763332084426;603.4508597176635;603.7011679218728;604.280905722198;3074.8308843013847;3360.983195084058;3740.0649837540614;3796.544414670202;3817.9045523862123;90620;93119;93279.53763699024;93304;94856;68841;79417.60291198507;79872.60063699761;80611.59694201492;81863.77204341939;52464.47535524672;58433.41566584365;61049.08426373608;62630.0605490918;71315.57368852594;7830.608469576612;8125.918740812516;8265.297449716601;8285.83428331442;8320.37597180226;32392.676073238967;42580.78709606432;44077.77961110174;44293.33559996538;45378.54621453743;56282;59953.40046599653;60131.699341503016;60167.699161503915;60339;10793.136549075898;10884;11050.447477626247;11148.721281968015;11411.429428528374;11946.462409191781;12005.699857503632;12175.756484870075;12433.502659893864;13017.674558136123;9946.850797238192;11410.315381077162;11652.776458471739;11659.067274618179;12280.81578776337;11414.828777568177;11530.711732206762;11541.134414919083;11569.768604627692;12455.626331210078;10876.758064355066;10891.891081089088;11150.776984460428;11215.43922803873;11281.548738050386;9283.907160928575;9296.349255552046;9473.857892131486;9572.473513956798;9585.9041409585;6827.52207345481;7676.385889128968;8509.74470765878;8569.742907712778;8742.562871856255 02:56:44 INFO - PROCESS | 1667 | __end_tp_report 02:56:44 INFO - PROCESS | 1667 | __start_cc_report 02:56:44 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1236 02:56:44 INFO - PROCESS | 1667 | __end_cc_report 02:56:44 INFO - PROCESS | 1667 | __startTimestamp1462874204744__endTimestamp 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | ------- Summary: start ------- 02:56:44 INFO - PROCESS | 1667 | Number of tests: 6 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5725.83 Median:5963.70 stddev:660.87 (11.1%) stddev-sans-first:627.63 02:56:44 INFO - PROCESS | 1667 | Values: 3010.7 3449.7 3463.4 3472.6 3518.0 5559.7 5617.4 5651.7 5704.7 5718.0 5621.3 5720.1 5748.5 5753.9 5791.8 5133.3 5139.4 5164.1 5191.9 5236.0 5208.0 5232.4 5248.7 5270.6 5283.6 5089.3 5108.2 5145.3 5183.6 5213.7 5143.3 5171.6 5196.4 5223.5 5237.6 5170.6 5176.5 5178.9 5180.7 5203.8 5447.5 5554.1 5603.7 5617.9 5703.8 6121.8 6137.7 6176.0 6204.4 6267.3 6295.3 6301.2 6337.3 6339.6 6356.7 4946.4 5674.1 5815.8 5890.7 5896.7 6241.2 6314.4 6318.3 6326.8 6329.5 6095.6 6122.8 6139.1 6168.8 6194.7 6134.8 6159.8 6279.2 6283.3 6303.7 6154.8 6190.9 6221.4 6227.6 6239.1 6251.9 6253.2 6257.5 6262.1 6269.4 4255.5 4887.2 4911.4 4940.5 4987.9 4443.2 4443.8 4451.2 4461.9 4490.7 5713.0 5715.4 5726.1 5733.4 5747.3 6149.9 6228.1 6275.0 6283.7 6315.8 5719.2 5746.3 5758.7 5759.9 5767.6 5391.9 5399.5 5406.6 5419.7 5426.5 5389.9 6247.9 6249.1 6332.1 6364.2 6333.1 6343.3 6345.5 6345.9 6351.0 6365.2 6372.6 6375.9 6384.8 6390.6 4355.3 5782.7 6098.9 6111.0 6197.3 6290.5 6305.5 6308.2 6310.8 6315.7 6079.1 6105.4 6114.5 6150.8 6152.7 5979.1 6017.0 6039.2 6044.2 6100.3 5936.0 5963.7 5964.2 5999.7 6002.9 5969.7 6035.4 6098.3 6119.9 6123.7 5811.7 5871.6 5933.8 5972.6 5991.4 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:71292.46 Median:18844.56 stddev:160709.67 (852.8%) stddev-sans-first:161072.66 02:56:44 INFO - PROCESS | 1667 | Values: 520.2 531.3 536.9 540.4 575.7 22032.9 42807.0 43779.3 44050.3 46292.8 44298.8 44448.3 44537.6 44550.6 44612.8 8553.1 9499.7 9680.9 9689.4 9693.8 8639.3 11330.6 11591.2 11613.4 11677.4 11614.6 11688.4 11704.6 11714.4 11725.5 8644.2 10029.6 11362.5 11612.5 11742.0 11500.4 11584.8 11597.5 11602.1 11602.7 39091.8 39313.0 39432.8 39490.8 39505.0 3210.8 3246.5 3338.4 3799.6 4093.4 28086.9 30066.2 30097.5 30141.0 32052.8 2561.3 2683.8 2685.0 2688.9 2743.9 544239.0 552155.0 553244.0 553865.0 555419.0 35064.6 64740.7 66242.0 66359.3 66456.0 34273.5 36147.0 36220.5 36315.6 36332.5 15230.5 15454.0 15497.4 15503.0 15514.8 11744.5 11976.7 12821.0 12944.4 12991.4 8140.6 8143.6 8151.2 8207.8 8230.3 3392.2 3409.5 3411.3 3415.6 3444.7 988.3 1031.8 1080.9 1085.3 1086.8 26417.6 29124.3 29241.8 29481.4 29828.7 1072.9 1141.3 1147.6 1150.9 1161.5 13682.5 14479.3 14484.0 14820.8 14921.6 681025.0 682491.0 686395.0 688248.0 688636.0 55020.7 57143.4 57203.7 57273.7 57332.9 1316.1 1364.0 1440.3 1472.1 1507.8 28585.7 29645.7 29660.0 30483.7 30508.1 49800.5 50358.5 50384.0 50562.5 50642.5 544828.0 552155.0 553657.0 554374.0 554631.0 22828.2 25249.7 25833.5 26096.6 27187.3 44774.3 46200.0 46818.8 47512.0 50277.5 37897.6 38014.1 38044.8 38400.8 38998.8 29319.0 37260.1 38037.4 38198.6 38351.0 37396.3 37668.8 38264.8 38329.2 38639.0 32610.2 33051.3 33602.8 33703.7 34062.3 24796.6 24804.0 25127.2 25206.4 25667.2 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:35085.27 Median:14066.66 stddev:103235.35 (733.9%) stddev-sans-first:103492.00 02:56:44 INFO - PROCESS | 1667 | Values: 350.6 388.7 393.4 404.3 404.9 19899.8 21881.9 22252.6 22316.8 22369.6 20389.3 21054.6 21419.3 21425.3 22081.7 5334.3 5539.9 5549.8 5552.6 5558.7 3915.1 4121.1 4768.3 5151.3 6189.9 6126.8 6168.5 6172.4 6180.7 6182.5 5953.6 6068.2 6116.6 6169.4 6170.8 3818.6 5653.0 5813.0 5979.4 5988.9 13825.0 13934.9 13943.2 24172.9 25376.6 2159.0 2177.8 2213.4 2343.4 2756.7 9371.8 9728.9 10048.1 10255.4 10436.1 1670.5 1707.0 1783.3 1813.9 1827.5 114907.4 119135.4 119176.0 119991.8 120700.4 11745.6 11766.6 11802.9 11810.7 11840.7 15221.4 15306.2 15314.6 15340.6 15432.6 7774.9 8749.2 8805.1 8806.0 8898.9 7077.5 8069.2 8613.1 8624.6 8783.3 4035.9 4584.7 4662.6 4690.6 4750.2 1595.7 1802.6 1843.3 1928.6 1968.4 875.6 875.8 950.9 985.9 1013.3 18336.6 19626.8 19680.9 19886.0 19897.9 898.9 933.4 941.0 949.7 953.4 10380.0 10537.4 10551.6 10599.3 10629.7 570296.0 622346.0 628489.0 632034.0 640484.0 125709.4 128130.0 128825.0 130508.3 131367.3 840.9 944.4 957.4 1073.4 1076.1 13844.1 14249.5 14293.6 14542.7 14570.6 49394.5 49791.5 50364.0 50432.0 52749.7 73367.0 74352.9 74893.0 75055.9 76515.0 6945.8 7422.8 8031.5 8089.1 8426.4 11392.3 12313.0 12391.0 12541.6 12590.1 9547.4 9760.5 10143.0 10973.7 11123.2 10552.3 10615.5 10705.0 10925.6 10998.4 10696.2 11040.3 11268.7 11338.4 11358.3 7370.7 7430.4 7502.3 7533.5 7709.0 8712.6 9433.8 10848.5 11077.7 11159.7 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5602.32 Median:4797.65 stddev:3187.17 (66.4%) stddev-sans-first:3180.70 02:56:44 INFO - PROCESS | 1667 | Values: 1432.1 1475.6 1618.7 1813.4 1971.6 7717.3 8509.4 8770.7 8779.6 8888.2 8496.6 8719.7 8742.5 8752.3 8785.5 2141.6 2227.7 2349.0 2350.6 2354.4 2320.8 2348.7 2361.9 2371.4 2374.4 2335.4 2354.6 2355.5 2378.8 2380.0 2028.4 2307.7 2336.2 2340.8 2343.1 2335.1 2336.4 2340.0 2342.0 2350.3 8459.3 8557.7 8605.9 8650.9 8786.4 5164.8 5349.5 5360.8 5365.7 5372.2 3061.6 3415.9 3454.7 3545.9 3589.7 1010.6 1056.4 1057.0 1063.5 1063.6 11495.5 11611.5 11613.0 11613.2 11655.2 4392.0 4418.7 4497.0 4540.6 4544.6 9062.4 9070.5 9079.0 9083.0 9087.1 5058.3 5143.0 5146.1 5147.3 5152.0 4257.6 4440.6 4645.6 4734.4 4788.7 3718.5 3742.2 3795.4 3824.1 3845.9 1182.3 1184.6 1185.6 1193.3 1207.5 629.8 784.8 893.9 937.7 946.5 5640.0 5812.0 5876.4 6002.0 6013.8 484.2 506.0 512.4 513.6 651.9 3746.8 3875.0 3920.9 3995.9 3996.9 10287.4 10741.3 10867.3 10870.6 10885.7 10769.1 10769.3 10788.3 10792.4 10818.9 7236.7 7614.5 7914.6 8096.6 8151.6 5921.0 5941.8 6043.6 6064.9 6080.9 8671.0 10050.4 10178.6 10183.1 10241.3 9972.3 10172.7 10323.1 10380.2 10422.7 7400.2 7469.6 7544.8 7557.1 7558.4 6794.6 8098.5 8103.0 8116.6 8198.9 7306.4 7327.2 7357.2 7489.1 7501.4 6904.4 7159.8 7180.4 7238.6 7262.2 6857.6 6865.2 6943.0 6943.9 6948.5 4404.0 4724.8 4826.3 4907.3 4922.1 4247.3 4806.6 4880.1 4941.1 4951.8 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4349.89 Median:4831.60 stddev:1329.06 (27.5%) stddev-sans-first:1297.66 02:56:44 INFO - PROCESS | 1667 | Values: 305.8 316.6 319.7 320.6 327.2 5163.8 5270.4 5284.3 5289.9 5395.8 4732.3 5116.4 5135.9 5289.4 5295.1 3609.5 3630.2 3664.4 3824.0 3846.9 3090.2 3965.2 3995.4 3999.4 4027.9 3509.5 3944.6 3996.2 4007.6 4018.7 3715.1 3728.4 3787.7 3936.9 4010.0 2867.8 3462.4 3481.1 3487.7 3521.2 4189.7 4964.8 4965.5 5002.6 5020.9 4085.9 4202.8 4524.8 4555.9 4572.2 5434.1 5475.8 5478.9 5496.6 5523.3 3601.3 3651.2 3802.6 3803.9 3830.9 5403.7 5771.6 5820.2 5821.2 5835.5 3480.3 3801.8 3832.3 3836.1 3845.8 3358.9 3735.5 3807.9 3857.4 3881.8 4510.2 4539.3 4547.4 4565.3 4575.5 5280.9 5513.9 5543.0 5546.9 5563.3 1147.5 1220.0 1245.2 1255.8 1271.7 800.7 819.7 845.4 853.4 857.8 4454.2 4831.6 5032.4 5034.7 5189.3 4929.5 5676.0 5742.3 5760.7 5765.6 4148.9 4620.8 4834.9 5212.3 5328.3 2447.6 2678.5 2832.4 2862.0 2872.8 4821.5 5183.8 5290.5 5629.2 5719.3 4689.9 4738.0 5305.8 5535.0 5699.7 5271.6 5618.9 5632.4 5674.6 5737.0 4654.9 4892.4 4902.9 5207.9 5327.8 5080.3 5365.3 5428.9 5465.5 5479.6 5195.4 5524.5 5615.1 5658.9 5664.6 3604.0 3683.8 3713.3 3750.5 3763.9 4175.6 4726.1 4965.1 5309.8 5366.8 4620.9 4851.0 5194.8 5223.2 5262.0 4419.5 4566.2 5261.7 5262.9 5270.4 4302.7 4661.8 4720.3 4774.1 5024.5 4831.6 4854.1 5164.9 5183.6 5378.8 4466.6 4667.7 5391.0 5403.5 5429.8 02:56:44 INFO - PROCESS | 1667 | 02:56:44 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17644.57 Median:30471.64 stddev:24843.74 (81.5%) stddev-sans-first:24880.50 02:56:44 INFO - PROCESS | 1667 | Values: 609.6 650.3 756.9 762.8 763.3 13628.7 14160.2 14687.6 15469.7 15501.4 15062.2 15506.5 15519.7 15952.7 15960.8 940.7 943.3 951.3 951.7 955.0 737.1 747.6 950.8 952.6 952.7 991.2 992.7 998.4 998.8 998.9 989.8 990.1 992.5 993.0 995.2 737.9 740.0 742.7 743.6 746.9 14773.9 14836.4 14848.5 14884.9 14914.6 12414.6 12431.7 12510.7 12544.4 12569.7 8490.4 9830.2 9999.8 10032.6 10218.5 3320.2 3484.4 3497.9 3543.4 3651.6 81029.0 83158.6 83401.0 83431.6 84213.2 9369.9 9413.5 9448.8 9617.3 9890.1 21511.0 21560.1 21568.9 21589.6 21604.1 7367.5 7435.6 7527.1 7644.9 7712.0 3990.0 4001.8 4034.4 4080.6 4140.4 3447.7 3564.1 3568.5 3571.6 3572.8 641.1 644.5 653.6 653.8 655.6 543.3 581.8 587.0 592.0 629.7 7542.8 7603.5 7663.8 7838.8 8042.5 571.4 598.5 603.5 603.7 604.3 3074.8 3361.0 3740.1 3796.5 3817.9 90620.0 93119.0 93279.5 93304.0 94856.0 68841.0 79417.6 79872.6 80611.6 81863.8 52464.5 58433.4 61049.1 62630.1 71315.6 7830.6 8125.9 8265.3 8285.8 8320.4 32392.7 42580.8 44077.8 44293.3 45378.5 56282.0 59953.4 60131.7 60167.7 60339.0 10793.1 10884.0 11050.4 11148.7 11411.4 11946.5 12005.7 12175.8 12433.5 13017.7 9946.9 11410.3 11652.8 11659.1 12280.8 11414.8 11530.7 11541.1 11569.8 12455.6 10876.8 10891.9 11150.8 11215.4 11281.5 9283.9 9296.3 9473.9 9572.5 9585.9 6827.5 7676.4 8509.7 8569.7 8742.6 02:56:44 INFO - PROCESS | 1667 | -------- Summary: end -------- 02:56:44 INFO - PROCESS | 1667 | 02:56:45 INFO - TEST-INFO | 1667: exit 0 02:56:45 INFO - TEST-OK | dromaeo_css | took 1115269ms 02:56:45 INFO - TEST-START | kraken 02:56:45 INFO - operating with platform_type : mac_ 02:56:45 INFO - Initialising browser for kraken test... 02:56:45 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpNsk7Mz/profile http://localhost:49270/getInfo.html) 02:56:47 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 02:56:47 INFO - PROCESS | 1677 | colorDepth:24 02:56:47 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 02:56:47 INFO - PROCESS | 1677 | __metrics 02:56:47 INFO - PROCESS | 1677 | 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" 02:56:47 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:56:48 INFO - TEST-INFO | 1677: exit 0 02:56:48 INFO - Browser initialized. 02:56:48 INFO - Running cycle 1/1 for kraken test... 02:56:48 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpNsk7Mz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:57:00 INFO - PROCESS | 1679 | RSS: Main: 238817280 02:57:00 INFO - PROCESS | 1679 | 02:57:03 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 02:57:03 INFO - PROCESS | 1679 | RSS: Main: 287657984 02:57:03 INFO - PROCESS | 1679 | 02:57:06 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 02:57:07 INFO - PROCESS | 1679 | RSS: Main: 295399424 02:57:07 INFO - PROCESS | 1679 | 02:57:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 02:57:11 INFO - PROCESS | 1679 | RSS: Main: 386547712 02:57:11 INFO - PROCESS | 1679 | 02:57:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 02:57:14 INFO - PROCESS | 1679 | RSS: Main: 285343744 02:57:14 INFO - PROCESS | 1679 | 02:57:16 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:57:17 INFO - PROCESS | 1679 | RSS: Main: 288694272 02:57:17 INFO - PROCESS | 1679 | 02:57:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 02:57:20 INFO - PROCESS | 1679 | RSS: Main: 326926336 02:57:20 INFO - PROCESS | 1679 | 02:57:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 02:57:24 INFO - PROCESS | 1679 | RSS: Main: 330010624 02:57:24 INFO - PROCESS | 1679 | 02:57:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 02:57:28 INFO - PROCESS | 1679 | RSS: Main: 328876032 02:57:28 INFO - PROCESS | 1679 | 02:57:29 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:57:30 INFO - PROCESS | 1679 | RSS: Main: 315699200 02:57:30 INFO - PROCESS | 1679 | 02:57:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:57:33 INFO - PROCESS | 1679 | RSS: Main: 323022848 02:57:33 INFO - PROCESS | 1679 | 02:57:35 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:57:35 INFO - PROCESS | 1679 | RSS: Main: 324177920 02:57:35 INFO - PROCESS | 1679 | 02:57:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:57:39 INFO - PROCESS | 1679 | RSS: Main: 328806400 02:57:39 INFO - PROCESS | 1679 | 02:57:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:57:43 INFO - PROCESS | 1679 | RSS: Main: 326684672 02:57:43 INFO - PROCESS | 1679 | 02:57:44 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 02:57:45 INFO - PROCESS | 1679 | RSS: Main: 372981760 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | __start_tp_report 02:57:45 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 02:57:45 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 02:57:45 INFO - PROCESS | 1679 | |i|pagename|runs| 02:57:45 INFO - PROCESS | 1679 | |0;ai-astar;111;106;112;112;110;113;111;112;111;111 02:57:45 INFO - PROCESS | 1679 | |1;audio-beat-detection;180;144;128;245;141;170;138;116;167;117 02:57:45 INFO - PROCESS | 1679 | |2;audio-dft;185;185;186;187;186;184;185;185;182;185 02:57:45 INFO - PROCESS | 1679 | |3;audio-fft;78;72;57;124;59;64;56;59;114;55 02:57:45 INFO - PROCESS | 1679 | |4;audio-oscillator;92;91;136;79;81;105;135;111;80;100 02:57:45 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;87;86;86;85;87;85;88;84;88;83 02:57:45 INFO - PROCESS | 1679 | |6;imaging-darkroom;93;94;94;94;93;92;107;150;92;93 02:57:45 INFO - PROCESS | 1679 | |7;imaging-desaturate;85;85;82;84;86;83;85;84;86;85 02:57:45 INFO - PROCESS | 1679 | |8;json-parse-financial;57;58;59;56;57;60;60;121;56;57 02:57:45 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;63;70;71;72;70;66;66;68;69;68 02:57:45 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;83;82;81;84;85;82;85;84;86;98 02:57:45 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;140;136;139;136;137;137;137;134;209;140 02:57:45 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;166;166;166;168;166;165;166;164;163;170 02:57:45 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;67;69;67;67;69;82;80;81;81 02:57:45 INFO - PROCESS | 1679 | __end_tp_report 02:57:45 INFO - PROCESS | 1679 | __start_cc_report 02:57:45 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2276 02:57:45 INFO - PROCESS | 1679 | __end_cc_report 02:57:45 INFO - PROCESS | 1679 | __startTimestamp1462874265189__endTimestamp 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | ------- Summary: start ------- 02:57:45 INFO - PROCESS | 1679 | Number of tests: 14 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:110.90 Median:111.50 stddev:1.91 (1.7%) stddev-sans-first:2.03 02:57:45 INFO - PROCESS | 1679 | Values: 111.0 106.0 112.0 112.0 110.0 113.0 111.0 112.0 111.0 111.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:154.60 Median:155.50 stddev:38.58 (24.8%) stddev-sans-first:39.81 02:57:45 INFO - PROCESS | 1679 | Values: 180.0 144.0 128.0 245.0 141.0 170.0 138.0 116.0 167.0 117.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:1.33 (0.7%) stddev-sans-first:1.41 02:57:45 INFO - PROCESS | 1679 | Values: 185.0 185.0 186.0 187.0 186.0 184.0 185.0 185.0 182.0 185.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:73.80 Median:59.00 stddev:25.04 (42.4%) stddev-sans-first:26.51 02:57:45 INFO - PROCESS | 1679 | Values: 78.0 72.0 57.0 124.0 59.0 64.0 56.0 59.0 114.0 55.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:101.00 Median:79.50 stddev:21.09 (26.5%) stddev-sans-first:22.12 02:57:45 INFO - PROCESS | 1679 | Values: 92.0 91.0 136.0 79.0 81.0 105.0 135.0 111.0 80.0 100.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.50 stddev:1.66 (1.9%) stddev-sans-first:1.72 02:57:45 INFO - PROCESS | 1679 | Values: 87.0 86.0 86.0 85.0 87.0 85.0 88.0 84.0 88.0 83.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:93.00 stddev:18.05 (19.4%) stddev-sans-first:18.95 02:57:45 INFO - PROCESS | 1679 | Values: 93.0 94.0 94.0 94.0 93.0 92.0 107.0 150.0 92.0 93.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.27 (1.5%) stddev-sans-first:1.33 02:57:45 INFO - PROCESS | 1679 | Values: 85.0 85.0 82.0 84.0 86.0 83.0 85.0 84.0 86.0 85.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:57.50 stddev:20.05 (34.9%) stddev-sans-first:21.10 02:57:45 INFO - PROCESS | 1679 | Values: 57.0 58.0 59.0 56.0 57.0 60.0 60.0 121.0 56.0 57.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:69.50 stddev:2.71 (3.9%) stddev-sans-first:2.09 02:57:45 INFO - PROCESS | 1679 | Values: 63.0 70.0 71.0 72.0 70.0 66.0 66.0 68.0 69.0 68.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:84.50 stddev:4.83 (5.7%) stddev-sans-first:5.07 02:57:45 INFO - PROCESS | 1679 | Values: 83.0 82.0 81.0 84.0 85.0 82.0 85.0 84.0 86.0 98.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:138.00 stddev:22.74 (16.5%) stddev-sans-first:24.06 02:57:45 INFO - PROCESS | 1679 | Values: 140.0 136.0 139.0 136.0 137.0 137.0 137.0 134.0 209.0 140.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.00 stddev:1.94 (1.2%) stddev-sans-first:2.06 02:57:45 INFO - PROCESS | 1679 | Values: 166.0 166.0 166.0 168.0 166.0 165.0 166.0 164.0 163.0 170.0 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:74.50 stddev:6.94 (9.3%) stddev-sans-first:7.02 02:57:45 INFO - PROCESS | 1679 | Values: 67.0 67.0 69.0 67.0 67.0 69.0 82.0 80.0 81.0 81.0 02:57:45 INFO - PROCESS | 1679 | -------- Summary: end -------- 02:57:45 INFO - PROCESS | 1679 | 02:57:45 INFO - TEST-INFO | 1679: exit 0 02:57:45 INFO - TEST-OK | kraken | took 60712ms 02:57:45 INFO - SUITE-END | took 1175s 02:57:46 INFO - Completed test suite (00:19:36) 02:57:46 INFO - javascript benchmark 02:57:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3010.6688264290924, 3449.7067749241314, 3463.376592213402, 3472.618012018679, 3517.9648203517972, 5559.722013899299, 5617.38208797032, 5651.660900345983, 5704.743286552105, 5717.971410142949, 5621.269234999455, 5720.141978703186, 5748.540116790647, 5753.942460575393, 5791.82624521264, 5133.30700355451, 5139.434662187156, 5164.070467315882, 5191.948080519203, 5236.0, 5208.0, 5232.398274198459, 5248.6588371755715, 5270.5520030797425, 5283.603729720267, 5089.262057001751, 5108.182690769496, 5145.279660847485, 5183.585313174937, 5213.713245771519, 5143.279940808253, 5171.560417364529, 5196.402413722432, 5223.529882310572, 5237.633365664405, 5170.586353091744, 5176.534111929906, 5178.948210517922, 5180.6891586504935, 5203.817866374697, 5447.455254474521, 5554.0835762099605, 5603.663780173202, 5617.859553511195, 5703.828885133411, 6121.816345509642, 6137.6624285664275, 6176.042713379434, 6204.410580994843, 6267.310595834455, 6295.307516173218, 6301.180846489973, 6337.334579869093, 6339.556231063827, 6356.745730170787, 4946.3817022872145, 5674.120511320752, 5815.82552523425, 5890.70546472679, 5896.6756828374855, 6241.21984751906, 6314.368563143741, 6318.304986451487, 6326.841828954313, 6329.525285603599, 6095.573309868355, 6122.7550897964475, 6139.140520327158, 6168.784092556829, 6194.721237544321, 6134.8346010261785, 6159.753609855556, 6279.215098112736, 6283.277423096402, 6303.653299068504, 6154.815355539341, 6190.907136392958, 6221.4089661482085, 6227.564070515111, 6239.095331176954, 6251.906221406683, 6253.249610046823, 6257.530685198628, 6262.12330273762, 6269.435750782361, 4255.468066491689, 4887.218045112765, 4911.4106307243355, 4940.481249468754, 4987.925181122286, 4443.155800397914, 4443.844465443694, 4451.198784218872, 4461.866144015685, 4490.730556166641, 5712.971435142797, 5715.371309155989, 5726.1410788381245, 5733.426657334317, 5747.339056008529, 6149.938500615026, 6228.096925945712, 6274.968625156845, 6283.685815709287, 6315.842103947438, 5719.19931209631, 5746.339170995306, 5758.740856661377, 5759.942400576025, 5767.567432442584, 5391.946080539223, 5399.514043736082, 5406.648567843077, 5419.674819510842, 5426.538744206787, 5389.946100538945, 6247.937520624827, 6249.0626406039455, 6332.11350410952, 6364.204474440695, 6333.050042493663, 6343.270523889627, 6345.524085693499, 6345.904811427925, 6351.0, 6365.2361716594305, 6372.6495042773095, 6375.936240637534, 6384.776532821329, 6390.648514331757, 4355.324924636687, 5782.653040817564, 6098.9466267744565, 6111.0, 6197.349278325711, 6290.528210384331, 6305.464035557029, 6308.211473565804, 6310.84222894413, 6315.747370105144, 6079.088136779518, 6105.389461053859, 6114.510839132948, 6150.7539698411565, 6152.723127459186, 5979.073243647242, 6016.969915150396, 6039.214902062704, 6044.184035155197, 6100.267967843887, 5936.020556608111, 5963.701814909324, 5964.165016897725, 5999.6700181488695, 6002.909956350571, 5969.671668058299, 6035.414909974774, 6098.324177734433, 6119.896141176215, 6123.730452203222, 5811.680357580204, 5871.559633027455, 5933.821985340274, 5972.611780234271, 5991.400859913973], "value": 5683.948198539974, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.2040877457489, 531.2907268796157, 536.8872536767284, 540.3812634533459, 575.6776205325019, 22032.88983555082, 42807.0, 43779.34330985038, 44050.33924491131, 46292.76853615735, 44298.778506107505, 44448.33327500082, 44537.55462445375, 44550.55449445505, 44612.776936115355, 8553.059163492042, 9499.667511637093, 9680.90319096807, 9689.370190937567, 9693.806123877519, 8639.308855291592, 11330.603428879991, 11591.188616796828, 11613.419329033515, 11677.416129193549, 11614.593489227878, 11688.357140357277, 11704.648860534198, 11714.355710435922, 11725.530978760838, 8644.222020018165, 10029.59881604735, 11362.545498180063, 11612.535498580046, 11742.001929835931, 11500.424978751029, 11584.826227606593, 11597.478113484913, 11602.129840262014, 11602.709932251677, 39091.8045409774, 39313.0, 39432.80283598592, 39490.802545987375, 39505.012374690865, 3210.7912985655857, 3246.4643333849885, 3338.382399256145, 3799.563050249229, 4093.406456063854, 28086.859565702245, 30066.24834379136, 30097.548536771967, 30141.0, 32052.83973580117, 2561.3084467193753, 2683.825551339157, 2685.0468083830156, 2688.9193324200305, 2743.931401714973, 544239.0, 552155.0, 553244.0, 553865.0, 555419.0, 35064.64935350614, 64740.676296618214, 66242.00636990448, 66359.33640663531, 66456.0, 34273.48589771155, 36147.0, 36220.45669314962, 36315.63684363176, 36332.455013174826, 15230.543083707507, 15454.0, 15497.380104795908, 15503.0, 15514.767278490832, 11744.530218791324, 11976.700582485508, 12821.0, 12944.417501212469, 12991.415386307639, 8140.633671484679, 8143.552104634184, 8151.225633564802, 8207.835843283101, 8230.341572674171, 3392.1519620094978, 3409.4544872820725, 3411.3347897159815, 3415.64135765746, 3444.689977902001, 988.2637435110888, 1031.8297480915714, 1080.8919108089128, 1085.2674444749762, 1086.8424078508572, 26417.603735943976, 29124.271893202415, 29241.760911493697, 29481.410371792874, 29828.701712983027, 1072.9034386905216, 1141.2809929744374, 1147.6098126636816, 1150.9366984815915, 1161.5005547614437, 13682.52111176104, 14479.348429320706, 14484.0, 14820.777688334683, 14921.552353429415, 681025.0, 682491.0, 686395.0, 688248.0, 688636.0, 55020.724896376065, 57143.42856571464, 57203.71398143066, 57273.71363143158, 57332.85334293374, 1316.1379296560767, 1363.9659008524668, 1440.3014537948877, 1472.138798802691, 1507.811523559555, 28585.714142858305, 29645.703542964293, 29660.0, 30483.69516304897, 30508.084757457313, 49800.50598482051, 50358.49641503685, 50384.0, 50562.49437505578, 50642.493575063774, 544828.0, 552155.0, 553657.0, 554374.0, 554631.0, 22828.201012964466, 25249.74750252474, 25833.483330333664, 26096.608550871373, 27187.320316992234, 44774.3283850749, 46200.0, 46818.82952926204, 47512.0, 50277.49722502875, 37897.62102378941, 38014.06455781086, 38044.80977595094, 38400.80799595984, 38998.805005975926, 29319.0, 37260.06849828776, 38037.42943855789, 38198.61801382062, 38351.0, 37396.2520749578, 37668.81165594154, 38264.80867595644, 38329.23341533209, 38639.0, 32610.184745381554, 33051.33897321992, 33602.83198583991, 33703.66296337005, 34062.31875362429, 24796.56916648348, 24804.0, 25127.24618261455, 25206.369840753396, 25667.229983100537], "value": 19072.271457390623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.6493506493506, 388.7337174035785, 393.36752477219534, 404.304596094717, 404.88865561970454, 19899.801001989992, 21881.890590547024, 22252.55494890101, 22316.77683223169, 22369.55260894785, 20389.28637497688, 21054.631448955806, 21419.250326238584, 21425.250116245934, 22081.668774968388, 5334.333208348957, 5539.9169012464845, 5549.750261238234, 5552.611317207797, 5558.749856256458, 3915.099527108767, 4121.113960498492, 4768.332433459318, 5151.330327057496, 6189.90715139271, 6126.846828829271, 6168.475679567242, 6172.4444799968, 6180.660063696495, 6182.536309776785, 5953.58324917256, 6068.180795592582, 6116.571839971204, 6169.352218017111, 6170.814875553718, 3818.618138186187, 5652.971735141298, 5813.040848260032, 5979.431953964367, 5988.9101663475085, 13825.0, 13934.93032534841, 13943.16341019542, 24172.879135604384, 25376.619350709752, 2158.9676154857534, 2177.825773938081, 2213.3581261434165, 2343.4492894169857, 2756.699548704028, 9371.812563748756, 9728.878747720722, 10048.095671389538, 10255.384676919408, 10436.060754532053, 1670.4905003973784, 1707.0, 1783.2777725021388, 1813.86396020299, 1827.4608990347879, 114907.42546287215, 119135.40432297783, 119176.0, 119991.80008199981, 120700.39649801783, 11745.647630571097, 11766.647000589996, 11802.88197118035, 11810.704732381588, 11840.703982400337, 15221.391144354102, 15306.158161301013, 15314.617134571725, 15340.616484587974, 15432.614184645474, 7774.922250777533, 8749.212570868525, 8805.119488051143, 8805.955970220108, 8898.86651700225, 7077.504574679826, 8069.193080691884, 8613.138686131337, 8624.568771561397, 8783.34124940632, 4035.9394609080887, 4584.6561507887045, 4662.6269898408045, 4690.577847993697, 4750.192467280571, 1595.7207488689432, 1802.6304607555683, 1843.3363789324565, 1928.5660726336744, 1968.4094771568473, 875.6349597257665, 875.7898104455012, 950.8763860698065, 985.8525688756515, 1013.2957594471771, 18336.633267334844, 19626.803731962784, 19680.90159549193, 19886.0435786105, 19897.900510497355, 898.9235914947303, 933.3839665820526, 941.0448394879203, 949.7198326493701, 953.423178976723, 10379.96200379956, 10537.367757934548, 10551.630692925712, 10599.258051936291, 10629.681109566725, 570296.0, 622346.0, 628489.0, 632034.0, 640484.0, 125709.37145314398, 128130.0, 128825.0, 130508.347458264, 131367.3431632855, 840.8906842110608, 944.3955868244329, 957.4111921168432, 1073.3988966178965, 1076.1060533461164, 13844.10013349129, 14249.50126745559, 14293.57119286423, 14542.709145817236, 14570.562883113524, 49394.50605493899, 49791.50208497869, 50364.0, 50431.99136017333, 52749.7362513185, 73367.03013213736, 74352.88470672836, 74893.0, 75055.87416188871, 76515.0, 6945.756898508528, 7422.777316680508, 8031.477953932976, 8089.069756977788, 8426.41015128935, 11392.25950313227, 12313.0, 12391.008719302614, 12541.561045363369, 12590.11869169186, 9547.42715437076, 9760.51197440111, 10143.036411540892, 10973.725656858642, 11123.221374503708, 10552.261341706007, 10615.469226538797, 10705.0, 10925.617603383844, 10998.395088270221, 10696.197785165989, 11040.282381645167, 11268.718282043015, 11338.433078345884, 11358.318500889973, 7370.742024029133, 7430.368418684472, 7502.287282708134, 7533.54798712079, 7708.997830282252, 8712.607932643174, 9433.764155896157, 10848.511816968414, 11077.723056923642, 11159.72100697489], "value": 9265.506277766211, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.05484380309, 1475.6089636246393, 1618.6969489560906, 1813.3743858368857, 1971.5563998100429, 7717.266859648332, 8509.404341696083, 8770.6930257441, 8779.648814047438, 8888.20006199442, 8496.61765220565, 8719.738407847759, 8742.519161446118, 8752.29981601472, 8785.516796576187, 2141.550274442369, 2227.6881236626928, 2348.988255058722, 2350.588646986779, 2354.4231663242444, 2320.8027317678016, 2348.729896061958, 2361.8781078987518, 2371.3715865295626, 2374.442006128558, 2335.439494521311, 2354.6232602783557, 2355.49356888269, 2378.8334816562847, 2379.9643005354933, 2028.3712049264627, 2307.699999000136, 2336.2407217654245, 2340.765923407654, 2343.0627748900406, 2335.112657190257, 2336.427575244059, 2339.9532009359887, 2341.976580234193, 2350.294911526535, 8459.280961118313, 8557.743267701979, 8605.924259467567, 8650.87023694645, 8786.428882122642, 5164.767585458663, 5349.545288650469, 5360.758765855507, 5365.731713414314, 5372.1941708744, 3061.6479104902774, 3415.8633654653786, 3454.671806178434, 3545.85816567337, 3589.6948759355487, 1010.5957616953204, 1056.4453661827524, 1056.9630062947838, 1063.4682658670665, 1063.5745701719375, 11495.482703278289, 11611.535538578446, 11613.012893904028, 11613.245139065933, 11655.242409243372, 4391.978040109843, 4418.690691651554, 4496.988177660066, 4540.614047805973, 4544.613707834871, 9062.410943288665, 9070.501122438198, 9078.954605226932, 9083.046280140623, 9087.136722011399, 5058.317127187856, 5143.022825663112, 5146.125158723026, 5147.279380886679, 5151.97424012885, 4257.595528424795, 4440.6447484201835, 4645.628349732014, 4734.4081989751285, 4788.664793464493, 3718.535183102112, 3742.1580144467275, 3795.35478968576, 3824.120452295932, 3845.942310865339, 1182.296533562529, 1184.59131599598, 1185.6265276438066, 1193.254216114928, 1207.5290636651712, 629.8362425769333, 784.7796676168499, 893.946363218209, 937.6952490440634, 946.5409276500892, 5639.971800141055, 5811.9709401453565, 5876.44173803488, 6001.979663457223, 6013.789517366871, 484.1503161950754, 506.02842542318837, 512.423523536022, 513.5788653304329, 651.9471054247451, 3746.756460830037, 3874.9612503875164, 3920.882373528799, 3995.9200815983518, 3996.86010989614, 10287.434191119411, 10741.301815381976, 10867.347959122479, 10870.565177392973, 10885.67342979696, 10769.138468922467, 10769.299995500267, 10788.298760580537, 10792.352458852494, 10818.945905270422, 7236.674349654279, 7614.466987310946, 7914.564698941498, 8096.595170241464, 8151.633176506953, 5921.0, 5941.792037278848, 6043.607165534227, 6064.939350606437, 6080.878382432238, 8671.0, 10050.396976181453, 10178.643747468803, 10183.13443357323, 10241.334313269614, 9972.252081094091, 10172.694819155437, 10323.122534584645, 10380.169586433218, 10422.687319380431, 7400.222976587596, 7469.58917259556, 7544.8491030180185, 7557.05536807899, 7558.3953283738265, 6794.558353706993, 8098.51408915467, 8103.0, 8116.594170291343, 8198.934138562186, 7306.378957788647, 7327.157376901511, 7357.153927298429, 7489.13874904393, 7501.38720175165, 6904.378605925491, 7159.821004474929, 7180.3537681608905, 7238.6380680964685, 7262.237465066092, 6857.55425897315, 6865.244823069358, 6942.993265976506, 6943.930560694328, 6948.5483443576, 4403.955960440355, 4724.763761811964, 4826.348442960156, 4907.312976183409, 4922.138625740553, 4247.256730072287, 4806.639502037291, 4880.072786170616, 4941.061198372297, 4951.752412379438], "value": 4431.506544554745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.7599784169427, 316.5915968400763, 319.6691424375771, 320.5736370627065, 327.20489211216733, 5163.751860249094, 5270.367555893294, 5284.2866213061225, 5289.8942021159555, 5395.811146609864, 4732.337472753818, 5116.437191908887, 5135.922961155577, 5289.408189986898, 5295.073362161616, 3609.5488063992, 3630.2376500934834, 3664.4136938089846, 3823.961760382402, 3846.8653597124107, 3090.1965488972805, 3965.187136636997, 3995.3607422812356, 3999.4200840878057, 4027.932597861569, 3509.4560343146854, 3944.5660977292473, 3996.240714264295, 4007.5591684914634, 4018.6939244745427, 3715.0620342436832, 3728.440733889911, 3787.6780473659774, 3936.877989772901, 4009.957411073113, 2867.813592116506, 3462.4113900636953, 3481.147118955872, 3487.7035451986612, 3521.154922818531, 4189.748615083105, 4964.776585053682, 4965.528274813887, 5002.59979201663, 5020.924686129711, 4085.937656209377, 4202.768847713374, 4524.841630542948, 4555.931661025087, 4572.199865023607, 5434.130539113762, 5475.835724928219, 5478.91781623276, 5496.560275177976, 5523.337199536041, 3601.2617413430185, 3651.233241019389, 3802.5817160112365, 3803.885883423474, 3830.942535861964, 5403.730433218057, 5771.567132465082, 5820.185174075633, 5821.15593238978, 5835.474807267324, 3480.3387356402204, 3801.809909504569, 3832.3101841668768, 3836.1176929306416, 3845.7501312073687, 3358.865645374158, 3735.51438313023, 3807.904802379963, 3857.4213867920034, 3881.7865017424315, 4510.233260345749, 4539.319102134707, 4547.386102876069, 4565.269556870885, 4575.5424457554645, 5280.910667259619, 5513.9448605514235, 5542.972285138549, 5546.86132846674, 5563.276774019351, 1147.5409836065473, 1219.9634010979685, 1245.2030700351781, 1255.813256472643, 1271.707507273314, 800.6509032280626, 819.7499762572353, 845.446232717571, 853.3898262742046, 857.7726902370877, 4454.175977444183, 4831.589314908272, 5032.370953630796, 5034.748262586856, 5189.273501709764, 4929.457759646436, 5676.0, 5742.28221472316, 5760.74076666551, 5765.596408251382, 4148.896277593084, 4620.821690468976, 4834.903301933941, 5212.296339994128, 5328.254044433782, 2447.559439300943, 2678.531257030012, 2832.362718388346, 2861.9680027180934, 2872.8023062357315, 4821.54195351441, 5183.766730497136, 5290.4766424707095, 5629.198656429903, 5719.28508936383, 4689.882752931136, 4737.976310118496, 5305.840824775263, 5534.976029434568, 5699.74351154199, 5271.6309858310315, 5618.85952851182, 5632.436756324499, 5674.602777805514, 5737.0, 4654.858764968152, 4892.3884514435695, 4902.901941961212, 5207.947920520746, 5327.840164795062, 5080.339555857862, 5365.25175219941, 5428.914217156505, 5465.508104270635, 5479.589030822784, 5195.350581177353, 5524.475174858382, 5615.129654903497, 5658.9151162733415, 5664.603477756682, 3603.9639603603628, 3683.834227459717, 3713.2573485303587, 3750.4749335092565, 3763.9247215056093, 4175.645070168948, 4726.109556177556, 4965.106280869334, 5309.814156504503, 5366.7584958677735, 4620.861374158781, 4850.957044235506, 5194.831162988297, 5223.242629818579, 5261.973690131525, 4419.535948725466, 4566.219174522005, 5261.7111556473055, 5262.947370526399, 5270.446603106619, 4302.698811083319, 4661.836835710734, 4720.268358404453, 4774.116788394158, 5024.472430394756, 4831.613470922248, 4854.126257273725, 5164.896702066013, 5183.611229157904, 5378.811741589026, 4466.57567531084, 4667.712820717892, 5391.0, 5403.486668766461, 5429.837104886859], "value": 3929.7527527756984, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.5954096371763, 650.3301599352666, 756.9356604688603, 762.8474305138974, 763.3015790551646, 13628.659283517916, 14160.150390976549, 14687.632809179775, 15469.690606187869, 15501.37994480221, 15062.246887655629, 15506.457273995413, 15519.689606207869, 15952.680946381095, 15960.76058859118, 940.666063547441, 943.2925306020502, 951.3435729346763, 951.6954574536152, 954.9522523873813, 737.1375490675895, 747.5850902748972, 950.8336041192764, 952.6379975609259, 952.6665667016558, 991.1872264742914, 992.6773798515511, 998.4059484606647, 998.7503124218945, 998.8701468809045, 989.7624570103195, 990.0544979544518, 992.4789485520088, 992.9602815887392, 995.2088089968493, 737.8561180569791, 739.985200295991, 742.6583771465132, 743.5984568333093, 746.947713660044, 14773.852261477356, 14836.406543738238, 14848.480303189337, 14884.851151488454, 14914.552563423114, 12414.62756117318, 12431.689207769788, 12510.74978500425, 12544.372781360895, 12569.74860502794, 8490.40567160299, 9830.21358291335, 9999.75000624983, 10032.592177877257, 10218.540165692562, 3320.236345640492, 3484.407650699362, 3497.9300413991577, 3543.3799085159994, 3651.5618125824803, 81029.0, 83158.58420707857, 83401.0, 83431.582842086, 84213.15786842114, 9369.859452108087, 9413.48225847585, 9448.826769842975, 9617.27870409722, 9890.109890109925, 21511.0, 21560.13759449636, 21568.89215553912, 21589.56820863574, 21604.13583456644, 7367.5211111277595, 7435.553866768011, 7527.096748390229, 7644.885326720104, 7712.035995500562, 3989.9601003989587, 4001.8399264029695, 4034.4150098236178, 4080.63274305308, 4140.399642051885, 3447.6552344765323, 3564.1267889367273, 3568.4647302904255, 3571.62497937718, 3572.7856328620364, 641.0832509511444, 644.4844124700221, 653.5915053091818, 653.8398092467281, 655.583704347733, 543.3344153412039, 581.8138195777395, 587.0050264801206, 592.0290723213933, 629.7071861584385, 7542.811429714191, 7603.505772124794, 7663.770086897402, 7838.804029899298, 8042.477238979448, 571.3600767140829, 598.4763332084426, 603.4508597176635, 603.7011679218728, 604.280905722198, 3074.8308843013847, 3360.983195084058, 3740.0649837540614, 3796.544414670202, 3817.9045523862123, 90620.0, 93119.0, 93279.53763699024, 93304.0, 94856.0, 68841.0, 79417.60291198507, 79872.60063699761, 80611.59694201492, 81863.77204341939, 52464.47535524672, 58433.41566584365, 61049.08426373608, 62630.0605490918, 71315.57368852594, 7830.608469576612, 8125.918740812516, 8265.297449716601, 8285.83428331442, 8320.37597180226, 32392.676073238967, 42580.78709606432, 44077.77961110174, 44293.33559996538, 45378.54621453743, 56282.0, 59953.40046599653, 60131.699341503016, 60167.699161503915, 60339.0, 10793.136549075898, 10884.0, 11050.447477626247, 11148.721281968015, 11411.429428528374, 11946.462409191781, 12005.699857503632, 12175.756484870075, 12433.502659893864, 13017.674558136123, 9946.850797238192, 11410.315381077162, 11652.776458471739, 11659.067274618179, 12280.81578776337, 11414.828777568177, 11530.711732206762, 11541.134414919083, 11569.768604627692, 12455.626331210078, 10876.758064355066, 10891.891081089088, 11150.776984460428, 11215.43922803873, 11281.548738050386, 9283.907160928575, 9296.349255552046, 9473.857892131486, 9572.473513956798, 9585.9041409585, 6827.52207345481, 7676.385889128968, 8509.74470765878, 8569.742907712778, 8742.562871856255], "value": 6812.33992001174, "unit": "score"}], "name": "dromaeo_css", "value": 7015.041499642643, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 112.0, 112.0, 110.0, 113.0, 111.0, 112.0, 111.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 144.0, 128.0, 245.0, 141.0, 170.0, 138.0, 116.0, 167.0, 117.0], "value": 154.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 186.0, 187.0, 186.0, 184.0, 185.0, 185.0, 182.0, 185.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 57.0, 124.0, 59.0, 64.0, 56.0, 59.0, 114.0, 55.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 136.0, 79.0, 81.0, 105.0, 135.0, 111.0, 80.0, 100.0], "value": 101.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 85.0, 87.0, 85.0, 88.0, 84.0, 88.0, 83.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 92.0, 107.0, 150.0, 92.0, 93.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 82.0, 84.0, 86.0, 83.0, 85.0, 84.0, 86.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 59.0, 56.0, 57.0, 60.0, 60.0, 121.0, 56.0, 57.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 71.0, 72.0, 70.0, 66.0, 66.0, 68.0, 69.0, 68.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 81.0, 84.0, 85.0, 82.0, 85.0, 84.0, 86.0, 98.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 139.0, 136.0, 137.0, 137.0, 137.0, 134.0, 209.0, 140.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 166.0, 168.0, 166.0, 165.0, 166.0, 164.0, 163.0, 170.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 67.0, 69.0, 82.0, 80.0, 81.0, 81.0], "value": 73.0, "unit": "score"}], "name": "kraken", "value": 1496.8, "alertThreshold": 2.0}]} 02:57:46 INFO - Return code: 0 02:57:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:57:46 ERROR - # TBPL SUCCESS # 02:57:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:57:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:57:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:57:46 INFO - 2016-05-10 02:57:46.488 screenresolution[1683:9955] starting screenresolution argv=screenresolution get 02:57:46 INFO - 2016-05-10 02:57:46.491 screenresolution[1683:9955] Display 0: 1600x1200x32@60 02:57:46 INFO - 2016-05-10 02:57:46.497 screenresolution[1684:9958] starting screenresolution argv=screenresolution list 02:57:46 INFO - Available Modes on Display 0 02:57:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:57:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:57:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:57:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:57:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:57:46 INFO - Intel Iris: 02:57:46 INFO - Chipset Model: Intel Iris 02:57:46 INFO - Type: GPU 02:57:46 INFO - Bus: Built-In 02:57:46 INFO - VRAM (Dynamic, Max): 1536 MB 02:57:46 INFO - Vendor: Intel (0x8086) 02:57:46 INFO - Device ID: 0x0a2e 02:57:46 INFO - Revision ID: 0x0009 02:57:46 INFO - Displays: 02:57:46 INFO - Display: 02:57:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:57:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:57:46 INFO - Display Serial Number: 1600x1200 60 02:57:46 INFO - Main Display: Yes 02:57:46 INFO - Mirror: Off 02:57:46 INFO - Online: Yes 02:57:46 INFO - Rotation: Supported 02:57:46 INFO - Return code: 0 02:57:46 INFO - Running post-action listener: _resource_record_post_action 02:57:46 INFO - ##### 02:57:46 INFO - ##### Finished run-tests step (success) 02:57:46 INFO - ##### 02:57:46 INFO - Running post-run listener: _resource_record_post_run 02:57:47 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37762560; Write bytes: 696559616; Read time: 394; Write time: 2059 02:57:47 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378184192; Write bytes: 372925952; Read time: 28036; Write time: 804 02:57:47 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36791808; Write bytes: 296661504; Read time: 378; Write time: 1199 02:57:47 INFO - Running post-run listener: _upload_blobber_files 02:57:47 INFO - Blob upload gear active. 02:57:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:57:47 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 02:57:47 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'] 02:57:47 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 02:57:48 INFO - (blobuploader) - INFO - Open directory for files ... 02:57:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:57:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:57:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:49 INFO - (blobuploader) - INFO - Done attempting. 02:57:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:57:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:57:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:50 INFO - (blobuploader) - INFO - Done attempting. 02:57:50 INFO - (blobuploader) - INFO - Iteration through files over. 02:57:50 INFO - Return code: 0 02:57:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:57:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:57:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83b883846d622fe1e1fdde01007a5369262f6a728f48e23aa0f4d785b1c58c6ae11291bc2c6bbf7895a1ae87cef0f6f6aeb391dedff7fbaaf083b25e650e49ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c33498e4713ff56652e71167b69d6e6d34626cb31af693231dc92f6bc1e6c4ad78b91e2a3ee99db7f6b3dfe1ffca6020ff8f4984ee8d9d2afe006e2e56e958"} 02:57:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:57:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:57:50 INFO - Contents: 02:57:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83b883846d622fe1e1fdde01007a5369262f6a728f48e23aa0f4d785b1c58c6ae11291bc2c6bbf7895a1ae87cef0f6f6aeb391dedff7fbaaf083b25e650e49ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c33498e4713ff56652e71167b69d6e6d34626cb31af693231dc92f6bc1e6c4ad78b91e2a3ee99db7f6b3dfe1ffca6020ff8f4984ee8d9d2afe006e2e56e958"} 02:57:50 INFO - Running post-run listener: copy_logs_to_upload_dir 02:57:50 INFO - Copying logs to upload dir... 02:57:50 INFO - mkdir: /builds/slave/test/build/upload/logs 02:57:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.746734 ========= master_lag: 8.59 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-10 02:57:59.041614) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 02:57:59.045670) ========= 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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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/83b883846d622fe1e1fdde01007a5369262f6a728f48e23aa0f4d785b1c58c6ae11291bc2c6bbf7895a1ae87cef0f6f6aeb391dedff7fbaaf083b25e650e49ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c33498e4713ff56652e71167b69d6e6d34626cb31af693231dc92f6bc1e6c4ad78b91e2a3ee99db7f6b3dfe1ffca6020ff8f4984ee8d9d2afe006e2e56e958"} build_url:https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/R8z9GLY6RVOiJXYE9JGvDw/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/83b883846d622fe1e1fdde01007a5369262f6a728f48e23aa0f4d785b1c58c6ae11291bc2c6bbf7895a1ae87cef0f6f6aeb391dedff7fbaaf083b25e650e49ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6c33498e4713ff56652e71167b69d6e6d34626cb31af693231dc92f6bc1e6c4ad78b91e2a3ee99db7f6b3dfe1ffca6020ff8f4984ee8d9d2afe006e2e56e958"}' ========= master_lag: 0.13 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 02:57:59.188786) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:57:59.189107) ========= 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.9V7oyV6WfF/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.DQ8OOu0Qpn/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.005103 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:57:59.209224) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 02:57:59.209501) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 02:57:59.209766) ========= ========= Total master_lag: 8.79 =========