builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0125 starttime: 1461913015.22 results: success (0) buildid: 20160428230248 builduid: b6b77af2544448ed91c404d02a73b1cc revision: 37465cb0c50550fd6f673223942b3c479e39e569 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.218980) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.219453) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.219785) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.241822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.242120) ========= 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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-28 23:56:55-- 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% 36.4M=0s 2016-04-28 23:56:55 (36.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.371279) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.371573) ========= 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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.024723 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:55.419545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 23:56:55.419853) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 37465cb0c50550fd6f673223942b3c479e39e569 --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 37465cb0c50550fd6f673223942b3c479e39e569 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0UgLgC8T1m/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.7EzGFGnYwu/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-28 23:56:55,488 truncating revision to first 12 chars 2016-04-28 23:56:55,488 Setting DEBUG logging. 2016-04-28 23:56:55,488 attempt 1/10 2016-04-28 23:56:55,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/37465cb0c505?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 23:56:56,677 unpacking tar archive at: fx-team-37465cb0c505/testing/mozharness/ program finished with exit code 0 elapsedTime=1.422739 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-28 23:56:56.858881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:56.862232) ========= script_repo_revision: 37465cb0c50550fd6f673223942b3c479e39e569 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:56.862628) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:56.862896) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 23:56:56.876290) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-28 23:56:56.876563) ========= /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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 23:56:57 INFO - MultiFileLogger online at 20160428 23:56:57 in /builds/slave/test 23:56:57 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 23:56:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:56:57 INFO - {'append_to_log': False, 23:56:57 INFO - 'base_work_dir': '/builds/slave/test', 23:56:57 INFO - 'blob_upload_branch': 'Fx-Team', 23:56:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:56:57 INFO - 'branch': 'Fx-Team', 23:56:57 INFO - 'buildbot_json_path': 'buildprops.json', 23:56:57 INFO - 'config_files': ('talos/mac_config.py',), 23:56:57 INFO - 'default_actions': ('clobber', 23:56:57 INFO - 'read-buildbot-config', 23:56:57 INFO - 'download-and-extract', 23:56:57 INFO - 'populate-webroot', 23:56:57 INFO - 'create-virtualenv', 23:56:57 INFO - 'install', 23:56:57 INFO - 'run-tests'), 23:56:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:56:57 INFO - 'download_minidump_stackwalk': True, 23:56:57 INFO - 'download_symbols': 'ondemand', 23:56:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:56:57 INFO - 'tooltool.py': '/tools/tooltool.py', 23:56:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:56:57 INFO - '/tools/misc-python/virtualenv.py')}, 23:56:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:56:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:56:57 INFO - 'installer_path': 'installer.exe', 23:56:57 INFO - 'log_level': 'info', 23:56:57 INFO - 'log_name': 'talos', 23:56:57 INFO - 'log_to_console': True, 23:56:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:56:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:56:57 INFO - 'opt_config_files': (), 23:56:57 INFO - 'pip_index': False, 23:56:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:56:57 INFO - 'cmd': ('bash', 23:56:57 INFO - '-c', 23:56:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:56:57 INFO - 'enabled': True, 23:56:57 INFO - 'halt_on_failure': False, 23:56:57 INFO - 'name': 'check_screen_resolution'},), 23:56:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:56:57 INFO - 'cmd': ('bash', 23:56:57 INFO - '-c', 23:56:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:56:57 INFO - 'enabled': True, 23:56:57 INFO - 'halt_on_failure': False, 23:56:57 INFO - 'name': 'check_screen_resolution'},), 23:56:57 INFO - 'run_cmd_checks_enabled': True, 23:56:57 INFO - 'sps_profile': False, 23:56:57 INFO - 'sps_profile_interval': 0, 23:56:57 INFO - 'suite': 'dromaeojs', 23:56:57 INFO - 'system_bits': '32', 23:56:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:56:57 INFO - 'title': 't-yosemite-r7-0125', 23:56:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:56:57 INFO - 'use_talos_json': True, 23:56:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:56:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:56:57 INFO - 'work_dir': 'build'} 23:56:57 INFO - ##### 23:56:57 INFO - ##### Running clobber step. 23:56:57 INFO - ##### 23:56:57 INFO - Running pre-action listener: _resource_record_pre_action 23:56:57 INFO - Running main action method: clobber 23:56:57 INFO - rmtree: /builds/slave/test/build 23:56:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:56:58 INFO - Running post-action listener: _resource_record_post_action 23:56:58 INFO - ##### 23:56:58 INFO - ##### Running read-buildbot-config step. 23:56:58 INFO - ##### 23:56:58 INFO - Running pre-action listener: _resource_record_pre_action 23:56:58 INFO - Running main action method: read_buildbot_config 23:56:58 INFO - Using buildbot properties: 23:56:58 INFO - { 23:56:58 INFO - "project": "", 23:56:58 INFO - "product": "firefox", 23:56:58 INFO - "script_repo_revision": "production", 23:56:58 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 23:56:58 INFO - "repository": "", 23:56:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 23:56:58 INFO - "buildid": "20160428230248", 23:56:58 INFO - "pgo_build": "False", 23:56:58 INFO - "basedir": "/builds/slave/test", 23:56:58 INFO - "buildnumber": 792, 23:56:58 INFO - "slavename": "t-yosemite-r7-0125", 23:56:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:56:58 INFO - "platform": "yosemite_r7", 23:56:58 INFO - "scheduler": "tests-fx-team-macosx64-talos", 23:56:58 INFO - "branch": "fx-team", 23:56:58 INFO - "repo_path": "integration/fx-team", 23:56:58 INFO - "revision": "37465cb0c50550fd6f673223942b3c479e39e569", 23:56:58 INFO - "stage_platform": "macosx64", 23:56:58 INFO - "builduid": "b6b77af2544448ed91c404d02a73b1cc", 23:56:58 INFO - "slavebuilddir": "test" 23:56:58 INFO - } 23:56:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 23:56:58 INFO - Running post-action listener: _resource_record_post_action 23:56:58 INFO - ##### 23:56:58 INFO - ##### Running download-and-extract step. 23:56:58 INFO - ##### 23:56:58 INFO - Running pre-action listener: _resource_record_pre_action 23:56:58 INFO - Running main action method: download_and_extract 23:56:58 INFO - mkdir: /builds/slave/test/build/tests 23:56:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:56:58 INFO - https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:56:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:56:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 23:56:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:56:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:56:59 INFO - Downloaded 1199 bytes. 23:56:59 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 23:56:59 INFO - Using the following test package requirements: 23:56:59 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:56:59 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 23:56:59 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 23:56:59 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'jsshell-mac.zip'], 23:56:59 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 23:56:59 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 23:56:59 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 23:56:59 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 23:56:59 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 23:56:59 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 23:56:59 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 23:56:59 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:56:59 INFO - https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:56:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:56:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:56:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:56:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:57:00 INFO - Downloaded 18124298 bytes. 23:57:00 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'] 23:57:00 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 23:57:03 INFO - Return code: 0 23:57:03 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 23:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:03 INFO - https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:57:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:57:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 23:57:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:57:04 INFO - Downloaded 18124298 bytes. 23:57:04 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'] 23:57:04 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 23:57:08 INFO - Return code: 0 23:57: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')]} 23:57:08 INFO - https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:57:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:57:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 23:57:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:57:09 INFO - Downloaded 10840145 bytes. 23:57:09 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'] 23:57:09 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 23:57:10 INFO - Return code: 0 23:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:10 INFO - https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 23:57:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:57:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:57:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 23:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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 23:57:13 INFO - Downloaded 110272016 bytes. 23:57:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:57:13 INFO - mkdir: /builds/slave/test/properties 23:57:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:57:13 INFO - Writing to file /builds/slave/test/properties/build_url 23:57:13 INFO - Contents: 23:57:13 INFO - build_url:https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 23:57:14 INFO - Running post-action listener: _resource_record_post_action 23:57:14 INFO - Running post-action listener: set_extra_try_arguments 23:57:14 INFO - ##### 23:57:14 INFO - ##### Running populate-webroot step. 23:57:14 INFO - ##### 23:57:14 INFO - Running pre-action listener: _resource_record_pre_action 23:57:14 INFO - Running main action method: populate_webroot 23:57:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:57:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:57:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:57:14 INFO - '--tppagecycles', 23:57:14 INFO - '7'], 23:57:14 INFO - 'tests': ['tsvgm']}}, 23:57:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:57:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:57:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:57:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:57:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:57:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:57:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['damp', 'tps']}, 23:57:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'tests': ['damp', 'tps']}, 23:57:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['dromaeo_dom']}, 23:57:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:57:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['a11yr', 23:57:14 INFO - 'ts_paint', 23:57:14 INFO - 'tpaint', 23:57:14 INFO - 'sessionrestore', 23:57:14 INFO - 'sessionrestore_no_auto_restore', 23:57:14 INFO - 'tabpaint']}, 23:57:14 INFO - 'other-e10s': {'tests': ['a11yr', 23:57:14 INFO - 'ts_paint', 23:57:14 INFO - 'tpaint', 23:57:14 INFO - 'sessionrestore', 23:57:14 INFO - 'sessionrestore_no_auto_restore', 23:57:14 INFO - 'tabpaint']}, 23:57:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:57:14 INFO - 'ts_paint', 23:57:14 INFO - 'tpaint', 23:57:14 INFO - 'sessionrestore', 23:57:14 INFO - 'sessionrestore_no_auto_restore', 23:57:14 INFO - 'tabpaint']}, 23:57:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:57:14 INFO - 'ts_paint', 23:57:14 INFO - 'tpaint', 23:57:14 INFO - 'sessionrestore', 23:57:14 INFO - 'sessionrestore_no_auto_restore', 23:57:14 INFO - 'tabpaint']}, 23:57:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['a11yr', 23:57:14 INFO - 'ts_paint', 23:57:14 INFO - 'tpaint', 23:57:14 INFO - 'sessionrestore', 23:57:14 INFO - 'sessionrestore_no_auto_restore', 23:57:14 INFO - 'tabpaint']}, 23:57:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['a11yr', 23:57:14 INFO - 'ts_paint', 23:57:14 INFO - 'tpaint', 23:57:14 INFO - 'sessionrestore', 23:57:14 INFO - 'sessionrestore_no_auto_restore', 23:57:14 INFO - 'tabpaint']}, 23:57:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['tsvgx', 23:57:14 INFO - 'tsvgr_opacity', 23:57:14 INFO - 'tart', 23:57:14 INFO - 'tscrollx', 23:57:14 INFO - 'cart']}, 23:57:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:57:14 INFO - 'tsvgr_opacity', 23:57:14 INFO - 'tart', 23:57:14 INFO - 'tscrollx', 23:57:14 INFO - 'cart']}, 23:57:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'talos_options': ['--disable-e10s'], 23:57:14 INFO - 'tests': ['tp5o']}, 23:57:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'tests': ['tp5o']}, 23:57:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'talos_options': ['--disable-e10s', 23:57:14 INFO - '--xperf_path', 23:57:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:57:14 INFO - 'tests': ['tp5n']}, 23:57:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:57:14 INFO - 'talos_options': ['--xperf_path', 23:57:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:57:14 INFO - 'tests': ['tp5n']}}, 23:57:14 INFO - 'talos.zip': {'path': '', 23:57:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:57:14 INFO - Running post-action listener: _resource_record_post_action 23:57:14 INFO - ##### 23:57:14 INFO - ##### Running create-virtualenv step. 23:57:14 INFO - ##### 23:57:14 INFO - Running pre-action listener: _resource_record_pre_action 23:57:14 INFO - Running main action method: create_virtualenv 23:57:14 INFO - Creating virtualenv /builds/slave/test/build/venv 23:57:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:57:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:57:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:57:15 INFO - Using real prefix '/tools/python27' 23:57:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:57:15 INFO - Installing distribute.............................................................................................................................................................................................done. 23:57:18 INFO - Installing pip.................done. 23:57:18 INFO - Return code: 0 23:57:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:18 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:18 INFO - 'HOME': '/Users/cltbld', 23:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:18 INFO - 'LOGNAME': 'cltbld', 23:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:18 INFO - 'MOZ_NO_REMOTE': '1', 23:57:18 INFO - 'NO_EM_RESTART': '1', 23:57:18 INFO - 'PAGER': '/bin/cat', 23:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:18 INFO - 'PWD': '/builds/slave/test', 23:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:18 INFO - 'SHELL': '/bin/bash', 23:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:18 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:18 INFO - 'USER': 'cltbld', 23:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:18 INFO - 'XPC_FLAGS': '0x0', 23:57:18 INFO - 'XPC_SERVICE_NAME': '0', 23:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:57:18 INFO - Cleaning up... 23:57:18 INFO - Return code: 0 23:57:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:18 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 23:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 23:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:18 INFO - 'HOME': '/Users/cltbld', 23:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:18 INFO - 'LOGNAME': 'cltbld', 23:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:18 INFO - 'MOZ_NO_REMOTE': '1', 23:57:18 INFO - 'NO_EM_RESTART': '1', 23:57:18 INFO - 'PAGER': '/bin/cat', 23:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:18 INFO - 'PWD': '/builds/slave/test', 23:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:18 INFO - 'SHELL': '/bin/bash', 23:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:18 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:18 INFO - 'USER': 'cltbld', 23:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:18 INFO - 'XPC_FLAGS': '0x0', 23:57:18 INFO - 'XPC_SERVICE_NAME': '0', 23:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:19 INFO - Downloading/unpacking psutil>=0.7.1 23:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:57:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:57:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:57:22 INFO - Installing collected packages: psutil 23:57:22 INFO - Running setup.py install for psutil 23:57:22 INFO - building 'psutil._psutil_osx' extension 23:57:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:57:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 23:57:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:57:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 23:57:23 INFO - building 'psutil._psutil_posix' extension 23:57:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 23:57:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:57:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:57:23 INFO - ^ 23:57:23 INFO - 1 warning generated. 23:57:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 23:57:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:57:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:57:23 INFO - Successfully installed psutil 23:57:23 INFO - Cleaning up... 23:57:23 INFO - Return code: 0 23:57:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:23 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:23 INFO - 'HOME': '/Users/cltbld', 23:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:23 INFO - 'LOGNAME': 'cltbld', 23:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:23 INFO - 'MOZ_NO_REMOTE': '1', 23:57:23 INFO - 'NO_EM_RESTART': '1', 23:57:23 INFO - 'PAGER': '/bin/cat', 23:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:23 INFO - 'PWD': '/builds/slave/test', 23:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:23 INFO - 'SHELL': '/bin/bash', 23:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:23 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:23 INFO - 'USER': 'cltbld', 23:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:23 INFO - 'XPC_FLAGS': '0x0', 23:57:23 INFO - 'XPC_SERVICE_NAME': '0', 23:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:57: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 23:57: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 23:57: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 23:57: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 23:57: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 23:57: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 23:57:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:57:26 INFO - Installing collected packages: mozsystemmonitor 23:57:26 INFO - Running setup.py install for mozsystemmonitor 23:57:26 INFO - Successfully installed mozsystemmonitor 23:57:26 INFO - Cleaning up... 23:57:26 INFO - Return code: 0 23:57:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:57: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')]} 23:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57: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')]} 23:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57: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', '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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:57: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 blobuploader==1.2.4 23:57:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:26 INFO - 'HOME': '/Users/cltbld', 23:57:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:26 INFO - 'LOGNAME': 'cltbld', 23:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:26 INFO - 'MOZ_NO_REMOTE': '1', 23:57:26 INFO - 'NO_EM_RESTART': '1', 23:57:26 INFO - 'PAGER': '/bin/cat', 23:57:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:26 INFO - 'PWD': '/builds/slave/test', 23:57:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:26 INFO - 'SHELL': '/bin/bash', 23:57:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:26 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:26 INFO - 'USER': 'cltbld', 23:57:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:26 INFO - 'XPC_FLAGS': '0x0', 23:57:26 INFO - 'XPC_SERVICE_NAME': '0', 23:57:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:26 INFO - Downloading/unpacking blobuploader==1.2.4 23:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:29 INFO - Downloading blobuploader-1.2.4.tar.gz 23:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:57:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:57: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 23:57: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 23:57: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 23:57: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 23:57: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 23:57: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 23:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:57:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:57: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 23:57: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 23:57: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 23:57: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 23:57: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 23:57: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 23:57:30 INFO - Downloading docopt-0.6.1.tar.gz 23:57:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:57:30 INFO - Installing collected packages: blobuploader, requests, docopt 23:57:30 INFO - Running setup.py install for blobuploader 23:57:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:57:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:57:30 INFO - Running setup.py install for requests 23:57:31 INFO - Running setup.py install for docopt 23:57:31 INFO - Successfully installed blobuploader requests docopt 23:57:31 INFO - Cleaning up... 23:57:31 INFO - Return code: 0 23:57:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:31 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:57:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:31 INFO - 'HOME': '/Users/cltbld', 23:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:31 INFO - 'LOGNAME': 'cltbld', 23:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:31 INFO - 'MOZ_NO_REMOTE': '1', 23:57:31 INFO - 'NO_EM_RESTART': '1', 23:57:31 INFO - 'PAGER': '/bin/cat', 23:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:31 INFO - 'PWD': '/builds/slave/test', 23:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:31 INFO - 'SHELL': '/bin/bash', 23:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:31 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:31 INFO - 'USER': 'cltbld', 23:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:31 INFO - 'XPC_FLAGS': '0x0', 23:57:31 INFO - 'XPC_SERVICE_NAME': '0', 23:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:57:31 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-xJqSKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:57:31 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-3mt7FE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-SEod7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-F_smEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-n4n5V1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-2LjSJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-jdASXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-G2hGzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-XveVFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-mgRInV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-rp1u9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:57:32 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ExTQxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:57:33 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-31ZlI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:57:33 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-CRaw3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:57:33 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ofKhCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:57:33 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Hd0WiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:57:33 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-YijGZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:57:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:57:33 INFO - Running setup.py install for manifestparser 23:57:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:57:33 INFO - Running setup.py install for mozcrash 23:57:33 INFO - Running setup.py install for mozdebug 23:57:34 INFO - Running setup.py install for mozdevice 23:57:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Running setup.py install for mozfile 23:57:34 INFO - Running setup.py install for mozhttpd 23:57:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Running setup.py install for mozinfo 23:57:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Running setup.py install for mozInstall 23:57:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:57:34 INFO - Running setup.py install for mozleak 23:57:34 INFO - Running setup.py install for mozlog 23:57:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:57:35 INFO - Running setup.py install for moznetwork 23:57:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:57:35 INFO - Running setup.py install for mozprocess 23:57:35 INFO - Running setup.py install for mozprofile 23:57:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:57:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:57:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:57:35 INFO - Running setup.py install for mozrunner 23:57:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:57:35 INFO - Running setup.py install for mozscreenshot 23:57:35 INFO - Running setup.py install for moztest 23:57:36 INFO - Running setup.py install for mozversion 23:57:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:57:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:57:36 INFO - Cleaning up... 23:57:36 INFO - Return code: 0 23:57:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:36 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:57:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:57:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:36 INFO - 'HOME': '/Users/cltbld', 23:57:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:36 INFO - 'LOGNAME': 'cltbld', 23:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:36 INFO - 'MOZ_NO_REMOTE': '1', 23:57:36 INFO - 'NO_EM_RESTART': '1', 23:57:36 INFO - 'PAGER': '/bin/cat', 23:57:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:36 INFO - 'PWD': '/builds/slave/test', 23:57:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:36 INFO - 'SHELL': '/bin/bash', 23:57:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:36 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:36 INFO - 'USER': 'cltbld', 23:57:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:36 INFO - 'XPC_FLAGS': '0x0', 23:57:36 INFO - 'XPC_SERVICE_NAME': '0', 23:57:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:57:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-rkoIpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:57:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-88UeVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:57:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-02wPUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:57:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-vNiNP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:57:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-NjHGKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:57:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-VZdJyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-GExWdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-FoJELM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-C5dvKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-mi9AbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-hlYkk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Mwh1tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-JdpzLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Yhd0lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:57:37 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-sOgZqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:57:38 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-axSTq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:57:38 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-iw2tip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:57:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:40 INFO - Downloading blessings-1.6.tar.gz 23:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:57:40 INFO - Installing collected packages: blessings 23:57:40 INFO - Running setup.py install for blessings 23:57:41 INFO - Successfully installed blessings 23:57:41 INFO - Cleaning up... 23:57:41 INFO - Return code: 0 23:57:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:57:41 INFO - Reading from file tmpfile_stdout 23:57:41 INFO - Current package versions: 23:57:41 INFO - blessings == 1.6 23:57:41 INFO - blobuploader == 1.2.4 23:57:41 INFO - docopt == 0.6.1 23:57:41 INFO - manifestparser == 1.1 23:57:41 INFO - mozInstall == 1.12 23:57:41 INFO - mozcrash == 0.17 23:57:41 INFO - mozdebug == 0.1 23:57:41 INFO - mozdevice == 0.48 23:57:41 INFO - mozfile == 1.2 23:57:41 INFO - mozhttpd == 0.7 23:57:41 INFO - mozinfo == 0.9 23:57:41 INFO - mozleak == 0.1 23:57:41 INFO - mozlog == 3.1 23:57:41 INFO - moznetwork == 0.27 23:57:41 INFO - mozprocess == 0.22 23:57:41 INFO - mozprofile == 0.28 23:57:41 INFO - mozrunner == 6.11 23:57:41 INFO - mozscreenshot == 0.1 23:57:41 INFO - mozsystemmonitor == 0.0 23:57:41 INFO - moztest == 0.7 23:57:41 INFO - mozversion == 1.4 23:57:41 INFO - psutil == 3.1.1 23:57:41 INFO - requests == 1.2.3 23:57:41 INFO - wsgiref == 0.1.2 23:57:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:41 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:41 INFO - 'HOME': '/Users/cltbld', 23:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:41 INFO - 'LOGNAME': 'cltbld', 23:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:41 INFO - 'MOZ_NO_REMOTE': '1', 23:57:41 INFO - 'NO_EM_RESTART': '1', 23:57:41 INFO - 'PAGER': '/bin/cat', 23:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:41 INFO - 'PWD': '/builds/slave/test', 23:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:41 INFO - 'SHELL': '/bin/bash', 23:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:41 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:41 INFO - 'USER': 'cltbld', 23:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:41 INFO - 'XPC_FLAGS': '0x0', 23:57:41 INFO - 'XPC_SERVICE_NAME': '0', 23:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:57:41 INFO - Cleaning up... 23:57:41 INFO - Return code: 0 23:57:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:41 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:41 INFO - 'HOME': '/Users/cltbld', 23:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:41 INFO - 'LOGNAME': 'cltbld', 23:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:41 INFO - 'MOZ_NO_REMOTE': '1', 23:57:41 INFO - 'NO_EM_RESTART': '1', 23:57:41 INFO - 'PAGER': '/bin/cat', 23:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:41 INFO - 'PWD': '/builds/slave/test', 23:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:41 INFO - 'SHELL': '/bin/bash', 23:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:41 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:41 INFO - 'USER': 'cltbld', 23:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:41 INFO - 'XPC_FLAGS': '0x0', 23:57:41 INFO - 'XPC_SERVICE_NAME': '0', 23:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:41 INFO - Downloading/unpacking jsonschema 23:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:57:44 INFO - Downloading/unpacking functools32 (from jsonschema) 23:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:44 INFO - Downloading functools32-3.2.3-2.tar.gz 23:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:57:44 INFO - warning: no files found matching '*.txt' 23:57:44 INFO - no previously-included directories found matching 'build' 23:57:44 INFO - no previously-included directories found matching 'dist' 23:57:44 INFO - no previously-included directories found matching '.git*' 23:57:44 INFO - Installing collected packages: jsonschema, functools32 23:57:44 INFO - Running setup.py install for functools32 23:57:45 INFO - warning: no files found matching '*.txt' 23:57:45 INFO - no previously-included directories found matching 'build' 23:57:45 INFO - no previously-included directories found matching 'dist' 23:57:45 INFO - no previously-included directories found matching '.git*' 23:57:45 INFO - Successfully installed jsonschema functools32 23:57:45 INFO - Cleaning up... 23:57:45 INFO - Return code: 0 23:57:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:57:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:57:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:57:45 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 0x101b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b4a57b9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, '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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:57:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:57:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:57:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:57:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:57:45 INFO - 'HOME': '/Users/cltbld', 23:57:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:57:45 INFO - 'LOGNAME': 'cltbld', 23:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:57:45 INFO - 'MOZ_NO_REMOTE': '1', 23:57:45 INFO - 'NO_EM_RESTART': '1', 23:57:45 INFO - 'PAGER': '/bin/cat', 23:57:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:57:45 INFO - 'PWD': '/builds/slave/test', 23:57:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:57:45 INFO - 'SHELL': '/bin/bash', 23:57:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:57:45 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:57:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:57:45 INFO - 'USER': 'cltbld', 23:57:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:57:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:57:45 INFO - 'XPC_FLAGS': '0x0', 23:57:45 INFO - 'XPC_SERVICE_NAME': '0', 23:57:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:57:45 INFO - Downloading/unpacking flake8 23:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:57:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:57:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:57:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:57:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:57:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:57:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:57:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:57:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:57:49 INFO - no previously-included directories found matching 'docs/_build' 23:57:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:57:49 INFO - Running setup.py install for pep8 23:57:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:57:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:57:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:57:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:57:49 INFO - no previously-included directories found matching 'docs/_build' 23:57:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:57:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:57:49 INFO - Cleaning up... 23:57:49 INFO - Return code: 0 23:57:49 INFO - Running post-action listener: _resource_record_post_action 23:57:49 INFO - Running post-action listener: _start_resource_monitoring 23:57:49 INFO - Starting resource monitoring. 23:57:49 INFO - ##### 23:57:49 INFO - ##### Running install step. 23:57:49 INFO - ##### 23:57:49 INFO - Running pre-action listener: _resource_record_pre_action 23:57:49 INFO - Running main action method: install 23:57:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:57:49 INFO - Reading from file tmpfile_stdout 23:57:49 INFO - Detecting whether we're running mozinstall >=1.0... 23:57:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:57:49 INFO - Reading from file tmpfile_stdout 23:57:49 INFO - Output received: 23:57:49 INFO - Usage: mozinstall [options] installer 23:57:49 INFO - Options: 23:57:49 INFO - -h, --help show this help message and exit 23:57:49 INFO - -d DEST, --destination=DEST 23:57:49 INFO - Directory to install application into. [default: 23:57:49 INFO - "/builds/slave/test"] 23:57:49 INFO - --app=APP Application being installed. [default: firefox] 23:57:49 INFO - mkdir: /builds/slave/test/build/application 23:57:49 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'] 23:57:49 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 23:58:22 INFO - Reading from file tmpfile_stdout 23:58:22 INFO - Output received: 23:58:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:58:22 INFO - Running post-action listener: _resource_record_post_action 23:58:22 INFO - ##### 23:58:22 INFO - ##### Running run-tests step. 23:58:22 INFO - ##### 23:58:22 INFO - Running pre-action listener: _resource_record_pre_action 23:58:22 INFO - Running main action method: run_tests 23:58:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:58:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:58:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:58:22 INFO - 2016-04-28 23:58:22.935 screenresolution[1642:7547] starting screenresolution argv=screenresolution get 23:58:22 INFO - 2016-04-28 23:58:22.937 screenresolution[1642:7547] Display 0: 1600x1200x32@60 23:58:22 INFO - 2016-04-28 23:58:22.943 screenresolution[1643:7549] starting screenresolution argv=screenresolution list 23:58:22 INFO - Available Modes on Display 0 23:58:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:58:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:58:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:58:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:58:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:58:23 INFO - Intel Iris: 23:58:23 INFO - Chipset Model: Intel Iris 23:58:23 INFO - Type: GPU 23:58:23 INFO - Bus: Built-In 23:58:23 INFO - VRAM (Dynamic, Max): 1536 MB 23:58:23 INFO - Vendor: Intel (0x8086) 23:58:23 INFO - Device ID: 0x0a2e 23:58:23 INFO - Revision ID: 0x0009 23:58:23 INFO - Displays: 23:58:23 INFO - Display: 23:58:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:58:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:58:23 INFO - Display Serial Number: 1600x1200 60 23:58:23 INFO - Main Display: Yes 23:58:23 INFO - Mirror: Off 23:58:23 INFO - Online: Yes 23:58:23 INFO - Rotation: Supported 23:58:23 INFO - Return code: 0 23:58:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:58:23 INFO - Python 2.7.3 23:58:23 INFO - Return code: 0 23:58:23 INFO - grabbing minidump binary from tooltool 23:58:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:58:23 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 0x1019c0e00>, '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 0x1017e9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:58:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:58:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:58:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:58:23 INFO - Return code: 0 23:58:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:58:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:58:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:58:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:58:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:58:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:58:24 INFO - Return code: 0 23:58:24 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/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:58:24 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/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0125 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:58:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0UgLgC8T1m/Render', 23:58:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:58:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:58:24 INFO - 'HOME': '/Users/cltbld', 23:58:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:58:24 INFO - 'LOGNAME': 'cltbld', 23:58:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:58:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:58:24 INFO - 'MOZ_NO_REMOTE': '1', 23:58:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:58:24 INFO - 'NO_EM_RESTART': '1', 23:58:24 INFO - 'PAGER': '/bin/cat', 23:58:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:58:24 INFO - 'PWD': '/builds/slave/test', 23:58:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:58:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:58:24 INFO - 'SHELL': '/bin/bash', 23:58:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7EzGFGnYwu/Listeners', 23:58:24 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:58:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:58:24 INFO - 'USER': 'cltbld', 23:58:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:58:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:58:24 INFO - 'XPC_FLAGS': '0x0', 23:58:24 INFO - 'XPC_SERVICE_NAME': '0', 23:58:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:58:24 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/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:58:24 INFO - mozversion application_buildid: 20160428230248 23:58:24 INFO - mozversion application_changeset: 37465cb0c50550fd6f673223942b3c479e39e569 23:58:24 INFO - mozversion application_display_name: Nightly 23:58:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:58:24 INFO - mozversion application_name: Firefox 23:58:24 INFO - mozversion application_remotingname: firefox 23:58:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:58:24 INFO - mozversion application_vendor: Mozilla 23:58:24 INFO - mozversion application_version: 49.0a1 23:58:24 INFO - mozversion platform_buildid: 20160428230248 23:58:24 INFO - mozversion platform_changeset: 37465cb0c50550fd6f673223942b3c479e39e569 23:58:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:58:24 INFO - mozversion platform_version: 49.0a1 23:58:24 INFO - using testdate: 1461913104 23:58:24 INFO - actual date: 1461913104 23:58:24 INFO - starting webserver on 'localhost:49270' 23:58:24 INFO - SUITE-START | Running 2 tests 23:58:24 INFO - TEST-START | dromaeo_css 23:58:24 INFO - operating with platform_type : mac_ 23:58:24 INFO - Initialising browser for dromaeo_css test... 23:58:24 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpm4P58A/profile http://localhost:49270/getInfo.html) 23:58:26 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 23:58:26 INFO - PROCESS | 1661 | colorDepth:24 23:58:26 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 23:58:26 INFO - PROCESS | 1661 | __metrics 23:58:26 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:58:26 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:58:27 INFO - TEST-INFO | 1661: exit 0 23:58:27 INFO - Browser initialized. 23:58:27 INFO - Running cycle 1/1 for dromaeo_css test... 23:58:27 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpm4P58A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:58:39 INFO - PROCESS | 1663 | RSS: Main: 239472640 23:58:39 INFO - PROCESS | 1663 | 23:59:30 INFO - PROCESS | 1663 | 1461913170893 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] 00:01:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 00:01:42 INFO - PROCESS | 1663 | RSS: Main: 339169280 00:01:42 INFO - PROCESS | 1663 | 00:04:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 00:04:45 INFO - PROCESS | 1663 | RSS: Main: 365645824 00:04:45 INFO - PROCESS | 1663 | 00:07:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 00:07:48 INFO - PROCESS | 1663 | RSS: Main: 371113984 00:07:48 INFO - PROCESS | 1663 | 00:10:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 00:10:51 INFO - PROCESS | 1663 | RSS: Main: 367292416 00:10:51 INFO - PROCESS | 1663 | 00:13:54 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 00:13:55 INFO - PROCESS | 1663 | RSS: Main: 336756736 00:13:55 INFO - PROCESS | 1663 | 00:16:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 00:16:58 INFO - PROCESS | 1663 | RSS: Main: 396025856 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | __start_tp_report 00:16:58 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 00:16:58 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 00:16:58 INFO - PROCESS | 1663 | |i|pagename|runs| 00:16:58 INFO - PROCESS | 1663 | |0;dojo.html;3299.5380646709464;3371.342588195303;3391.6269210386868;3397.898063058108;3443.58676958765;5297.364316282042;5383.380911195214;5558.499735023847;5560.749766260518;5587.357453892799;5558.805441809537;5585.748641311141;5642.379338272797;5705.400932902048;5711.086226203808;5009.223570346584;5045;5058.4435712071645;5082.009008243394;5231.5814734821315;5164.45773193813;5165.457626949173;5198.428172900997;5212.973935130319;5228.843134705965;4993.001399720052;5011.173156429185;5080.110980578384;5091.745412729349;5121.436641969381;4338.828516300613;4955.603551715854;5086.567641750456;5086.948080072889;5137.33214682089;5014.774335154927;5061.240813877911;5076.974615126901;5082.79668813247;5151.639385243035;5402.91895621566;5424.240606315119;5520.889582208373;5581.0512212923495;5597.552195824324;6055.878882422371;6077.483413909823;6106.816795496142;6113.205283313186;6113.908291375633;4918.532739389752;6110.08348747687;6111.113888486189;6116.694165291718;6121.602095863799;5517.944820551783;5601.747921343509;5603.80386686464;5640.774369025234;5701.344345400249;5398.541124004425;6086.239220097488;6237.906431403532;6249.218847644044;6256.374362563707;5949.613275137189;5954;5966.582339236212;5967.433093856077;5981.312149102821;5933.6736479493175;6036.3360030396625;6069.332373438918;6121.204243448323;6146.508279337642;6018.277806663228;6069.241344831896;6076.27084749824;6077.8480537986015;6095.634261944208;6085.513158947274;6097.908531372033;6104.969475152596;6111.938880611226;6113.083037544404;4020.6783457323345;4743.359646447754;4776.614781713264;4802.615790736802;4806.831760888422;4316.870493885189;4333.111712099012;4352.238358287287;4355.825766969349;4383.408239887638;5531.834044978657;5539.639923404966;5541.833744987657;5569.972150139223;5606.355269144019;6099.817005489843;6144.569880108439;6155.938440615537;6158.599691020069;6185.907211391833;5469.726513674301;5512.200730893998;5556.333240011144;5574.219609254707;5575.080111781592;5237.921431178535;5248.76380562868;5269.604779641466;5287.312649355559;5307;6243.906341404882;6253.312135665074;6278.277998030285;6288.308286088527;6295.874082518416;5234.424213336454;5895.408239775151;6008.489278411384;6146.2033334162725;6185.195924529782;6152.923238433167;6220.875582488416;6239.844003899939;6265.56141070139;6283.49732021428;6069.150318955261;6070.089486577049;6076.179715738495;6079.787207447718;6089.119295196399;5974.014287642491;6099.573029887865;6169.383061693795;6171.228596425447;6209.254889413119;4905.754712264444;5900.793472228452;5936.28764548257;5936.851578710567;5992.730327135202;5743.798967036134;5891.440313170242;5923.200367950271;5927.377625349449;5930.555208359304;5627.634203776742;5740.052891272893;5744.712764361849;5789.594728368974;5861.443162899518;5938.049912014057;5938.61399009063;5941.643501389931;5945.494632956247;5989.970050149721;5822.98097832886;5833.67914764692;5839.883202336015;5855.238818953509;5865 00:16:58 INFO - PROCESS | 1663 | |1;ext.html;519.2859817750593;536.8147988943814;551.0191858491886;572.3703925681748;583.6439771739239;29759.404811903776;30182.547261791064;37789;37962.81018594906;38407.615923840756;35071.82464087676;40548.39177412341;41235.58764412355;41441.96395090124;41445.58554414455;9313.953430232856;9562.426254424721;9568.473733944631;9576.186024187953;9587.13715765581;8361.456505327134;9067.909320906772;11332.206745527816;11335.979761821434;11336.716582085473;9803.67650367202;11227.943860280688;11412.885871141265;11422.143339249547;11428.314301141958;10317.84523232152;10464.947675261652;10477.528511216937;10479.895201047968;10489.055984961315;11261.774764504746;11363.715907102305;11372.943135284353;11384.260023098555;11488.540458381654;39045;39434.408483872765;39465.80267098675;39494.40758388598;39580.80209598933;2944.5877577139217;3223.3231021485517;3230.361606897824;3256.7557433192605;3397.915052123692;22461.43846403853;28883.422331553462;28896.855515722287;28923.56614650782;28952.710472895422;2689.7982651301036;2719.864006799672;2727.5635898256382;2766.792490563216;2908.43285559316;492533;494225;494978;498420;499300;59080.704596476746;59426;59471;59561.106583401284;59663.80672386528;62994;63200;63231.36768632255;63298.6835065831;63352.366476334646;16264.430744924108;16484.423045193602;16511;16537.338506459848;17037.829621703873;11684.649460516199;11939.462724177432;13250;13299.867001329863;13318.600441986757;15107.773383399257;15227.238638068053;15251.542453726406;15258.923705381401;15407.922960385127;6458.967705161444;6541.182352205974;6623.238327592292;6634.137562116894;6641.667916604247;909.9636014559476;1030.8351562734063;1035.7565971996576;1049.1277082652207;1110.3005106782675;24506;26138.73861261401;29143.834246630322;29198.567640052446;29757.40485190284;968.9806203876024;984.7636567223815;989.5151375825938;1011.6256984915628;1118.9160812938899;14781.556553303419;15021;15046.398144074334;15251.389944402099;15295.541133766004;545030.2748486232;548282;550601.9879602465;551266;637382;54999;57188.856222876144;57590.7120464395;57756.4224357751;58010.839783204945;1330.301591664384;1364.883984861258;1474.5428917035538;1492.514932646894;1493.8804895608325;24387.634185486877;27440.725592744617;27514.106863847966;27906.16281511558;28014.719852801212;51091.48908511016;51332;51596.48403516067;51658.225126622376;51919.22121168261;420601;425865;428364.8581757071;428609.713902857;430273;21542.6768598468;21812.127514899224;21885.343439696833;24768.380790479663;25483.61774573346;28076;33109;33234.83382583072;36264.37902125175;36666.45000324944;32151.67848321487;33709;34266.48600271048;35033;35243.118922027155;34726.13184670403;34836.303273934886;35369.46945795764;35610.82194589011;35666.64333356737;34260.48609270913;34600;34690.82654586711;35110.82444587761;35535.644643553234;25111;31022.844885775427;31033.844830776605;31155.68844311528;31227.907023254076;23346.062338804208;23498.412539686644;23603.763962360157;23628;23785.405364866016 00:16:58 INFO - PROCESS | 1663 | |2;jquery.html;390.13390273592614;407.63313018283543;410.6468437144056;413.1736526946108;418.155326240993;18110.5472363191;19636;20401;20403.897980510093;20744.170233190653;20089.59820803583;20545.075471603777;20560.168360435324;20903.895480522613;20953.476163095893;5776.537876969833;6037.667928263943;6076.240469941257;6088.45203931646;6094.238220222473;5800;5862.384449632791;5962.582619216657;6017.097435384705;6020.488258498033;5868.17845501628;5929.673867937262;5929.851753706171;5930.288365396159;5941.970290148565;5573.191887176357;5909.586328956974;5921.230240068785;5925.140854576105;5937.70311484424;4617.538246175389;5746.36789953109;5779.479846813765;5783.855403614902;5950.404959504058;13616.387262573207;13926.164430134224;14010.159390436607;14051.227182504957;20200.49498762543;2074.408793493862;2205.073868975034;2281.828862835294;2288.6567014947723;2533.6579561759295;9862;10282.074613284765;10377.481125943672;10494.422806745626;10521.736956576071;1753.5265478320782;1801.7387478815629;1838.2555065198617;1842.2723024405284;1858.4145994011842;93894.06105938922;120340.39829800882;121101;121165.39417302857;122565;9868.30921835479;10300.484975751333;10846.240763146661;11485.138614603939;11669.533218671328;13454.93272533631;15190.924045379703;15260.618484537754;15307.464238751812;15335.386584536716;8734.956325218332;8783.472991620523;8821.161989610977;8825.955870220609;8918.331125165509;7318.682637125368;8429.594461715735;8702.825943481095;8806.339524535557;8830.735077947671;4625.560571745679;4648.372469716613;4685.390899183084;4691.272852707836;4716.764161791897;1909.5035290824485;1982.692682634194;1984.7320611717523;1989.9403017909485;1993.5913137806715;936.9109934556207;955.498363359228;974.4737841565475;1019.5157300282455;1064.3028816125295;19506;20111.29610463627;20392.898035510025;20450.590988180153;20797.688034679493;994.4878387630376;994.8955359687128;1004.1163775877181;1004.1414590525142;1018.6553749051329;8188.716924613081;8710.91289087101;9446.574904129331;9671.371360861522;11382.317060976367;582317;593407;599860.0007000024;609844;615314;126740;131241.0313845293;132014.33992830166;151567;151895;995.7687304696307;1033.5917312661456;1045.4981608827804;1048.3605000949415;1051.805415998043;15261.694766104394;17612.29550817953;17905.28378864883;18240.726389103907;18659.533511662317;53758.924821502565;54408;54633.63415914634;56505.43494565002;56930;75429;76456.61771691106;76966.84265258654;77374.61312693401;85364.5731771362;10930.836037459603;11355.820370296331;11923.76152476963;11961.222520536137;12090.153689241668;7194.676239569128;12615.87384126147;12725;13232.603021909357;13247.20516768997;11010.174236932422;11375.54497820078;11507.136964727846;11695.35816161628;11809.23239989398;11097.500612472297;11335.829962550733;11448.885511144781;11721.886420790011;11848.407579620813;11376.374299413612;11726.88273117258;11811.291322520676;11844.822327665264;12046.156769026084;10823.80938096793;11627.825582616098;11995.64013079609;12248;12327.568535101229;11464;11529.82705259405;11581.88418115808;11716.238444501421;11847.466863990969 00:16:58 INFO - PROCESS | 1663 | |3;mootools.html;1407.303384824512;1619.7651340555617;1633.4446288261993;1914.8946807925558;2032.5629989552253;7221.711131554738;7625.199354067826;8915.465072095678;8919;8923.464592124477;8435.873461898076;8705.477671339724;8711.564421778901;8719.520426376546;8720.997085335188;2448.52253810507;2499.1128149506935;2500.2999160235163;2502.436951685865;2507.260358194337;2349.9647505287435;2420.2376251480728;2423.6243382275775;2426.7573242675767;2427.356750461129;2056.516718571135;2442.8778561071963;2445.7065152181763;2479.752024797524;2479.913203037894;2164.2425151196953;2417.10567090176;2436.7685069918416;2445.0464318915547;2445.119756887519;2304.308707387777;2405.314485371678;2434.9756502434925;2435.3789783605243;2436.537057959;8488.915110848875;8663.39356245063;8766.430182038212;8766.912330876738;8775.122487751238;5181.481851814826;5422.376426710939;5458.344998600153;5465.4261302563455;5471.2887324647545;3456.9827150864335;3535.1515636247373;3602.0634634994826;3611.494390785292;3642.8724994625327;1030.8505266736356;1052.952617132231;1058.3226734889677;1058.6771034834373;1061.103367654331;10742.462876856125;11157.888421115766;11164.609238676607;11179.720506987309;11189.160812939062;4733.5503127202865;4799.199472920259;4887.633427492952;4888.828890988798;4903.975480122576;9759.560819763128;9780;9796.314258002014;9797.951010245046;9799;5334.759935802898;5349.812756553502;5350.0904846176245;5359.705216213146;5364.088105022156;4042.555318914917;4330.740155590612;4477.664175186809;4495.483019452739;4710.882227944328;3910.2179564086728;4028.7381320214677;4074.266632006267;4099.754014759124;4105.8973525662095;1163.7722203075666;1342.9194248345132;1375.4154484344115;1376.3599926034242;1390.610629023875;907.2651152566442;914.5975770660888;921.2629896083107;953.756792018031;972.615816752393;5603.719814009283;5721.141828725725;5881.911771323434;5910.024845900464;5979.6711180884595;458.6285109061671;471.9386479757678;473.33260103254247;485.10498130948343;490.4751915450434;3843.673287770515;3888.3972984187494;3917.7257591968287;3963.6432721055244;4010.27814993304;10261.28171028021;10586.947065264625;10721.249512534203;10739.248252622241;10750.892491075145;10606.204534659932;10627.52176152075;10698.946505267424;10701.678949631523;10724.94637526823;8014.118446970829;8037.2766451018515;8131;8192.918070819334;8219;5380.273663055437;5629.746661400329;5656.547476201977;5766.798162064308;5842.591018628656;9405.247580193705;9662.855057174289;9957.352772070082;9959.004099589984;10043.045910638477;9381.014993425591;9964.003599639978;10030.699079027641;10158.542865570918;10220.080192782389;5764.7405866736935;6681.699323530355;7201.459890508128;7270.745523906638;7467.551946883204;8176.059753128228;8218.383621228311;8267.793305167419;8274.13121622221;8288.70989515364;6202.906956395567;7237.131544214728;7321.377682897013;7394.593297368697;7479.102507699111;7182.89225661605;7234.950932114919;7241.094863142107;7280.67236974348;7307.671154798153;6819.420349270169;6915.930840691529;6973.442124629916;6974.37230649244;6983.3714965653335;4756.928646070381;4796.856094317176;4811.566958973709;4882.438519570295;5033.798648054037;4880.341153944172;4931.950680493293;4960.8759781005765;4981.576565991932;5162.612804039637 00:16:58 INFO - PROCESS | 1663 | |4;prototype.html;322.20094166467163;322.5758128061599;330.1943258449383;330.75689368314283;336.10093001221725;5241.240020197074;5259.6581222220575;5307.363116426025;5316.867078323044;5345.732713364326;5224.387806096952;5250.396204436491;5281.63028587998;5295.549878260343;5295.947040529593;3813.256414999083;3874.612538746131;3976.4432979382764;4067.5525692173837;4106.178764247147;3309.7352211823118;3503.7197024238126;4045.63589276965;4062.593740625943;4098.692598055143;3721.1719093488873;3809.847606095753;4010.6189911958318;4017.3572228443454;4036.6770658347264;3309.751768617339;3808.9812399428347;3950.486436763202;4021.2761702893613;4073.959260407388;3530.135116896351;3530.940717784654;3539.9115022124397;3549.4328200158675;3566.892993210208;4435.711678740904;4745.525447455261;5069.746512674352;5072.72100034498;5089.211172268304;4489.73061616304;4500.1390157683845;4571.908561828778;4603.227757313425;4637.003044345448;4808.422989241313;5468.589855760794;5487.6981766002855;5491.2312276281355;5528.364238112628;3083.5066389377594;3742.438634204891;3862.9806850965565;3915.4909861717792;3918.0009097680195;5135.870108576108;5506.311711036121;5770.6249093808765;5791.305043394777;5800.027990483256;3487.302539492112;3593.3352329819068;3676.4117741161285;3782.905427364338;3938.487996560486;3427.520147179397;4037.7577345359373;4038.6163314485075;4044.919101617951;4047.0691740899756;3656.451532270181;4606.383988568958;4612.446506419251;4615.561521655437;4650.4186976627925;5211.973940130351;5341.465853414705;5375.113106337489;5591.245181900473;5624.268845050117;1196.7307355844866;1333.3933060457468;1425.8806836633194;1435.167602790379;1440.6457929546193;753.3333000294779;771.984839935483;844.4553263145237;951.9861347664715;956.8373376526007;4657.930131048037;4751.263554149113;4985.061801177102;5059.937413143208;5108;4713.505081966344;5697;5703.944770217523;5735.791720187758;5738.254026976467;4905.901881962341;5157.561607263425;5188.714620695822;5205.817796377184;5232.215167724872;3120.9531857021707;3249.5125731140047;3282.9179270517952;3285.5071739239306;3287.8027318360973;6479.773207937794;6518.185226846645;6524.380183882524;6527.804165875032;6547.770828020996;5343.812966546152;5943.465088142088;6167.259928808572;6346.635473373247;6444.806655800334;6103.145559621567;6362.236531616236;6498.707558159894;6526.347365263436;6543.640099794462;5540.806071787468;5662;5857.47282744555;6219.036049412348;6266.342034086355;4689;5496.532794712334;5721.225205775112;6150.938490615036;6153.969230153821;6203.751849925952;6322.462590679844;6461.967690161519;6464.321246269074;6507.577007494498;4056.1684854605323;4088.8773336799945;4088.9795551022053;4103.753774773523;4121.402396652528;5421.430749771217;5624.803131890528;5865.73604187804;6012.376658302146;6265.780697675559;4590.265557510782;5866.090755932837;5890.941090589039;6042.848928776816;6096.969515152396;4790.904181916412;5707.172459993195;5960.1357803117435;5998.880022399615;6033.396660333931;5516.917246241229;5824.242848429677;5856.677882716492;5908.261467316585;5958.285005799332;5032.049840874895;5145.948540514547;5191.428942816363;5411.648242864201;5500.312460942382;4652.790624421977;4758.4765675776325;5352.759125839269;5397;5442.836230283915 00:16:58 INFO - PROCESS | 1663 | |5;yui.html;608.4120445636888;709.0923617769257;709.8899670551064;713.6324792731747;717.3687155303334;14440.422383104677;15019.173945432996;15021.774673379909;15031;15054.548363549084;13731.794023089662;13829.930850345734;13841.792373114411;13898.51355202568;13899.16605003698;931.352709866643;942.2273735536862;954.89973552777;956.5265193729114;961.2358175250693;753.1188509443964;778.0391216847189;781.5740421470313;783.3811289081618;952.538019060755;788.4362680683311;811.9472234304781;967.192394350718;1021.8467229915498;1023.6468418395641;972.0765272990652;1006.7533454303742;1007.2646967713611;1007.3804610164769;1008.6772232885479;589.6874656432097;721.4372789224361;740.5926740292871;750.6584504050644;753.6231884057971;11200.439978001148;11280.153988450898;11330.206885518017;14491.275436228147;14984.625384365369;12197.207181533173;12252.387380630933;12376.443060062229;12554;12587.118901676886;9742.75643108928;9824.445808812103;9856.802863942752;9879.604815807359;10263.89736102637;3376.3584918865336;3417.470292104728;3447.224374515714;3456.3087382523595;3640.854365825364;77739.61130194369;79615.40769184641;79764.60117699433;80325.79511307336;81613.59193204055;8763.211310982042;9105.681301154427;9275.304352173614;9320.347575669639;9520.953294945552;20420.693689594667;20658.07038683233;20807.68788468174;20839.958002099833;20907;6458.354164583504;6511.120998665215;6522.054302126166;6624.9337506624315;6697.631630260285;3868.3423817951016;3915.9021024474046;3994.960050399517;4045.664930572963;4052.878413647595;3024.2439390152463;3576.338377400189;3580.051286409104;3581.964180358163;3581.982090089533;633.7401665317198;646.8641585267053;653.7319698923513;663.1147418196762;680.625655889259;498.9875253118701;508.6693649128022;521.8877941242652;562.4740867289098;566.9829905102854;8038.356931445471;8068.596570171468;8209.958950205211;8281.54451505173;8361.372897032747;535.5795700375187;571.6570057965188;594.6759016336107;610.9327973922865;611.8868009418183;3638.21778317663;3836.1368692044066;3883.708721845873;4008.7995600219874;4088.550259471456;82207.17792821996;83897;85462.5726871374;85605.71591426134;90977;67163.66418167877;72983;74531;77382;78875;63507;63643.681781591724;63730;63821.042684359774;65066.34933650603;7481.66332515049;7542.547447153188;7576.545407275581;7730.729424470342;7971.760847174594;40105.24112775422;43551.346729798446;43841;44237.77881110574;44662.660120196444;60336.79326413535;63125.684371577845;63221;63570;63931;9925.3548519346;9941.65204217849;10101.5454304555;10165.898341016495;10522.21083418762;10872.510737017012;10901.073408760345;11123.054540364159;11205.271657342246;11435.59975400857;10128.493575321352;10322.225833062701;10349.896501034893;10379.792404152025;10415.166786656897;10196.643117490852;10198.592056317664;10270.537825797708;10570;11148;8394.328453723809;10946.343219406861;11478.540858365906;11704.063674906158;12112.152149349813;7685.385169186564;9216.603549417146;9377.953110234404;9492.193163581172;9586.618303906092;9233.769155771159;9529.52352382392;9558.761030974281;9592.712218633453;9682.663577653742 00:16:58 INFO - PROCESS | 1663 | __end_tp_report 00:16:58 INFO - PROCESS | 1663 | __start_cc_report 00:16:58 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1075 00:16:58 INFO - PROCESS | 1663 | __end_cc_report 00:16:58 INFO - PROCESS | 1663 | __startTimestamp1461914218241__endTimestamp 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | ------- Summary: start ------- 00:16:58 INFO - PROCESS | 1663 | Number of tests: 6 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5588.55 Median:5744.71 stddev:636.50 (11.1%) stddev-sans-first:612.60 00:16:58 INFO - PROCESS | 1663 | Values: 3299.5 3371.3 3391.6 3397.9 3443.6 5297.4 5383.4 5558.5 5560.7 5587.4 5558.8 5585.7 5642.4 5705.4 5711.1 5009.2 5045.0 5058.4 5082.0 5231.6 5164.5 5165.5 5198.4 5213.0 5228.8 4993.0 5011.2 5080.1 5091.7 5121.4 4338.8 4955.6 5086.6 5086.9 5137.3 5014.8 5061.2 5077.0 5082.8 5151.6 5402.9 5424.2 5520.9 5581.1 5597.6 6055.9 6077.5 6106.8 6113.2 6113.9 4918.5 6110.1 6111.1 6116.7 6121.6 5517.9 5601.7 5603.8 5640.8 5701.3 5398.5 6086.2 6237.9 6249.2 6256.4 5949.6 5954.0 5966.6 5967.4 5981.3 5933.7 6036.3 6069.3 6121.2 6146.5 6018.3 6069.2 6076.3 6077.8 6095.6 6085.5 6097.9 6105.0 6111.9 6113.1 4020.7 4743.4 4776.6 4802.6 4806.8 4316.9 4333.1 4352.2 4355.8 4383.4 5531.8 5539.6 5541.8 5570.0 5606.4 6099.8 6144.6 6155.9 6158.6 6185.9 5469.7 5512.2 5556.3 5574.2 5575.1 5237.9 5248.8 5269.6 5287.3 5307.0 6243.9 6253.3 6278.3 6288.3 6295.9 5234.4 5895.4 6008.5 6146.2 6185.2 6152.9 6220.9 6239.8 6265.6 6283.5 6069.2 6070.1 6076.2 6079.8 6089.1 5974.0 6099.6 6169.4 6171.2 6209.3 4905.8 5900.8 5936.3 5936.9 5992.7 5743.8 5891.4 5923.2 5927.4 5930.6 5627.6 5740.1 5744.7 5789.6 5861.4 5938.0 5938.6 5941.6 5945.5 5990.0 5823.0 5833.7 5839.9 5855.2 5865.0 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:62780.67 Median:18182.88 stddev:133361.21 (733.4%) stddev-sans-first:133653.39 00:16:58 INFO - PROCESS | 1663 | Values: 519.3 536.8 551.0 572.4 583.6 29759.4 30182.5 37789.0 37962.8 38407.6 35071.8 40548.4 41235.6 41442.0 41445.6 9314.0 9562.4 9568.5 9576.2 9587.1 8361.5 9067.9 11332.2 11336.0 11336.7 9803.7 11227.9 11412.9 11422.1 11428.3 10317.8 10464.9 10477.5 10479.9 10489.1 11261.8 11363.7 11372.9 11384.3 11488.5 39045.0 39434.4 39465.8 39494.4 39580.8 2944.6 3223.3 3230.4 3256.8 3397.9 22461.4 28883.4 28896.9 28923.6 28952.7 2689.8 2719.9 2727.6 2766.8 2908.4 492533.0 494225.0 494978.0 498420.0 499300.0 59080.7 59426.0 59471.0 59561.1 59663.8 62994.0 63200.0 63231.4 63298.7 63352.4 16264.4 16484.4 16511.0 16537.3 17037.8 11684.6 11939.5 13250.0 13299.9 13318.6 15107.8 15227.2 15251.5 15258.9 15407.9 6459.0 6541.2 6623.2 6634.1 6641.7 910.0 1030.8 1035.8 1049.1 1110.3 24506.0 26138.7 29143.8 29198.6 29757.4 969.0 984.8 989.5 1011.6 1118.9 14781.6 15021.0 15046.4 15251.4 15295.5 545030.3 548282.0 550602.0 551266.0 637382.0 54999.0 57188.9 57590.7 57756.4 58010.8 1330.3 1364.9 1474.5 1492.5 1493.9 24387.6 27440.7 27514.1 27906.2 28014.7 51091.5 51332.0 51596.5 51658.2 51919.2 420601.0 425865.0 428364.9 428609.7 430273.0 21542.7 21812.1 21885.3 24768.4 25483.6 28076.0 33109.0 33234.8 36264.4 36666.5 32151.7 33709.0 34266.5 35033.0 35243.1 34726.1 34836.3 35369.5 35610.8 35666.6 34260.5 34600.0 34690.8 35110.8 35535.6 25111.0 31022.8 31033.8 31155.7 31227.9 23346.1 23498.4 23603.8 23628.0 23785.4 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35263.75 Median:20296.70 stddev:100454.84 (494.9%) stddev-sans-first:100702.51 00:16:58 INFO - PROCESS | 1663 | Values: 390.1 407.6 410.6 413.2 418.2 18110.5 19636.0 20401.0 20403.9 20744.2 20089.6 20545.1 20560.2 20903.9 20953.5 5776.5 6037.7 6076.2 6088.5 6094.2 5800.0 5862.4 5962.6 6017.1 6020.5 5868.2 5929.7 5929.9 5930.3 5942.0 5573.2 5909.6 5921.2 5925.1 5937.7 4617.5 5746.4 5779.5 5783.9 5950.4 13616.4 13926.2 14010.2 14051.2 20200.5 2074.4 2205.1 2281.8 2288.7 2533.7 9862.0 10282.1 10377.5 10494.4 10521.7 1753.5 1801.7 1838.3 1842.3 1858.4 93894.1 120340.4 121101.0 121165.4 122565.0 9868.3 10300.5 10846.2 11485.1 11669.5 13454.9 15190.9 15260.6 15307.5 15335.4 8735.0 8783.5 8821.2 8826.0 8918.3 7318.7 8429.6 8702.8 8806.3 8830.7 4625.6 4648.4 4685.4 4691.3 4716.8 1909.5 1982.7 1984.7 1989.9 1993.6 936.9 955.5 974.5 1019.5 1064.3 19506.0 20111.3 20392.9 20450.6 20797.7 994.5 994.9 1004.1 1004.1 1018.7 8188.7 8710.9 9446.6 9671.4 11382.3 582317.0 593407.0 599860.0 609844.0 615314.0 126740.0 131241.0 132014.3 151567.0 151895.0 995.8 1033.6 1045.5 1048.4 1051.8 15261.7 17612.3 17905.3 18240.7 18659.5 53758.9 54408.0 54633.6 56505.4 56930.0 75429.0 76456.6 76966.8 77374.6 85364.6 10930.8 11355.8 11923.8 11961.2 12090.2 7194.7 12615.9 12725.0 13232.6 13247.2 11010.2 11375.5 11507.1 11695.4 11809.2 11097.5 11335.8 11448.9 11721.9 11848.4 11376.4 11726.9 11811.3 11844.8 12046.2 10823.8 11627.8 11995.6 12248.0 12327.6 11464.0 11529.8 11581.9 11716.2 11847.5 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5627.08 Median:4888.23 stddev:3126.83 (64.0%) stddev-sans-first:3119.52 00:16:58 INFO - PROCESS | 1663 | Values: 1407.3 1619.8 1633.4 1914.9 2032.6 7221.7 7625.2 8915.5 8919.0 8923.5 8435.9 8705.5 8711.6 8719.5 8721.0 2448.5 2499.1 2500.3 2502.4 2507.3 2350.0 2420.2 2423.6 2426.8 2427.4 2056.5 2442.9 2445.7 2479.8 2479.9 2164.2 2417.1 2436.8 2445.0 2445.1 2304.3 2405.3 2435.0 2435.4 2436.5 8488.9 8663.4 8766.4 8766.9 8775.1 5181.5 5422.4 5458.3 5465.4 5471.3 3457.0 3535.2 3602.1 3611.5 3642.9 1030.9 1053.0 1058.3 1058.7 1061.1 10742.5 11157.9 11164.6 11179.7 11189.2 4733.6 4799.2 4887.6 4888.8 4904.0 9759.6 9780.0 9796.3 9798.0 9799.0 5334.8 5349.8 5350.1 5359.7 5364.1 4042.6 4330.7 4477.7 4495.5 4710.9 3910.2 4028.7 4074.3 4099.8 4105.9 1163.8 1342.9 1375.4 1376.4 1390.6 907.3 914.6 921.3 953.8 972.6 5603.7 5721.1 5881.9 5910.0 5979.7 458.6 471.9 473.3 485.1 490.5 3843.7 3888.4 3917.7 3963.6 4010.3 10261.3 10586.9 10721.2 10739.2 10750.9 10606.2 10627.5 10698.9 10701.7 10724.9 8014.1 8037.3 8131.0 8192.9 8219.0 5380.3 5629.7 5656.5 5766.8 5842.6 9405.2 9662.9 9957.4 9959.0 10043.0 9381.0 9964.0 10030.7 10158.5 10220.1 5764.7 6681.7 7201.5 7270.7 7467.6 8176.1 8218.4 8267.8 8274.1 8288.7 6202.9 7237.1 7321.4 7394.6 7479.1 7182.9 7235.0 7241.1 7280.7 7307.7 6819.4 6915.9 6973.4 6974.4 6983.4 4756.9 4796.9 4811.6 4882.4 5033.8 4880.3 4932.0 4960.9 4981.6 5162.6 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4639.07 Median:5151.76 stddev:1477.30 (28.7%) stddev-sans-first:1445.47 00:16:58 INFO - PROCESS | 1663 | Values: 322.2 322.6 330.2 330.8 336.1 5241.2 5259.7 5307.4 5316.9 5345.7 5224.4 5250.4 5281.6 5295.5 5295.9 3813.3 3874.6 3976.4 4067.6 4106.2 3309.7 3503.7 4045.6 4062.6 4098.7 3721.2 3809.8 4010.6 4017.4 4036.7 3309.8 3809.0 3950.5 4021.3 4074.0 3530.1 3530.9 3539.9 3549.4 3566.9 4435.7 4745.5 5069.7 5072.7 5089.2 4489.7 4500.1 4571.9 4603.2 4637.0 4808.4 5468.6 5487.7 5491.2 5528.4 3083.5 3742.4 3863.0 3915.5 3918.0 5135.9 5506.3 5770.6 5791.3 5800.0 3487.3 3593.3 3676.4 3782.9 3938.5 3427.5 4037.8 4038.6 4044.9 4047.1 3656.5 4606.4 4612.4 4615.6 4650.4 5212.0 5341.5 5375.1 5591.2 5624.3 1196.7 1333.4 1425.9 1435.2 1440.6 753.3 772.0 844.5 952.0 956.8 4657.9 4751.3 4985.1 5059.9 5108.0 4713.5 5697.0 5703.9 5735.8 5738.3 4905.9 5157.6 5188.7 5205.8 5232.2 3121.0 3249.5 3282.9 3285.5 3287.8 6479.8 6518.2 6524.4 6527.8 6547.8 5343.8 5943.5 6167.3 6346.6 6444.8 6103.1 6362.2 6498.7 6526.3 6543.6 5540.8 5662.0 5857.5 6219.0 6266.3 4689.0 5496.5 5721.2 6150.9 6154.0 6203.8 6322.5 6462.0 6464.3 6507.6 4056.2 4088.9 4089.0 4103.8 4121.4 5421.4 5624.8 5865.7 6012.4 6265.8 4590.3 5866.1 5890.9 6042.8 6097.0 4790.9 5707.2 5960.1 5998.9 6033.4 5516.9 5824.2 5856.7 5908.3 5958.3 5032.0 5145.9 5191.4 5411.6 5500.3 4652.8 4758.5 5352.8 5397.0 5442.8 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17193.59 Median:611.41 stddev:24113.82 (3944.0%) stddev-sans-first:24149.31 00:16:58 INFO - PROCESS | 1663 | Values: 608.4 709.1 709.9 713.6 717.4 14440.4 15019.2 15021.8 15031.0 15054.5 13731.8 13829.9 13841.8 13898.5 13899.2 931.4 942.2 954.9 956.5 961.2 753.1 778.0 781.6 783.4 952.5 788.4 811.9 967.2 1021.8 1023.6 972.1 1006.8 1007.3 1007.4 1008.7 589.7 721.4 740.6 750.7 753.6 11200.4 11280.2 11330.2 14491.3 14984.6 12197.2 12252.4 12376.4 12554.0 12587.1 9742.8 9824.4 9856.8 9879.6 10263.9 3376.4 3417.5 3447.2 3456.3 3640.9 77739.6 79615.4 79764.6 80325.8 81613.6 8763.2 9105.7 9275.3 9320.3 9521.0 20420.7 20658.1 20807.7 20840.0 20907.0 6458.4 6511.1 6522.1 6624.9 6697.6 3868.3 3915.9 3995.0 4045.7 4052.9 3024.2 3576.3 3580.1 3582.0 3582.0 633.7 646.9 653.7 663.1 680.6 499.0 508.7 521.9 562.5 567.0 8038.4 8068.6 8210.0 8281.5 8361.4 535.6 571.7 594.7 610.9 611.9 3638.2 3836.1 3883.7 4008.8 4088.6 82207.2 83897.0 85462.6 85605.7 90977.0 67163.7 72983.0 74531.0 77382.0 78875.0 63507.0 63643.7 63730.0 63821.0 65066.3 7481.7 7542.5 7576.5 7730.7 7971.8 40105.2 43551.3 43841.0 44237.8 44662.7 60336.8 63125.7 63221.0 63570.0 63931.0 9925.4 9941.7 10101.5 10165.9 10522.2 10872.5 10901.1 11123.1 11205.3 11435.6 10128.5 10322.2 10349.9 10379.8 10415.2 10196.6 10198.6 10270.5 10570.0 11148.0 8394.3 10946.3 11478.5 11704.1 12112.2 7685.4 9216.6 9378.0 9492.2 9586.6 9233.8 9529.5 9558.8 9592.7 9682.7 00:16:58 INFO - PROCESS | 1663 | -------- Summary: end -------- 00:16:58 INFO - PROCESS | 1663 | 00:16:58 INFO - TEST-INFO | 1663: exit 0 00:16:58 INFO - TEST-OK | dromaeo_css | took 1114280ms 00:16:58 INFO - TEST-START | kraken 00:16:58 INFO - operating with platform_type : mac_ 00:16:58 INFO - Initialising browser for kraken test... 00:16:58 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpTLrgq6/profile http://localhost:49270/getInfo.html) 00:17:01 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 00:17:01 INFO - PROCESS | 1672 | colorDepth:24 00:17:01 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 00:17:01 INFO - PROCESS | 1672 | __metrics 00:17:01 INFO - PROCESS | 1672 | 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" 00:17:01 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:17:02 INFO - TEST-INFO | 1672: exit 0 00:17:02 INFO - Browser initialized. 00:17:02 INFO - Running cycle 1/1 for kraken test... 00:17:02 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpTLrgq6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:17:14 INFO - PROCESS | 1674 | RSS: Main: 239292416 00:17:14 INFO - PROCESS | 1674 | 00:17:16 INFO - PROCESS | 1674 | 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) 00:17:17 INFO - PROCESS | 1674 | RSS: Main: 286445568 00:17:17 INFO - PROCESS | 1674 | 00:17:20 INFO - PROCESS | 1674 | 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) 00:17:21 INFO - PROCESS | 1674 | RSS: Main: 295776256 00:17:21 INFO - PROCESS | 1674 | 00:17:24 INFO - PROCESS | 1674 | 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) 00:17:25 INFO - PROCESS | 1674 | RSS: Main: 378957824 00:17:25 INFO - PROCESS | 1674 | 00:17:27 INFO - PROCESS | 1674 | 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) 00:17:27 INFO - PROCESS | 1674 | RSS: Main: 280027136 00:17:27 INFO - PROCESS | 1674 | 00:17:30 INFO - PROCESS | 1674 | 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) 00:17:30 INFO - PROCESS | 1674 | RSS: Main: 283385856 00:17:30 INFO - PROCESS | 1674 | 00:17:33 INFO - PROCESS | 1674 | 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) 00:17:34 INFO - PROCESS | 1674 | RSS: Main: 323301376 00:17:34 INFO - PROCESS | 1674 | 00:17:36 INFO - PROCESS | 1674 | 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) 00:17:37 INFO - PROCESS | 1674 | RSS: Main: 324644864 00:17:37 INFO - PROCESS | 1674 | 00:17:40 INFO - PROCESS | 1674 | 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) 00:17:40 INFO - PROCESS | 1674 | RSS: Main: 323076096 00:17:40 INFO - PROCESS | 1674 | 00:17:42 INFO - PROCESS | 1674 | 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) 00:17:43 INFO - PROCESS | 1674 | RSS: Main: 314662912 00:17:43 INFO - PROCESS | 1674 | 00:17:44 INFO - PROCESS | 1674 | 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) 00:17:45 INFO - PROCESS | 1674 | RSS: Main: 321572864 00:17:45 INFO - PROCESS | 1674 | 00:17:47 INFO - PROCESS | 1674 | 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) 00:17:48 INFO - PROCESS | 1674 | RSS: Main: 323358720 00:17:48 INFO - PROCESS | 1674 | 00:17:51 INFO - PROCESS | 1674 | 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) 00:17:52 INFO - PROCESS | 1674 | RSS: Main: 321351680 00:17:52 INFO - PROCESS | 1674 | 00:17:54 INFO - PROCESS | 1674 | 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) 00:17:55 INFO - PROCESS | 1674 | RSS: Main: 322072576 00:17:55 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | 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) 00:17:57 INFO - PROCESS | 1674 | RSS: Main: 386392064 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | __start_tp_report 00:17:57 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 00:17:57 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 00:17:57 INFO - PROCESS | 1674 | |i|pagename|runs| 00:17:57 INFO - PROCESS | 1674 | |0;ai-astar;111;112;108;114;112;113;112;113;105;112 00:17:57 INFO - PROCESS | 1674 | |1;audio-beat-detection;158;123;144;169;118;142;142;118;168;120 00:17:57 INFO - PROCESS | 1674 | |2;audio-dft;186;187;187;186;181;184;185;185;185;183 00:17:57 INFO - PROCESS | 1674 | |3;audio-fft;75;73;99;180;81;83;55;69;135;56 00:17:57 INFO - PROCESS | 1674 | |4;audio-oscillator;92;99;134;78;80;78;121;80;76;125 00:17:57 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;117;131;92;83;86;83;89;83;85;83 00:17:57 INFO - PROCESS | 1674 | |6;imaging-darkroom;92;92;93;97;93;93;92;92;93;93 00:17:57 INFO - PROCESS | 1674 | |7;imaging-desaturate;84;84;84;84;84;84;86;86;84;88 00:17:57 INFO - PROCESS | 1674 | |8;json-parse-financial;59;59;59;58;58;59;60;72;56;58 00:17:57 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;65;63;71;78;69;67;68;67;67;70 00:17:57 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;84;81;82;83;81;82;80;82;83;85 00:17:57 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;137;150;137;134;135;134;138;154;189;148 00:17:57 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;170;169;175;231;171;170;165;170;169;167 00:17:57 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;70;68;70;69;69;69;70;69;70;67 00:17:57 INFO - PROCESS | 1674 | __end_tp_report 00:17:57 INFO - PROCESS | 1674 | __start_cc_report 00:17:57 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1995 00:17:57 INFO - PROCESS | 1674 | __end_cc_report 00:17:57 INFO - PROCESS | 1674 | __startTimestamp1461914277547__endTimestamp 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | ------- Summary: start ------- 00:17:57 INFO - PROCESS | 1674 | Number of tests: 14 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.70 (2.4%) stddev-sans-first:2.86 00:17:57 INFO - PROCESS | 1674 | Values: 111.0 112.0 108.0 114.0 112.0 113.0 112.0 113.0 105.0 112.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:143.00 stddev:20.08 (14.0%) stddev-sans-first:20.24 00:17:57 INFO - PROCESS | 1674 | Values: 158.0 123.0 144.0 169.0 118.0 142.0 142.0 118.0 168.0 120.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:1.85 (1.0%) stddev-sans-first:1.92 00:17:57 INFO - PROCESS | 1674 | Values: 186.0 187.0 187.0 186.0 181.0 184.0 185.0 185.0 185.0 183.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:90.60 Median:74.00 stddev:38.97 (52.7%) stddev-sans-first:40.92 00:17:57 INFO - PROCESS | 1674 | Values: 75.0 73.0 99.0 180.0 81.0 83.0 55.0 69.0 135.0 56.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:79.00 stddev:22.35 (28.3%) stddev-sans-first:23.65 00:17:57 INFO - PROCESS | 1674 | Values: 92.0 99.0 134.0 78.0 80.0 78.0 121.0 80.0 76.0 125.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:84.00 stddev:16.83 (20.0%) stddev-sans-first:15.49 00:17:57 INFO - PROCESS | 1674 | Values: 117.0 131.0 92.0 83.0 86.0 83.0 89.0 83.0 85.0 83.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:93.00 Median:93.00 stddev:1.49 (1.6%) stddev-sans-first:1.54 00:17:57 INFO - PROCESS | 1674 | Values: 92.0 92.0 93.0 97.0 93.0 93.0 92.0 92.0 93.0 93.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.00 stddev:1.40 (1.7%) stddev-sans-first:1.45 00:17:57 INFO - PROCESS | 1674 | Values: 84.0 84.0 84.0 84.0 84.0 84.0 86.0 86.0 84.0 88.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:59.00 stddev:4.42 (7.5%) stddev-sans-first:4.68 00:17:57 INFO - PROCESS | 1674 | Values: 59.0 59.0 59.0 58.0 58.0 59.0 60.0 72.0 56.0 58.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:68.50 stddev:4.06 (5.9%) stddev-sans-first:4.11 00:17:57 INFO - PROCESS | 1674 | Values: 65.0 63.0 71.0 78.0 69.0 67.0 68.0 67.0 67.0 70.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:82.50 stddev:1.49 (1.8%) stddev-sans-first:1.45 00:17:57 INFO - PROCESS | 1674 | Values: 84.0 81.0 82.0 83.0 81.0 82.0 80.0 82.0 83.0 85.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:143.00 stddev:16.89 (11.8%) stddev-sans-first:17.62 00:17:57 INFO - PROCESS | 1674 | Values: 137.0 150.0 137.0 134.0 135.0 134.0 138.0 154.0 189.0 148.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:170.00 stddev:19.60 (11.5%) stddev-sans-first:20.68 00:17:57 INFO - PROCESS | 1674 | Values: 170.0 169.0 175.0 231.0 171.0 170.0 165.0 170.0 169.0 167.0 00:17:57 INFO - PROCESS | 1674 | 00:17:57 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:0.99 (1.4%) stddev-sans-first:1.00 00:17:57 INFO - PROCESS | 1674 | Values: 70.0 68.0 70.0 69.0 69.0 69.0 70.0 69.0 70.0 67.0 00:17:57 INFO - PROCESS | 1674 | -------- Summary: end -------- 00:17:57 INFO - PROCESS | 1674 | 00:17:58 INFO - TEST-INFO | 1674: exit 0 00:17:58 INFO - TEST-OK | kraken | took 59549ms 00:17:58 INFO - SUITE-END | took 1173s 00:17:58 INFO - Completed test suite (00:19:34) 00:17:58 INFO - javascript benchmark 00:17:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.5380646709464, 3371.342588195303, 3391.6269210386868, 3397.898063058108, 3443.58676958765, 5297.364316282042, 5383.380911195214, 5558.499735023847, 5560.749766260518, 5587.357453892799, 5558.805441809537, 5585.748641311141, 5642.379338272797, 5705.400932902048, 5711.086226203808, 5009.223570346584, 5045.0, 5058.4435712071645, 5082.009008243394, 5231.5814734821315, 5164.45773193813, 5165.457626949173, 5198.428172900997, 5212.973935130319, 5228.843134705965, 4993.001399720052, 5011.173156429185, 5080.110980578384, 5091.745412729349, 5121.436641969381, 4338.828516300613, 4955.603551715854, 5086.567641750456, 5086.948080072889, 5137.33214682089, 5014.774335154927, 5061.240813877911, 5076.974615126901, 5082.79668813247, 5151.639385243035, 5402.91895621566, 5424.240606315119, 5520.889582208373, 5581.0512212923495, 5597.552195824324, 6055.878882422371, 6077.483413909823, 6106.816795496142, 6113.205283313186, 6113.908291375633, 4918.532739389752, 6110.08348747687, 6111.113888486189, 6116.694165291718, 6121.602095863799, 5517.944820551783, 5601.747921343509, 5603.80386686464, 5640.774369025234, 5701.344345400249, 5398.541124004425, 6086.239220097488, 6237.906431403532, 6249.218847644044, 6256.374362563707, 5949.613275137189, 5954.0, 5966.582339236212, 5967.433093856077, 5981.312149102821, 5933.6736479493175, 6036.3360030396625, 6069.332373438918, 6121.204243448323, 6146.508279337642, 6018.277806663228, 6069.241344831896, 6076.27084749824, 6077.8480537986015, 6095.634261944208, 6085.513158947274, 6097.908531372033, 6104.969475152596, 6111.938880611226, 6113.083037544404, 4020.6783457323345, 4743.359646447754, 4776.614781713264, 4802.615790736802, 4806.831760888422, 4316.870493885189, 4333.111712099012, 4352.238358287287, 4355.825766969349, 4383.408239887638, 5531.834044978657, 5539.639923404966, 5541.833744987657, 5569.972150139223, 5606.355269144019, 6099.817005489843, 6144.569880108439, 6155.938440615537, 6158.599691020069, 6185.907211391833, 5469.726513674301, 5512.200730893998, 5556.333240011144, 5574.219609254707, 5575.080111781592, 5237.921431178535, 5248.76380562868, 5269.604779641466, 5287.312649355559, 5307.0, 6243.906341404882, 6253.312135665074, 6278.277998030285, 6288.308286088527, 6295.874082518416, 5234.424213336454, 5895.408239775151, 6008.489278411384, 6146.2033334162725, 6185.195924529782, 6152.923238433167, 6220.875582488416, 6239.844003899939, 6265.56141070139, 6283.49732021428, 6069.150318955261, 6070.089486577049, 6076.179715738495, 6079.787207447718, 6089.119295196399, 5974.014287642491, 6099.573029887865, 6169.383061693795, 6171.228596425447, 6209.254889413119, 4905.754712264444, 5900.793472228452, 5936.28764548257, 5936.851578710567, 5992.730327135202, 5743.798967036134, 5891.440313170242, 5923.200367950271, 5927.377625349449, 5930.555208359304, 5627.634203776742, 5740.052891272893, 5744.712764361849, 5789.594728368974, 5861.443162899518, 5938.049912014057, 5938.61399009063, 5941.643501389931, 5945.494632956247, 5989.970050149721, 5822.98097832886, 5833.67914764692, 5839.883202336015, 5855.238818953509, 5865.0], "value": 5549.261738250064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.2859817750593, 536.8147988943814, 551.0191858491886, 572.3703925681748, 583.6439771739239, 29759.404811903776, 30182.547261791064, 37789.0, 37962.81018594906, 38407.615923840756, 35071.82464087676, 40548.39177412341, 41235.58764412355, 41441.96395090124, 41445.58554414455, 9313.953430232856, 9562.426254424721, 9568.473733944631, 9576.186024187953, 9587.13715765581, 8361.456505327134, 9067.909320906772, 11332.206745527816, 11335.979761821434, 11336.716582085473, 9803.67650367202, 11227.943860280688, 11412.885871141265, 11422.143339249547, 11428.314301141958, 10317.84523232152, 10464.947675261652, 10477.528511216937, 10479.895201047968, 10489.055984961315, 11261.774764504746, 11363.715907102305, 11372.943135284353, 11384.260023098555, 11488.540458381654, 39045.0, 39434.408483872765, 39465.80267098675, 39494.40758388598, 39580.80209598933, 2944.5877577139217, 3223.3231021485517, 3230.361606897824, 3256.7557433192605, 3397.915052123692, 22461.43846403853, 28883.422331553462, 28896.855515722287, 28923.56614650782, 28952.710472895422, 2689.7982651301036, 2719.864006799672, 2727.5635898256382, 2766.792490563216, 2908.43285559316, 492533.0, 494225.0, 494978.0, 498420.0, 499300.0, 59080.704596476746, 59426.0, 59471.0, 59561.106583401284, 59663.80672386528, 62994.0, 63200.0, 63231.36768632255, 63298.6835065831, 63352.366476334646, 16264.430744924108, 16484.423045193602, 16511.0, 16537.338506459848, 17037.829621703873, 11684.649460516199, 11939.462724177432, 13250.0, 13299.867001329863, 13318.600441986757, 15107.773383399257, 15227.238638068053, 15251.542453726406, 15258.923705381401, 15407.922960385127, 6458.967705161444, 6541.182352205974, 6623.238327592292, 6634.137562116894, 6641.667916604247, 909.9636014559476, 1030.8351562734063, 1035.7565971996576, 1049.1277082652207, 1110.3005106782675, 24506.0, 26138.73861261401, 29143.834246630322, 29198.567640052446, 29757.40485190284, 968.9806203876024, 984.7636567223815, 989.5151375825938, 1011.6256984915628, 1118.9160812938899, 14781.556553303419, 15021.0, 15046.398144074334, 15251.389944402099, 15295.541133766004, 545030.2748486232, 548282.0, 550601.9879602465, 551266.0, 637382.0, 54999.0, 57188.856222876144, 57590.7120464395, 57756.4224357751, 58010.839783204945, 1330.301591664384, 1364.883984861258, 1474.5428917035538, 1492.514932646894, 1493.8804895608325, 24387.634185486877, 27440.725592744617, 27514.106863847966, 27906.16281511558, 28014.719852801212, 51091.48908511016, 51332.0, 51596.48403516067, 51658.225126622376, 51919.22121168261, 420601.0, 425865.0, 428364.8581757071, 428609.713902857, 430273.0, 21542.6768598468, 21812.127514899224, 21885.343439696833, 24768.380790479663, 25483.61774573346, 28076.0, 33109.0, 33234.83382583072, 36264.37902125175, 36666.45000324944, 32151.67848321487, 33709.0, 34266.48600271048, 35033.0, 35243.118922027155, 34726.13184670403, 34836.303273934886, 35369.46945795764, 35610.82194589011, 35666.64333356737, 34260.48609270913, 34600.0, 34690.82654586711, 35110.82444587761, 35535.644643553234, 25111.0, 31022.844885775427, 31033.844830776605, 31155.68844311528, 31227.907023254076, 23346.062338804208, 23498.412539686644, 23603.763962360157, 23628.0, 23785.405364866016], "value": 18975.032074870865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.13390273592614, 407.63313018283543, 410.6468437144056, 413.1736526946108, 418.155326240993, 18110.5472363191, 19636.0, 20401.0, 20403.897980510093, 20744.170233190653, 20089.59820803583, 20545.075471603777, 20560.168360435324, 20903.895480522613, 20953.476163095893, 5776.537876969833, 6037.667928263943, 6076.240469941257, 6088.45203931646, 6094.238220222473, 5800.0, 5862.384449632791, 5962.582619216657, 6017.097435384705, 6020.488258498033, 5868.17845501628, 5929.673867937262, 5929.851753706171, 5930.288365396159, 5941.970290148565, 5573.191887176357, 5909.586328956974, 5921.230240068785, 5925.140854576105, 5937.70311484424, 4617.538246175389, 5746.36789953109, 5779.479846813765, 5783.855403614902, 5950.404959504058, 13616.387262573207, 13926.164430134224, 14010.159390436607, 14051.227182504957, 20200.49498762543, 2074.408793493862, 2205.073868975034, 2281.828862835294, 2288.6567014947723, 2533.6579561759295, 9862.0, 10282.074613284765, 10377.481125943672, 10494.422806745626, 10521.736956576071, 1753.5265478320782, 1801.7387478815629, 1838.2555065198617, 1842.2723024405284, 1858.4145994011842, 93894.06105938922, 120340.39829800882, 121101.0, 121165.39417302857, 122565.0, 9868.30921835479, 10300.484975751333, 10846.240763146661, 11485.138614603939, 11669.533218671328, 13454.93272533631, 15190.924045379703, 15260.618484537754, 15307.464238751812, 15335.386584536716, 8734.956325218332, 8783.472991620523, 8821.161989610977, 8825.955870220609, 8918.331125165509, 7318.682637125368, 8429.594461715735, 8702.825943481095, 8806.339524535557, 8830.735077947671, 4625.560571745679, 4648.372469716613, 4685.390899183084, 4691.272852707836, 4716.764161791897, 1909.5035290824485, 1982.692682634194, 1984.7320611717523, 1989.9403017909485, 1993.5913137806715, 936.9109934556207, 955.498363359228, 974.4737841565475, 1019.5157300282455, 1064.3028816125295, 19506.0, 20111.29610463627, 20392.898035510025, 20450.590988180153, 20797.688034679493, 994.4878387630376, 994.8955359687128, 1004.1163775877181, 1004.1414590525142, 1018.6553749051329, 8188.716924613081, 8710.91289087101, 9446.574904129331, 9671.371360861522, 11382.317060976367, 582317.0, 593407.0, 599860.0007000024, 609844.0, 615314.0, 126740.0, 131241.0313845293, 132014.33992830166, 151567.0, 151895.0, 995.7687304696307, 1033.5917312661456, 1045.4981608827804, 1048.3605000949415, 1051.805415998043, 15261.694766104394, 17612.29550817953, 17905.28378864883, 18240.726389103907, 18659.533511662317, 53758.924821502565, 54408.0, 54633.63415914634, 56505.43494565002, 56930.0, 75429.0, 76456.61771691106, 76966.84265258654, 77374.61312693401, 85364.5731771362, 10930.836037459603, 11355.820370296331, 11923.76152476963, 11961.222520536137, 12090.153689241668, 7194.676239569128, 12615.87384126147, 12725.0, 13232.603021909357, 13247.20516768997, 11010.174236932422, 11375.54497820078, 11507.136964727846, 11695.35816161628, 11809.23239989398, 11097.500612472297, 11335.829962550733, 11448.885511144781, 11721.886420790011, 11848.407579620813, 11376.374299413612, 11726.88273117258, 11811.291322520676, 11844.822327665264, 12046.156769026084, 10823.80938096793, 11627.825582616098, 11995.64013079609, 12248.0, 12327.568535101229, 11464.0, 11529.82705259405, 11581.88418115808, 11716.238444501421, 11847.466863990969], "value": 9712.613140690124, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.303384824512, 1619.7651340555617, 1633.4446288261993, 1914.8946807925558, 2032.5629989552253, 7221.711131554738, 7625.199354067826, 8915.465072095678, 8919.0, 8923.464592124477, 8435.873461898076, 8705.477671339724, 8711.564421778901, 8719.520426376546, 8720.997085335188, 2448.52253810507, 2499.1128149506935, 2500.2999160235163, 2502.436951685865, 2507.260358194337, 2349.9647505287435, 2420.2376251480728, 2423.6243382275775, 2426.7573242675767, 2427.356750461129, 2056.516718571135, 2442.8778561071963, 2445.7065152181763, 2479.752024797524, 2479.913203037894, 2164.2425151196953, 2417.10567090176, 2436.7685069918416, 2445.0464318915547, 2445.119756887519, 2304.308707387777, 2405.314485371678, 2434.9756502434925, 2435.3789783605243, 2436.537057959, 8488.915110848875, 8663.39356245063, 8766.430182038212, 8766.912330876738, 8775.122487751238, 5181.481851814826, 5422.376426710939, 5458.344998600153, 5465.4261302563455, 5471.2887324647545, 3456.9827150864335, 3535.1515636247373, 3602.0634634994826, 3611.494390785292, 3642.8724994625327, 1030.8505266736356, 1052.952617132231, 1058.3226734889677, 1058.6771034834373, 1061.103367654331, 10742.462876856125, 11157.888421115766, 11164.609238676607, 11179.720506987309, 11189.160812939062, 4733.5503127202865, 4799.199472920259, 4887.633427492952, 4888.828890988798, 4903.975480122576, 9759.560819763128, 9780.0, 9796.314258002014, 9797.951010245046, 9799.0, 5334.759935802898, 5349.812756553502, 5350.0904846176245, 5359.705216213146, 5364.088105022156, 4042.555318914917, 4330.740155590612, 4477.664175186809, 4495.483019452739, 4710.882227944328, 3910.2179564086728, 4028.7381320214677, 4074.266632006267, 4099.754014759124, 4105.8973525662095, 1163.7722203075666, 1342.9194248345132, 1375.4154484344115, 1376.3599926034242, 1390.610629023875, 907.2651152566442, 914.5975770660888, 921.2629896083107, 953.756792018031, 972.615816752393, 5603.719814009283, 5721.141828725725, 5881.911771323434, 5910.024845900464, 5979.6711180884595, 458.6285109061671, 471.9386479757678, 473.33260103254247, 485.10498130948343, 490.4751915450434, 3843.673287770515, 3888.3972984187494, 3917.7257591968287, 3963.6432721055244, 4010.27814993304, 10261.28171028021, 10586.947065264625, 10721.249512534203, 10739.248252622241, 10750.892491075145, 10606.204534659932, 10627.52176152075, 10698.946505267424, 10701.678949631523, 10724.94637526823, 8014.118446970829, 8037.2766451018515, 8131.0, 8192.918070819334, 8219.0, 5380.273663055437, 5629.746661400329, 5656.547476201977, 5766.798162064308, 5842.591018628656, 9405.247580193705, 9662.855057174289, 9957.352772070082, 9959.004099589984, 10043.045910638477, 9381.014993425591, 9964.003599639978, 10030.699079027641, 10158.542865570918, 10220.080192782389, 5764.7405866736935, 6681.699323530355, 7201.459890508128, 7270.745523906638, 7467.551946883204, 8176.059753128228, 8218.383621228311, 8267.793305167419, 8274.13121622221, 8288.70989515364, 6202.906956395567, 7237.131544214728, 7321.377682897013, 7394.593297368697, 7479.102507699111, 7182.89225661605, 7234.950932114919, 7241.094863142107, 7280.67236974348, 7307.671154798153, 6819.420349270169, 6915.930840691529, 6973.442124629916, 6974.37230649244, 6983.3714965653335, 4756.928646070381, 4796.856094317176, 4811.566958973709, 4882.438519570295, 5033.798648054037, 4880.341153944172, 4931.950680493293, 4960.8759781005765, 4981.576565991932, 5162.612804039637], "value": 4490.280395726334, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.20094166467163, 322.5758128061599, 330.1943258449383, 330.75689368314283, 336.10093001221725, 5241.240020197074, 5259.6581222220575, 5307.363116426025, 5316.867078323044, 5345.732713364326, 5224.387806096952, 5250.396204436491, 5281.63028587998, 5295.549878260343, 5295.947040529593, 3813.256414999083, 3874.612538746131, 3976.4432979382764, 4067.5525692173837, 4106.178764247147, 3309.7352211823118, 3503.7197024238126, 4045.63589276965, 4062.593740625943, 4098.692598055143, 3721.1719093488873, 3809.847606095753, 4010.6189911958318, 4017.3572228443454, 4036.6770658347264, 3309.751768617339, 3808.9812399428347, 3950.486436763202, 4021.2761702893613, 4073.959260407388, 3530.135116896351, 3530.940717784654, 3539.9115022124397, 3549.4328200158675, 3566.892993210208, 4435.711678740904, 4745.525447455261, 5069.746512674352, 5072.72100034498, 5089.211172268304, 4489.73061616304, 4500.1390157683845, 4571.908561828778, 4603.227757313425, 4637.003044345448, 4808.422989241313, 5468.589855760794, 5487.6981766002855, 5491.2312276281355, 5528.364238112628, 3083.5066389377594, 3742.438634204891, 3862.9806850965565, 3915.4909861717792, 3918.0009097680195, 5135.870108576108, 5506.311711036121, 5770.6249093808765, 5791.305043394777, 5800.027990483256, 3487.302539492112, 3593.3352329819068, 3676.4117741161285, 3782.905427364338, 3938.487996560486, 3427.520147179397, 4037.7577345359373, 4038.6163314485075, 4044.919101617951, 4047.0691740899756, 3656.451532270181, 4606.383988568958, 4612.446506419251, 4615.561521655437, 4650.4186976627925, 5211.973940130351, 5341.465853414705, 5375.113106337489, 5591.245181900473, 5624.268845050117, 1196.7307355844866, 1333.3933060457468, 1425.8806836633194, 1435.167602790379, 1440.6457929546193, 753.3333000294779, 771.984839935483, 844.4553263145237, 951.9861347664715, 956.8373376526007, 4657.930131048037, 4751.263554149113, 4985.061801177102, 5059.937413143208, 5108.0, 4713.505081966344, 5697.0, 5703.944770217523, 5735.791720187758, 5738.254026976467, 4905.901881962341, 5157.561607263425, 5188.714620695822, 5205.817796377184, 5232.215167724872, 3120.9531857021707, 3249.5125731140047, 3282.9179270517952, 3285.5071739239306, 3287.8027318360973, 6479.773207937794, 6518.185226846645, 6524.380183882524, 6527.804165875032, 6547.770828020996, 5343.812966546152, 5943.465088142088, 6167.259928808572, 6346.635473373247, 6444.806655800334, 6103.145559621567, 6362.236531616236, 6498.707558159894, 6526.347365263436, 6543.640099794462, 5540.806071787468, 5662.0, 5857.47282744555, 6219.036049412348, 6266.342034086355, 4689.0, 5496.532794712334, 5721.225205775112, 6150.938490615036, 6153.969230153821, 6203.751849925952, 6322.462590679844, 6461.967690161519, 6464.321246269074, 6507.577007494498, 4056.1684854605323, 4088.8773336799945, 4088.9795551022053, 4103.753774773523, 4121.402396652528, 5421.430749771217, 5624.803131890528, 5865.73604187804, 6012.376658302146, 6265.780697675559, 4590.265557510782, 5866.090755932837, 5890.941090589039, 6042.848928776816, 6096.969515152396, 4790.904181916412, 5707.172459993195, 5960.1357803117435, 5998.880022399615, 6033.396660333931, 5516.917246241229, 5824.242848429677, 5856.677882716492, 5908.261467316585, 5958.285005799332, 5032.049840874895, 5145.948540514547, 5191.428942816363, 5411.648242864201, 5500.312460942382, 4652.790624421977, 4758.4765675776325, 5352.759125839269, 5397.0, 5442.836230283915], "value": 4174.51043655704, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.4120445636888, 709.0923617769257, 709.8899670551064, 713.6324792731747, 717.3687155303334, 14440.422383104677, 15019.173945432996, 15021.774673379909, 15031.0, 15054.548363549084, 13731.794023089662, 13829.930850345734, 13841.792373114411, 13898.51355202568, 13899.16605003698, 931.352709866643, 942.2273735536862, 954.89973552777, 956.5265193729114, 961.2358175250693, 753.1188509443964, 778.0391216847189, 781.5740421470313, 783.3811289081618, 952.538019060755, 788.4362680683311, 811.9472234304781, 967.192394350718, 1021.8467229915498, 1023.6468418395641, 972.0765272990652, 1006.7533454303742, 1007.2646967713611, 1007.3804610164769, 1008.6772232885479, 589.6874656432097, 721.4372789224361, 740.5926740292871, 750.6584504050644, 753.6231884057971, 11200.439978001148, 11280.153988450898, 11330.206885518017, 14491.275436228147, 14984.625384365369, 12197.207181533173, 12252.387380630933, 12376.443060062229, 12554.0, 12587.118901676886, 9742.75643108928, 9824.445808812103, 9856.802863942752, 9879.604815807359, 10263.89736102637, 3376.3584918865336, 3417.470292104728, 3447.224374515714, 3456.3087382523595, 3640.854365825364, 77739.61130194369, 79615.40769184641, 79764.60117699433, 80325.79511307336, 81613.59193204055, 8763.211310982042, 9105.681301154427, 9275.304352173614, 9320.347575669639, 9520.953294945552, 20420.693689594667, 20658.07038683233, 20807.68788468174, 20839.958002099833, 20907.0, 6458.354164583504, 6511.120998665215, 6522.054302126166, 6624.9337506624315, 6697.631630260285, 3868.3423817951016, 3915.9021024474046, 3994.960050399517, 4045.664930572963, 4052.878413647595, 3024.2439390152463, 3576.338377400189, 3580.051286409104, 3581.964180358163, 3581.982090089533, 633.7401665317198, 646.8641585267053, 653.7319698923513, 663.1147418196762, 680.625655889259, 498.9875253118701, 508.6693649128022, 521.8877941242652, 562.4740867289098, 566.9829905102854, 8038.356931445471, 8068.596570171468, 8209.958950205211, 8281.54451505173, 8361.372897032747, 535.5795700375187, 571.6570057965188, 594.6759016336107, 610.9327973922865, 611.8868009418183, 3638.21778317663, 3836.1368692044066, 3883.708721845873, 4008.7995600219874, 4088.550259471456, 82207.17792821996, 83897.0, 85462.5726871374, 85605.71591426134, 90977.0, 67163.66418167877, 72983.0, 74531.0, 77382.0, 78875.0, 63507.0, 63643.681781591724, 63730.0, 63821.042684359774, 65066.34933650603, 7481.66332515049, 7542.547447153188, 7576.545407275581, 7730.729424470342, 7971.760847174594, 40105.24112775422, 43551.346729798446, 43841.0, 44237.77881110574, 44662.660120196444, 60336.79326413535, 63125.684371577845, 63221.0, 63570.0, 63931.0, 9925.3548519346, 9941.65204217849, 10101.5454304555, 10165.898341016495, 10522.21083418762, 10872.510737017012, 10901.073408760345, 11123.054540364159, 11205.271657342246, 11435.59975400857, 10128.493575321352, 10322.225833062701, 10349.896501034893, 10379.792404152025, 10415.166786656897, 10196.643117490852, 10198.592056317664, 10270.537825797708, 10570.0, 11148.0, 8394.328453723809, 10946.343219406861, 11478.540858365906, 11704.063674906158, 12112.152149349813, 7685.385169186564, 9216.603549417146, 9377.953110234404, 9492.193163581172, 9586.618303906092, 9233.769155771159, 9529.52352382392, 9558.761030974281, 9592.712218633453, 9682.663577653742], "value": 6612.430938331577, "unit": "score"}], "name": "dromaeo_css", "value": 7087.718107894785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 108.0, 114.0, 112.0, 113.0, 112.0, 113.0, 105.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 144.0, 169.0, 118.0, 142.0, 142.0, 118.0, 168.0, 120.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 187.0, 186.0, 181.0, 184.0, 185.0, 185.0, 185.0, 183.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 99.0, 180.0, 81.0, 83.0, 55.0, 69.0, 135.0, 56.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 134.0, 78.0, 80.0, 78.0, 121.0, 80.0, 76.0, 125.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 131.0, 92.0, 83.0, 86.0, 83.0, 89.0, 83.0, 85.0, 83.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 97.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 86.0, 86.0, 84.0, 88.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 58.0, 58.0, 59.0, 60.0, 72.0, 56.0, 58.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 71.0, 78.0, 69.0, 67.0, 68.0, 67.0, 67.0, 70.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 83.0, 81.0, 82.0, 80.0, 82.0, 83.0, 85.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 150.0, 137.0, 134.0, 135.0, 134.0, 138.0, 154.0, 189.0, 148.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 175.0, 231.0, 171.0, 170.0, 165.0, 170.0, 169.0, 167.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 69.0, 69.0, 69.0, 70.0, 69.0, 70.0, 67.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1495.1999999999998, "alertThreshold": 2.0}]} 00:17:58 INFO - Return code: 0 00:17:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 00:17:58 ERROR - # TBPL SUCCESS # 00:17:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:17:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:17:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:17:58 INFO - 2016-04-29 00:17:58.882 screenresolution[1678:9864] starting screenresolution argv=screenresolution get 00:17:58 INFO - 2016-04-29 00:17:58.885 screenresolution[1678:9864] Display 0: 1600x1200x32@60 00:17:58 INFO - 2016-04-29 00:17:58.891 screenresolution[1679:9867] starting screenresolution argv=screenresolution list 00:17:58 INFO - Available Modes on Display 0 00:17:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:17:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:17:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:17:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:17:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:17:59 INFO - Intel Iris: 00:17:59 INFO - Chipset Model: Intel Iris 00:17:59 INFO - Type: GPU 00:17:59 INFO - Bus: Built-In 00:17:59 INFO - VRAM (Dynamic, Max): 1536 MB 00:17:59 INFO - Vendor: Intel (0x8086) 00:17:59 INFO - Device ID: 0x0a2e 00:17:59 INFO - Revision ID: 0x0009 00:17:59 INFO - Displays: 00:17:59 INFO - Display: 00:17:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:17:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:17:59 INFO - Display Serial Number: 1600x1200 60 00:17:59 INFO - Main Display: Yes 00:17:59 INFO - Mirror: Off 00:17:59 INFO - Online: Yes 00:17:59 INFO - Rotation: Supported 00:17:59 INFO - Return code: 0 00:17:59 INFO - Running post-action listener: _resource_record_post_action 00:17:59 INFO - Running post-run listener: _resource_record_post_run 00:18:00 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 41367040; Write bytes: 698548224; Read time: 513; Write time: 2055 00:18:00 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 389566976; Write bytes: 384349696; Read time: 28694; Write time: 744 00:18:00 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 39544320; Write bytes: 298560000; Read time: 492; Write time: 1278 00:18:00 INFO - Running post-run listener: _upload_blobber_files 00:18:00 INFO - Blob upload gear active. 00:18:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:18:00 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 00:18:00 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'] 00:18:00 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 00:18:00 INFO - (blobuploader) - INFO - Open directory for files ... 00:18:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:18:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:18:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:18:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:18:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:18:01 INFO - (blobuploader) - INFO - Done attempting. 00:18:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:18:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:18:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:18:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:18:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:18:02 INFO - (blobuploader) - INFO - Done attempting. 00:18:02 INFO - (blobuploader) - INFO - Iteration through files over. 00:18:02 INFO - Return code: 0 00:18:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:18:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:18:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c05209826f71fa010f457a46a609817ae19eb344c8ea95df40d9613f410034bb41e0dfa6905ac69fe93fb932ae2355eaf2915c22223d42eb3a9c17983693481", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a42170ec510c4f10944999d84113fcc20a6aa3f3d10f0ba2f10a3fbcb2479f0f3e88ee776bfa720f422c8491a8fa206d72125d667cb7b6e6634225882f1bb07"} 00:18:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:18:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:18:02 INFO - Contents: 00:18:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c05209826f71fa010f457a46a609817ae19eb344c8ea95df40d9613f410034bb41e0dfa6905ac69fe93fb932ae2355eaf2915c22223d42eb3a9c17983693481", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a42170ec510c4f10944999d84113fcc20a6aa3f3d10f0ba2f10a3fbcb2479f0f3e88ee776bfa720f422c8491a8fa206d72125d667cb7b6e6634225882f1bb07"} 00:18:02 INFO - Running post-run listener: copy_logs_to_upload_dir 00:18:02 INFO - Copying logs to upload dir... 00:18:02 INFO - mkdir: /builds/slave/test/build/upload/logs 00:18:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.608352 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-04-29 00:18:02.561510) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 00:18:02.568519) ========= 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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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/8c05209826f71fa010f457a46a609817ae19eb344c8ea95df40d9613f410034bb41e0dfa6905ac69fe93fb932ae2355eaf2915c22223d42eb3a9c17983693481", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a42170ec510c4f10944999d84113fcc20a6aa3f3d10f0ba2f10a3fbcb2479f0f3e88ee776bfa720f422c8491a8fa206d72125d667cb7b6e6634225882f1bb07"} build_url:https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/E7MeFRzhRM6ViFH_OC-cSA/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/8c05209826f71fa010f457a46a609817ae19eb344c8ea95df40d9613f410034bb41e0dfa6905ac69fe93fb932ae2355eaf2915c22223d42eb3a9c17983693481", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a42170ec510c4f10944999d84113fcc20a6aa3f3d10f0ba2f10a3fbcb2479f0f3e88ee776bfa720f422c8491a8fa206d72125d667cb7b6e6634225882f1bb07"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 00:18:02.610659) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 00:18:02.610951) ========= 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.0UgLgC8T1m/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.7EzGFGnYwu/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.005114 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 00:18:02.663617) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 00:18:02.663900) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 00:18:02.664600) ========= ========= Total master_lag: 0.22 =========