builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0056 starttime: 1461024541.03 results: success (0) buildid: 20160418151628 builduid: 8c315b4aa65a4fcdaad46c949d0ca905 revision: e8ef4670ee16f419b1037b715f0d28978825439e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:01.025333) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:01.025738) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-18 17:09:01.026012) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2016-04-18 17:09:05.567007) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:05.567307) ========= 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.AE5pcWzU9i/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.npuBrcTetB/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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-18 17:09:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-04-18 17:09:05 (143 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095819 ========= master_lag: 0.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:05.954599) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-18 17:09:05.956712) ========= 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.AE5pcWzU9i/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.npuBrcTetB/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.020418 ========= master_lag: 3.70 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-18 17:09:09.675930) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 17:09:09.676301) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e8ef4670ee16f419b1037b715f0d28978825439e --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 e8ef4670ee16f419b1037b715f0d28978825439e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AE5pcWzU9i/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.npuBrcTetB/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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-18 17:09:09,764 truncating revision to first 12 chars 2016-04-18 17:09:09,765 Setting DEBUG logging. 2016-04-18 17:09:09,765 attempt 1/10 2016-04-18 17:09:09,765 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e8ef4670ee16?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 17:09:10,051 unpacking tar archive at: fx-team-e8ef4670ee16/testing/mozharness/ program finished with exit code 0 elapsedTime=0.531515 ========= master_lag: 1.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 17:09:11.339741) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:11.340078) ========= script_repo_revision: e8ef4670ee16f419b1037b715f0d28978825439e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:11.340466) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:11.340747) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 17:09:12.190796) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-18 17:09:12.191198) ========= /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.AE5pcWzU9i/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.npuBrcTetB/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:09:12 INFO - MultiFileLogger online at 20160418 17:09:12 in /builds/slave/test 17:09:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:09:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:09:12 INFO - {'append_to_log': False, 17:09:12 INFO - 'base_work_dir': '/builds/slave/test', 17:09:12 INFO - 'blob_upload_branch': 'Fx-Team', 17:09:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:09:12 INFO - 'branch': 'Fx-Team', 17:09:12 INFO - 'buildbot_json_path': 'buildprops.json', 17:09:12 INFO - 'config_files': ('talos/mac_config.py',), 17:09:12 INFO - 'default_actions': ('clobber', 17:09:12 INFO - 'read-buildbot-config', 17:09:12 INFO - 'download-and-extract', 17:09:12 INFO - 'populate-webroot', 17:09:12 INFO - 'create-virtualenv', 17:09:12 INFO - 'install', 17:09:12 INFO - 'run-tests'), 17:09:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:09:12 INFO - 'download_minidump_stackwalk': True, 17:09:12 INFO - 'download_symbols': 'ondemand', 17:09:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:09:12 INFO - 'tooltool.py': '/tools/tooltool.py', 17:09:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:09:12 INFO - '/tools/misc-python/virtualenv.py')}, 17:09:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:09:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:09:12 INFO - 'installer_path': 'installer.exe', 17:09:12 INFO - 'log_level': 'info', 17:09:12 INFO - 'log_name': 'talos', 17:09:12 INFO - 'log_to_console': True, 17:09:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:09:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:09:12 INFO - 'opt_config_files': (), 17:09:12 INFO - 'pip_index': False, 17:09:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:09:12 INFO - 'cmd': ('bash', 17:09:12 INFO - '-c', 17:09:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:09:12 INFO - 'enabled': True, 17:09:12 INFO - 'halt_on_failure': False, 17:09:12 INFO - 'name': 'check_screen_resolution'},), 17:09:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:09:12 INFO - 'cmd': ('bash', 17:09:12 INFO - '-c', 17:09:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:09:12 INFO - 'enabled': True, 17:09:12 INFO - 'halt_on_failure': False, 17:09:12 INFO - 'name': 'check_screen_resolution'},), 17:09:12 INFO - 'run_cmd_checks_enabled': True, 17:09:12 INFO - 'sps_profile': False, 17:09:12 INFO - 'sps_profile_interval': 0, 17:09:12 INFO - 'suite': 'dromaeojs', 17:09:12 INFO - 'system_bits': '32', 17:09:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:09:12 INFO - 'title': 't-yosemite-r7-0056', 17:09:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:09:12 INFO - 'use_talos_json': True, 17:09:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:09:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:09:12 INFO - 'work_dir': 'build'} 17:09:12 INFO - ##### 17:09:12 INFO - ##### Running clobber step. 17:09:12 INFO - ##### 17:09:12 INFO - Running pre-action listener: _resource_record_pre_action 17:09:12 INFO - Running main action method: clobber 17:09:12 INFO - rmtree: /builds/slave/test/build 17:09:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:09:13 INFO - Running post-action listener: _resource_record_post_action 17:09:13 INFO - ##### 17:09:13 INFO - ##### Running read-buildbot-config step. 17:09:13 INFO - ##### 17:09:13 INFO - Running pre-action listener: _resource_record_pre_action 17:09:13 INFO - Running main action method: read_buildbot_config 17:09:13 INFO - Using buildbot properties: 17:09:13 INFO - { 17:09:13 INFO - "project": "", 17:09:13 INFO - "product": "firefox", 17:09:13 INFO - "script_repo_revision": "production", 17:09:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:09:13 INFO - "repository": "", 17:09:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:09:13 INFO - "buildid": "20160418151628", 17:09:13 INFO - "pgo_build": "False", 17:09:13 INFO - "basedir": "/builds/slave/test", 17:09:13 INFO - "buildnumber": 631, 17:09:13 INFO - "slavename": "t-yosemite-r7-0056", 17:09:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:09:13 INFO - "platform": "yosemite_r7", 17:09:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:09:13 INFO - "branch": "fx-team", 17:09:13 INFO - "repo_path": "integration/fx-team", 17:09:13 INFO - "revision": "e8ef4670ee16f419b1037b715f0d28978825439e", 17:09:13 INFO - "stage_platform": "macosx64", 17:09:13 INFO - "builduid": "8c315b4aa65a4fcdaad46c949d0ca905", 17:09:13 INFO - "slavebuilddir": "test" 17:09:13 INFO - } 17:09:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 17:09:13 INFO - Running post-action listener: _resource_record_post_action 17:09:13 INFO - ##### 17:09:13 INFO - ##### Running download-and-extract step. 17:09:13 INFO - ##### 17:09:13 INFO - Running pre-action listener: _resource_record_pre_action 17:09:13 INFO - Running main action method: download_and_extract 17:09:13 INFO - mkdir: /builds/slave/test/build/tests 17:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:13 INFO - https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:09:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 17:09:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 17:09:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 17:09:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 17:09:14 INFO - Downloaded 1199 bytes. 17:09:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 17:09:14 INFO - Using the following test package requirements: 17:09:14 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 17:09:14 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 17:09:14 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 17:09:14 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'jsshell-mac.zip'], 17:09:14 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 17:09:14 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 17:09:14 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 17:09:14 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 17:09:14 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 17:09:14 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:09:14 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 17:09:14 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:14 INFO - https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:09:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:09:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:09:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:09:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:09:15 INFO - Downloaded 18038227 bytes. 17:09:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:09:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:09:18 INFO - Return code: 0 17:09:18 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:09: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')]} 17:09:18 INFO - https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:09:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:09:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:09:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:09:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:09:20 INFO - Downloaded 18038227 bytes. 17:09:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:09:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:09:23 INFO - Return code: 0 17:09: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')]} 17:09:23 INFO - https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 17:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 17:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 17:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:09:25 INFO - Downloaded 10845952 bytes. 17:09:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:09:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:09:25 INFO - Return code: 0 17:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:25 INFO - https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:09:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:09:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:09:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 17:09:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 17:09:30 INFO - Downloaded 109948184 bytes. 17:09:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:09:30 INFO - mkdir: /builds/slave/test/properties 17:09:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:09:30 INFO - Writing to file /builds/slave/test/properties/build_url 17:09:30 INFO - Contents: 17:09:30 INFO - build_url:https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:09:31 INFO - Running post-action listener: _resource_record_post_action 17:09:31 INFO - Running post-action listener: set_extra_try_arguments 17:09:31 INFO - ##### 17:09:31 INFO - ##### Running populate-webroot step. 17:09:31 INFO - ##### 17:09:31 INFO - Running pre-action listener: _resource_record_pre_action 17:09:31 INFO - Running main action method: populate_webroot 17:09:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:09:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:09:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:09:31 INFO - '--tppagecycles', 17:09:31 INFO - '7'], 17:09:31 INFO - 'tests': ['tsvgm']}}, 17:09:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:09:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:09:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:09:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:09:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['damp', 'tps']}, 17:09:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'tests': ['damp', 'tps']}, 17:09:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['dromaeo_dom']}, 17:09:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:09:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['a11yr', 17:09:31 INFO - 'ts_paint', 17:09:31 INFO - 'tpaint', 17:09:31 INFO - 'sessionrestore', 17:09:31 INFO - 'sessionrestore_no_auto_restore', 17:09:31 INFO - 'tabpaint']}, 17:09:31 INFO - 'other-e10s': {'tests': ['a11yr', 17:09:31 INFO - 'ts_paint', 17:09:31 INFO - 'tpaint', 17:09:31 INFO - 'sessionrestore', 17:09:31 INFO - 'sessionrestore_no_auto_restore', 17:09:31 INFO - 'tabpaint']}, 17:09:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:09:31 INFO - 'ts_paint', 17:09:31 INFO - 'tpaint', 17:09:31 INFO - 'sessionrestore', 17:09:31 INFO - 'sessionrestore_no_auto_restore', 17:09:31 INFO - 'tabpaint']}, 17:09:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:09:31 INFO - 'ts_paint', 17:09:31 INFO - 'tpaint', 17:09:31 INFO - 'sessionrestore', 17:09:31 INFO - 'sessionrestore_no_auto_restore', 17:09:31 INFO - 'tabpaint']}, 17:09:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['a11yr', 17:09:31 INFO - 'ts_paint', 17:09:31 INFO - 'tpaint', 17:09:31 INFO - 'sessionrestore', 17:09:31 INFO - 'sessionrestore_no_auto_restore', 17:09:31 INFO - 'tabpaint']}, 17:09:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['a11yr', 17:09:31 INFO - 'ts_paint', 17:09:31 INFO - 'tpaint', 17:09:31 INFO - 'sessionrestore', 17:09:31 INFO - 'sessionrestore_no_auto_restore', 17:09:31 INFO - 'tabpaint']}, 17:09:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['tsvgx', 17:09:31 INFO - 'tsvgr_opacity', 17:09:31 INFO - 'tart', 17:09:31 INFO - 'tscrollx', 17:09:31 INFO - 'cart']}, 17:09:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:09:31 INFO - 'tsvgr_opacity', 17:09:31 INFO - 'tart', 17:09:31 INFO - 'tscrollx', 17:09:31 INFO - 'cart']}, 17:09:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'talos_options': ['--disable-e10s'], 17:09:31 INFO - 'tests': ['tp5o']}, 17:09:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'tests': ['tp5o']}, 17:09:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'talos_options': ['--disable-e10s', 17:09:31 INFO - '--xperf_path', 17:09:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:31 INFO - 'tests': ['tp5n']}, 17:09:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:09:31 INFO - 'talos_options': ['--xperf_path', 17:09:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:31 INFO - 'tests': ['tp5n']}}, 17:09:31 INFO - 'talos.zip': {'path': '', 17:09:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:09:31 INFO - Running post-action listener: _resource_record_post_action 17:09:31 INFO - ##### 17:09:31 INFO - ##### Running create-virtualenv step. 17:09:31 INFO - ##### 17:09:31 INFO - Running pre-action listener: _resource_record_pre_action 17:09:31 INFO - Running main action method: create_virtualenv 17:09:31 INFO - Creating virtualenv /builds/slave/test/build/venv 17:09:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:09:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:09:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:09:32 INFO - Using real prefix '/tools/python27' 17:09:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:09:32 INFO - Installing distribute.............................................................................................................................................................................................done. 17:09:35 INFO - Installing pip.................done. 17:09:35 INFO - Return code: 0 17:09:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:35 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:35 INFO - 'HOME': '/Users/cltbld', 17:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:35 INFO - 'LOGNAME': 'cltbld', 17:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:35 INFO - 'MOZ_NO_REMOTE': '1', 17:09:35 INFO - 'NO_EM_RESTART': '1', 17:09:35 INFO - 'PAGER': '/bin/cat', 17:09:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:35 INFO - 'PWD': '/builds/slave/test', 17:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:35 INFO - 'SHELL': '/bin/bash', 17:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:35 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:35 INFO - 'USER': 'cltbld', 17:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:35 INFO - 'XPC_FLAGS': '0x0', 17:09:35 INFO - 'XPC_SERVICE_NAME': '0', 17:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:09:35 INFO - Cleaning up... 17:09:35 INFO - Return code: 0 17:09:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:35 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:09:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:35 INFO - 'HOME': '/Users/cltbld', 17:09:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:35 INFO - 'LOGNAME': 'cltbld', 17:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:35 INFO - 'MOZ_NO_REMOTE': '1', 17:09:35 INFO - 'NO_EM_RESTART': '1', 17:09:35 INFO - 'PAGER': '/bin/cat', 17:09:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:35 INFO - 'PWD': '/builds/slave/test', 17:09:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:35 INFO - 'SHELL': '/bin/bash', 17:09:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:35 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:35 INFO - 'USER': 'cltbld', 17:09:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:35 INFO - 'XPC_FLAGS': '0x0', 17:09:35 INFO - 'XPC_SERVICE_NAME': '0', 17:09:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:36 INFO - Downloading/unpacking psutil>=0.7.1 17:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:09:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:38 INFO - Installing collected packages: psutil 17:09:38 INFO - Running setup.py install for psutil 17:09:38 INFO - building 'psutil._psutil_osx' extension 17:09:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:09:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:09:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:09:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:09:39 INFO - building 'psutil._psutil_posix' extension 17:09:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:09:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:09:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:09:39 INFO - ^ 17:09:39 INFO - 1 warning generated. 17:09:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:09:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:09:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:09:39 INFO - Successfully installed psutil 17:09:39 INFO - Cleaning up... 17:09:39 INFO - Return code: 0 17:09:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:39 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:39 INFO - 'HOME': '/Users/cltbld', 17:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:39 INFO - 'LOGNAME': 'cltbld', 17:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:39 INFO - 'MOZ_NO_REMOTE': '1', 17:09:39 INFO - 'NO_EM_RESTART': '1', 17:09:39 INFO - 'PAGER': '/bin/cat', 17:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:39 INFO - 'PWD': '/builds/slave/test', 17:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:39 INFO - 'SHELL': '/bin/bash', 17:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:39 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:39 INFO - 'USER': 'cltbld', 17:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:39 INFO - 'XPC_FLAGS': '0x0', 17:09:39 INFO - 'XPC_SERVICE_NAME': '0', 17:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:09:42 INFO - Installing collected packages: mozsystemmonitor 17:09:42 INFO - Running setup.py install for mozsystemmonitor 17:09:42 INFO - Successfully installed mozsystemmonitor 17:09:42 INFO - Cleaning up... 17:09:42 INFO - Return code: 0 17:09:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:42 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:09:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:42 INFO - 'HOME': '/Users/cltbld', 17:09:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:42 INFO - 'LOGNAME': 'cltbld', 17:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:42 INFO - 'MOZ_NO_REMOTE': '1', 17:09:42 INFO - 'NO_EM_RESTART': '1', 17:09:42 INFO - 'PAGER': '/bin/cat', 17:09:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:42 INFO - 'PWD': '/builds/slave/test', 17:09:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:42 INFO - 'SHELL': '/bin/bash', 17:09:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:42 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:42 INFO - 'USER': 'cltbld', 17:09:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:42 INFO - 'XPC_FLAGS': '0x0', 17:09:42 INFO - 'XPC_SERVICE_NAME': '0', 17:09:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:43 INFO - Downloading/unpacking blobuploader==1.2.4 17:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:45 INFO - Downloading blobuploader-1.2.4.tar.gz 17:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:09:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:09: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 17:09: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 17:09: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 17:09: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 17:09: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 17:09: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 17:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:09:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:46 INFO - Downloading docopt-0.6.1.tar.gz 17:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:09:46 INFO - Installing collected packages: blobuploader, requests, docopt 17:09:46 INFO - Running setup.py install for blobuploader 17:09:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:09:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:09:46 INFO - Running setup.py install for requests 17:09:47 INFO - Running setup.py install for docopt 17:09:47 INFO - Successfully installed blobuploader requests docopt 17:09:47 INFO - Cleaning up... 17:09:47 INFO - Return code: 0 17:09:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:47 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:47 INFO - 'HOME': '/Users/cltbld', 17:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:47 INFO - 'LOGNAME': 'cltbld', 17:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:47 INFO - 'MOZ_NO_REMOTE': '1', 17:09:47 INFO - 'NO_EM_RESTART': '1', 17:09:47 INFO - 'PAGER': '/bin/cat', 17:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:47 INFO - 'PWD': '/builds/slave/test', 17:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:47 INFO - 'SHELL': '/bin/bash', 17:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:47 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:47 INFO - 'USER': 'cltbld', 17:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:47 INFO - 'XPC_FLAGS': '0x0', 17:09:47 INFO - 'XPC_SERVICE_NAME': '0', 17:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:47 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-VTwsps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:47 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-dWlTDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Of8USZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-N59Vof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-8rDDYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-HAHwvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-AQzAg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-9AGVZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Yo0AgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-hsL0Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:48 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-SqYbD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:49 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-0MznlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:49 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-m3tapa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:49 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-UoxDvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:49 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-cAnYGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:49 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-ru6CqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:49 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-FvFOka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:09:49 INFO - Running setup.py install for manifestparser 17:09:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:09:49 INFO - Running setup.py install for mozcrash 17:09:49 INFO - Running setup.py install for mozdebug 17:09:50 INFO - Running setup.py install for mozdevice 17:09:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Running setup.py install for mozfile 17:09:50 INFO - Running setup.py install for mozhttpd 17:09:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Running setup.py install for mozinfo 17:09:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Running setup.py install for mozInstall 17:09:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:09:50 INFO - Running setup.py install for mozleak 17:09:50 INFO - Running setup.py install for mozlog 17:09:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:09:51 INFO - Running setup.py install for moznetwork 17:09:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:09:51 INFO - Running setup.py install for mozprocess 17:09:51 INFO - Running setup.py install for mozprofile 17:09:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:09:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:09:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:09:51 INFO - Running setup.py install for mozrunner 17:09:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:09:51 INFO - Running setup.py install for mozscreenshot 17:09:51 INFO - Running setup.py install for moztest 17:09:52 INFO - Running setup.py install for mozversion 17:09:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:09:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:09:52 INFO - Cleaning up... 17:09:52 INFO - Return code: 0 17:09:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:52 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:52 INFO - 'HOME': '/Users/cltbld', 17:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:52 INFO - 'LOGNAME': 'cltbld', 17:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:52 INFO - 'MOZ_NO_REMOTE': '1', 17:09:52 INFO - 'NO_EM_RESTART': '1', 17:09:52 INFO - 'PAGER': '/bin/cat', 17:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:52 INFO - 'PWD': '/builds/slave/test', 17:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:52 INFO - 'SHELL': '/bin/bash', 17:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:52 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:52 INFO - 'USER': 'cltbld', 17:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:52 INFO - 'XPC_FLAGS': '0x0', 17:09:52 INFO - 'XPC_SERVICE_NAME': '0', 17:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:09:52 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-0PBpAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:09:52 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-WdtX8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:09:52 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-kMW1dH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:09:52 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-1L5v4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:09:52 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-yumVt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-ZEG4yF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Jzj9hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-rKJ9tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-IRKS3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-D1n7CM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-3j88XF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-69RbCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Wa0qLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:09:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:09:53 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-sUELfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-7Bi33y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-ObP0K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:09:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Uzpbr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:09:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:57 INFO - Downloading blessings-1.6.tar.gz 17:09:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:09:57 INFO - Installing collected packages: blessings 17:09:57 INFO - Running setup.py install for blessings 17:09:57 INFO - Successfully installed blessings 17:09:57 INFO - Cleaning up... 17:09:57 INFO - Return code: 0 17:09:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:09:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:09:57 INFO - Reading from file tmpfile_stdout 17:09:57 INFO - Current package versions: 17:09:57 INFO - blessings == 1.6 17:09:57 INFO - blobuploader == 1.2.4 17:09:57 INFO - docopt == 0.6.1 17:09:57 INFO - manifestparser == 1.1 17:09:57 INFO - mozInstall == 1.12 17:09:57 INFO - mozcrash == 0.17 17:09:57 INFO - mozdebug == 0.1 17:09:57 INFO - mozdevice == 0.48 17:09:57 INFO - mozfile == 1.2 17:09:57 INFO - mozhttpd == 0.7 17:09:57 INFO - mozinfo == 0.9 17:09:57 INFO - mozleak == 0.1 17:09:57 INFO - mozlog == 3.1 17:09:57 INFO - moznetwork == 0.27 17:09:57 INFO - mozprocess == 0.22 17:09:57 INFO - mozprofile == 0.28 17:09:57 INFO - mozrunner == 6.11 17:09:57 INFO - mozscreenshot == 0.1 17:09:57 INFO - mozsystemmonitor == 0.0 17:09:57 INFO - moztest == 0.7 17:09:57 INFO - mozversion == 1.4 17:09:57 INFO - psutil == 3.1.1 17:09:57 INFO - requests == 1.2.3 17:09:57 INFO - wsgiref == 0.1.2 17:09:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:57 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:57 INFO - 'HOME': '/Users/cltbld', 17:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:57 INFO - 'LOGNAME': 'cltbld', 17:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:57 INFO - 'MOZ_NO_REMOTE': '1', 17:09:57 INFO - 'NO_EM_RESTART': '1', 17:09:57 INFO - 'PAGER': '/bin/cat', 17:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:57 INFO - 'PWD': '/builds/slave/test', 17:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:57 INFO - 'SHELL': '/bin/bash', 17:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:57 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:57 INFO - 'USER': 'cltbld', 17:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:57 INFO - 'XPC_FLAGS': '0x0', 17:09:57 INFO - 'XPC_SERVICE_NAME': '0', 17:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:09:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:09:57 INFO - Cleaning up... 17:09:58 INFO - Return code: 0 17:09:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:09: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')]} 17:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09: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')]} 17:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:09:58 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:09:58 INFO - 'HOME': '/Users/cltbld', 17:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:09:58 INFO - 'LOGNAME': 'cltbld', 17:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:58 INFO - 'MOZ_NO_REMOTE': '1', 17:09:58 INFO - 'NO_EM_RESTART': '1', 17:09:58 INFO - 'PAGER': '/bin/cat', 17:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:58 INFO - 'PWD': '/builds/slave/test', 17:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:09:58 INFO - 'SHELL': '/bin/bash', 17:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:09:58 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:09:58 INFO - 'USER': 'cltbld', 17:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:58 INFO - 'XPC_FLAGS': '0x0', 17:09:58 INFO - 'XPC_SERVICE_NAME': '0', 17:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:58 INFO - Downloading/unpacking jsonschema 17:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:10:00 INFO - Downloading/unpacking functools32 (from jsonschema) 17:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:01 INFO - Downloading functools32-3.2.3-2.tar.gz 17:10:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:10:01 INFO - warning: no files found matching '*.txt' 17:10:01 INFO - no previously-included directories found matching 'build' 17:10:01 INFO - no previously-included directories found matching 'dist' 17:10:01 INFO - no previously-included directories found matching '.git*' 17:10:01 INFO - Installing collected packages: jsonschema, functools32 17:10:01 INFO - Running setup.py install for functools32 17:10:01 INFO - warning: no files found matching '*.txt' 17:10:01 INFO - no previously-included directories found matching 'build' 17:10:01 INFO - no previously-included directories found matching 'dist' 17:10:01 INFO - no previously-included directories found matching '.git*' 17:10:01 INFO - Successfully installed jsonschema functools32 17:10:01 INFO - Cleaning up... 17:10:01 INFO - Return code: 0 17:10:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:10:01 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 0x10703ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10704b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4a14b2750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, '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.AE5pcWzU9i/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.npuBrcTetB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:10:01 INFO - 'HOME': '/Users/cltbld', 17:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:10:01 INFO - 'LOGNAME': 'cltbld', 17:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:01 INFO - 'MOZ_NO_REMOTE': '1', 17:10:01 INFO - 'NO_EM_RESTART': '1', 17:10:01 INFO - 'PAGER': '/bin/cat', 17:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:01 INFO - 'PWD': '/builds/slave/test', 17:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:10:01 INFO - 'SHELL': '/bin/bash', 17:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:10:01 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:10:01 INFO - 'USER': 'cltbld', 17:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:01 INFO - 'XPC_FLAGS': '0x0', 17:10:01 INFO - 'XPC_SERVICE_NAME': '0', 17:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:01 INFO - Downloading/unpacking flake8 17:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:10:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:10:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:10:05 INFO - no previously-included directories found matching 'docs/_build' 17:10:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:10:05 INFO - Running setup.py install for pep8 17:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:10:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:10:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:10:05 INFO - no previously-included directories found matching 'docs/_build' 17:10:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:10:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:10:05 INFO - Cleaning up... 17:10:05 INFO - Return code: 0 17:10:05 INFO - Running post-action listener: _resource_record_post_action 17:10:05 INFO - Running post-action listener: _start_resource_monitoring 17:10:05 INFO - Starting resource monitoring. 17:10:05 INFO - ##### 17:10:05 INFO - ##### Running install step. 17:10:05 INFO - ##### 17:10:05 INFO - Running pre-action listener: _resource_record_pre_action 17:10:05 INFO - Running main action method: install 17:10:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:06 INFO - Reading from file tmpfile_stdout 17:10:06 INFO - Detecting whether we're running mozinstall >=1.0... 17:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:10:06 INFO - Reading from file tmpfile_stdout 17:10:06 INFO - Output received: 17:10:06 INFO - Usage: mozinstall [options] installer 17:10:06 INFO - Options: 17:10:06 INFO - -h, --help show this help message and exit 17:10:06 INFO - -d DEST, --destination=DEST 17:10:06 INFO - Directory to install application into. [default: 17:10:06 INFO - "/builds/slave/test"] 17:10:06 INFO - --app=APP Application being installed. [default: firefox] 17:10:06 INFO - mkdir: /builds/slave/test/build/application 17:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:10:39 INFO - Reading from file tmpfile_stdout 17:10:39 INFO - Output received: 17:10:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:10:39 INFO - Running post-action listener: _resource_record_post_action 17:10:39 INFO - ##### 17:10:39 INFO - ##### Running run-tests step. 17:10:39 INFO - ##### 17:10:39 INFO - Running pre-action listener: _resource_record_pre_action 17:10:39 INFO - Running main action method: run_tests 17:10:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:10:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:10:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:10:39 INFO - 2016-04-18 17:10:39.712 screenresolution[2541:73785] starting screenresolution argv=screenresolution get 17:10:39 INFO - 2016-04-18 17:10:39.715 screenresolution[2541:73785] Display 0: 1600x1200x32@60 17:10:39 INFO - 2016-04-18 17:10:39.721 screenresolution[2542:73787] starting screenresolution argv=screenresolution list 17:10:39 INFO - Available Modes on Display 0 17:10:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:10:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:10:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:10:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:10:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:10:39 INFO - Intel Iris: 17:10:39 INFO - Chipset Model: Intel Iris 17:10:39 INFO - Type: GPU 17:10:39 INFO - Bus: Built-In 17:10:39 INFO - VRAM (Dynamic, Max): 1536 MB 17:10:39 INFO - Vendor: Intel (0x8086) 17:10:39 INFO - Device ID: 0x0a2e 17:10:39 INFO - Revision ID: 0x0009 17:10:39 INFO - Displays: 17:10:39 INFO - Display: 17:10:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:10:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:10:39 INFO - Display Serial Number: 1600x1200 60 17:10:39 INFO - Main Display: Yes 17:10:39 INFO - Mirror: Off 17:10:39 INFO - Online: Yes 17:10:39 INFO - Rotation: Supported 17:10:39 INFO - Return code: 0 17:10:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:10:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:10:39 INFO - Python 2.7.3 17:10:39 INFO - Return code: 0 17:10:39 INFO - grabbing minidump binary from tooltool 17:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:10:39 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 0x106edae00>, '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 0x106d03db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ed5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:10:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:10:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:10:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:10:40 INFO - Return code: 0 17:10:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:10:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:10:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:10:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:10:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:10:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:10:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:10:41 INFO - Return code: 0 17:10:41 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/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:10:41 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/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AE5pcWzU9i/Render', 17:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:10:41 INFO - 'HOME': '/Users/cltbld', 17:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:10:41 INFO - 'LOGNAME': 'cltbld', 17:10:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:10:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:41 INFO - 'MOZ_NO_REMOTE': '1', 17:10:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:10:41 INFO - 'NO_EM_RESTART': '1', 17:10:41 INFO - 'PAGER': '/bin/cat', 17:10:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:41 INFO - 'PWD': '/builds/slave/test', 17:10:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:10:41 INFO - 'SHELL': '/bin/bash', 17:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.npuBrcTetB/Listeners', 17:10:41 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 17:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:10:41 INFO - 'USER': 'cltbld', 17:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:41 INFO - 'XPC_FLAGS': '0x0', 17:10:41 INFO - 'XPC_SERVICE_NAME': '0', 17:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:10:41 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/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:10:41 INFO - mozversion application_buildid: 20160418151628 17:10:41 INFO - mozversion application_changeset: e8ef4670ee16f419b1037b715f0d28978825439e 17:10:41 INFO - mozversion application_display_name: Nightly 17:10:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:10:41 INFO - mozversion application_name: Firefox 17:10:41 INFO - mozversion application_remotingname: firefox 17:10:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:10:41 INFO - mozversion application_vendor: Mozilla 17:10:41 INFO - mozversion application_version: 48.0a1 17:10:41 INFO - mozversion platform_buildid: 20160418151628 17:10:41 INFO - mozversion platform_changeset: e8ef4670ee16f419b1037b715f0d28978825439e 17:10:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:10:41 INFO - mozversion platform_version: 48.0a1 17:10:41 INFO - using testdate: 1461024641 17:10:41 INFO - actual date: 1461024641 17:10:41 INFO - starting webserver on 'localhost:53976' 17:10:41 INFO - SUITE-START | Running 2 tests 17:10:41 INFO - TEST-START | dromaeo_css 17:10:41 INFO - operating with platform_type : mac_ 17:10:41 INFO - Initialising browser for dromaeo_css test... 17:10:41 INFO - TEST-INFO | started process 2560 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpuTBtQt/profile http://localhost:53976/getInfo.html) 17:10:43 INFO - PROCESS | 2560 | __metrics Screen width/height:1600/1200 17:10:43 INFO - PROCESS | 2560 | colorDepth:24 17:10:43 INFO - PROCESS | 2560 | Browser inner width/height: 1024/689 17:10:43 INFO - PROCESS | 2560 | __metrics 17:10:43 INFO - PROCESS | 2560 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:10:43 ERROR - PROCESS | 2560 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:10:44 INFO - TEST-INFO | 2560: exit 0 17:10:44 INFO - Browser initialized. 17:10:44 INFO - Running cycle 1/1 for dromaeo_css test... 17:10:44 INFO - TEST-INFO | started process 2562 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpuTBtQt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:56 INFO - PROCESS | 2562 | RSS: Main: 237314048 17:10:56 INFO - PROCESS | 2562 | 17:11:46 INFO - PROCESS | 2562 | 1461024706942 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:13:58 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:53976/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53976/tests/dromaeo/cssquery-ext.html) 17:13:59 INFO - PROCESS | 2562 | RSS: Main: 353689600 17:13:59 INFO - PROCESS | 2562 | 17:17:02 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:53976/tests/dromaeo/cssquery-ext.html (next: http://localhost:53976/tests/dromaeo/cssquery-jquery.html) 17:17:02 INFO - PROCESS | 2562 | RSS: Main: 374771712 17:17:02 INFO - PROCESS | 2562 | 17:20:04 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:53976/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53976/tests/dromaeo/cssquery-mootools.html) 17:20:05 INFO - PROCESS | 2562 | RSS: Main: 373219328 17:20:05 INFO - PROCESS | 2562 | 17:23:07 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:53976/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53976/tests/dromaeo/cssquery-prototype.html) 17:23:08 INFO - PROCESS | 2562 | RSS: Main: 374861824 17:23:08 INFO - PROCESS | 2562 | 17:26:11 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:53976/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53976/tests/dromaeo/cssquery-yui.html) 17:26:12 INFO - PROCESS | 2562 | RSS: Main: 347095040 17:26:12 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | Cycle 1(1): loaded http://localhost:53976/tests/dromaeo/cssquery-yui.html (next: http://localhost:53976/tests/dromaeo/cssquery-dojo.html) 17:29:15 INFO - PROCESS | 2562 | RSS: Main: 380588032 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | __start_tp_report 17:29:15 INFO - PROCESS | 2562 | _x_x_mozilla_page_load 17:29:15 INFO - PROCESS | 2562 | _x_x_mozilla_page_load_details 17:29:15 INFO - PROCESS | 2562 | |i|pagename|runs| 17:29:15 INFO - PROCESS | 2562 | |0;dojo.html;2979.9255018624544;3384.932301353973;3391.9660803391976;3455.3434847378994;3476.72186225102;5652.406497317784;5727.341355744085;5750.971245143774;5751.4248575142465;5761.567882408825;5748.367679555246;5769.16347129666;5847.210626565412;5889.705514724268;5909.586328956974;5157.020166168435;5183.922241166376;5211.817586384477;5223;5324.946750532503;5328.387235467932;5329.413764485904;5330.466953304677;5351.7056561889085;5393.865153371177;5178.223266510035;5272.894542109175;5278.340207474066;5293.126634105387;5294.126469132589;5256.632035757499;5280.6039547034125;5291.947080529184;5295.761690723926;5295.947040529622;4611.976940115278;4927.768057985503;5231.2937753403185;5284.81503147388;5307.336582927134;5628.54971602275;5671.291088613923;5699.145128230757;5714.342850572154;5742.684152371659;6249.9062514062325;6284.7486100555925;6296.181496405484;6305.14880491137;6328;6138.357744607992;6224.253089629228;6269.028300613412;6333.80998570039;6341.207349081365;5803.400864357385;5819.476247137778;5844.298684157929;5844.47399734026;5858.974679431082;6331.905021424683;6334.176557047646;6339.809805705836;6342.175517182736;6350.841228969312;6096.481799047131;6104.913929256131;6122.448979591858;6247.5939063960695;6251.6561589112125;6273.592216505913;6288.088227207029;6291.937080629226;6292.528060395489;6318.71565779541;6108.786192483241;6251.3123556408755;6251.937480625226;6267.435930766162;6276.686165691697;6147.66187859672;6176.629402235881;6303.6532990685955;6309.589876658094;6316.62100273985;4931.4821943696115;4962.503749625009;4974.651774375831;4975;4997.725125118153;4357.912841743211;4483;4485.035717320791;4489;4491.977540112344;5595.07681232592;5623.100303951348;5623.409541998031;5642.04085305499;5646.858828529237;6218.160548325918;6227.501799856001;6228.128062071313;6237.781677641352;6247.843803904848;5642.830715078554;5648.124540696199;5753.108268218432;5795.623284486495;5808.128780682931;4576.794044268016;5327.653702509325;5440.211169380497;5442.755076021588;5474.507294343528;6405.19935008124;6408.1349017881985;6413.0380442933;6414.358564143548;6418.326075762071;6174.783882564268;6226.844328891814;6297.370262973666;6317.115603815561;6345.96827015862;6428.164338635947;6440.227172739301;6442.35576442352;6445.774397896051;6448.1295025171;6263.248410190807;6270.2475702915945;6272.4982001440685;6282.994720844643;6283.246010478589;6136.73353236607;6212.720427580679;6213.627182369072;6240.687965601793;6284.905726414199;6168.506519478341;6171.6297022178815;6196;6205.162303089206;6208.844778880383;4990.326305948651;6135.447809697149;6152.630842149485;6172.413620706206;6184.443400094013;5980.910286345621;6051.364606716407;6055.6063855849225;6055.757769689163;6100.176476175837;6115.379270641068;6131.736526946108;6153.384661533811;6171.166892469639;6178.567500275118;5774.249347584787;5898.174255604133;5986.730597123053;6028.585177578402;6094.481969032505 17:29:15 INFO - PROCESS | 2562 | |1;ext.html;476.51157563497424;503.94708555601636;508.94147173075095;533.3999250842802;540.240961449164;30383.24041898951;31006;31127.99999999997;38724;40144.19711605766;42634;43354.783226083906;43373.56626433735;43386.78306608462;43429.565704342946;9503.429794212352;9702.126808587225;9718.514074296292;9731.513424328756;9733.610655573768;8511;8831.911680883173;9494.38286511378;11564.132690048238;11967.70080747984;11075.612353567627;11591.304521728724;11607.955284024436;11668;11674.299542027506;8530.445521041112;11507.3095614263;11638.592649257236;11655.475503602274;11673.357965311905;9618;11879.465424055938;11884.821727674005;11887.821682674767;11891.28652280866;39224.215515689815;39864.800675996434;39894.401583976265;39903.80288591347;39914.60085399138;3095.5356696495705;3132.8746850125967;3180.2685382361956;3287.178205448638;3375.9156021099425;22727;28384.574231386545;28972.84108635652;29004.70995290041;29529.571033924516;2676.411189538318;2676.973230267692;2685.9194224173307;2707.756301932816;2790.6372171617763;387935.1206487927;447774;467934;473181;474383.62808186084;35513.64486355155;65067.67466162639;65657.67171164209;65687;65810.99999999904;35539.289214216085;35766.64233357633;35784;35793.8210308952;35867.10332241715;14944.70110597782;15182.924085379502;15221.923890380478;15226.162561059247;15256.923715381574;11537.769244615229;11863.762724745457;12770.872291277154;12827.166234194747;12850;8001.199880011952;8135.959320203361;8144.389170812213;8212.46618969765;8216.301614362847;3293.5389045533643;3331.7334613230882;3337.466005439118;3343.6990670839255;3360.6471320510996;953.756792018031;998.3111652959435;1088.7278180454887;1088.8911108889047;1110.4225802582773;29112.417751644847;29565.113046608636;30116.397672046438;30878.24182808606;30908.69091309058;1095.8411030400548;1111.8832522585012;1130.598637483698;1182.8403165572709;1288.2785640041607;13326.666833329244;15636.296366663528;15680.76478852818;15843.287052082684;16038.839611603968;282016;284166;284951;638887;641730;61483;62574;62598.0610290846;62856;62888.68555657192;1313.4352228541627;1436.7054753775242;1481.3408033425023;1484.0724547158027;1485.3093311609937;24993.876500257553;28901;29315.560266595592;29796.95710650117;30039.54940675848;49198.26202606892;49739;49889.75055124701;49912.50087499079;49934.75032624813;411201;423515;423560;424392;426369;22895;23782.643260350764;27121.593176102768;27636.927230457393;27727.44545109127;43748.34377484277;43763.78118109389;44122.33816492691;45087;45321.600879933474;34861;36625.53497860056;36928.26143477169;37355.6264437363;37552;35703.642963571074;35895;36727.63272367349;36831;37379.25241495209;37250.8137459311;37852.86441406762;37895;38792.6120738789;38814.6118538811;30417.695823041486;33532.83233583817;33716.15709607279;33835.83082084672;33895.813646522256;24722;24833.503329932937;24967.12615058464;25081.372965676004;25432 17:29:15 INFO - PROCESS | 2562 | |2;jquery.html;396.69256326347084;398.4541178585338;401.8312308830291;403.4895856741223;413.62153629429076;14877.479288224915;17817.732734008983;18541;18618.53453663659;18670.533236669075;16096.919515402407;16812.243449044792;20133.59732805343;20149.17911492392;20654.38036858893;5916.230889984317;6058.636481811083;6059.182010428602;6061.181740465025;6090.208272924514;6047;6071;6078;6093.025115981444;6147.631142131464;5811.389804070575;5995.550333724988;6036.818895433122;6043.123747056674;6043.486303664194;5934.376890426486;5994.07091900756;6004.159417681528;6013.789517366893;6023.488003519684;5135.3324067870935;5314.60140489461;5337.653052551572;5340.305760251143;5344.198370244456;25515.234542963743;26620.802063906976;26988.05541806047;27005;27046.1886143416;2101.3905967269475;2241.8542794718455;2506.9749302506925;2721.7958653101095;2721.9520484613568;13875.722485550232;15035.54893353201;15153.924230378887;15215.163166025864;15347;1753.7194048952233;1823.5896923192308;1824.224704500582;1840.0339821593636;1873.9063046847739;118267.408662957;118476;119388.8061119395;119451;121560;15977.920110399373;17592.504637105732;19385.321513746952;20800.51181574632;20920.581588368146;35082.12294692653;35092.64907350945;35106;35118.82440587781;35205.82397088049;9588.328816982745;10125.594976201017;10536.578536858593;10855.457227138611;11084.667459976214;8849.115088491099;8873.378863479495;8892.199702026848;9011;9028.8645670315;9159.89322455839;9160.22138118254;9251.814963700688;9339.579718912666;9346.48594327305;3063.3566950940553;3271.81626725489;3594.9101272468397;3682.4476328550395;3700.148965737895;843.8945131858518;944.1596978688972;956.53129966316;1020.7754294055296;1024.3802499487851;16468.258928348252;19775.802241977683;20981.89509052445;21063.473413164793;21225.469363266042;886.4548302793864;935.9064093590723;956.3353469338742;987.7234374375187;996.4121168510469;8992.46045237288;10514.633097697251;10912.672619821418;11213.66359009231;11216.719582010515;586365;590541;597586;603241;611752.9412352999;130991;132965.6924015329;133808.66191338157;134070.3248546265;135735.96396054068;1035.321864178964;1039.4127318065268;1069.155367259856;1093.5352475198008;1096.0847692177122;18439.446816595522;18527.351542695942;18597.44207673772;18621.255149793855;18647.81352186461;27473.587896181172;30969.19977403506;45089.54910450854;46256;47392.52607473881;68422;68828.65585672039;69230.30769692239;69607.60784784175;70701.64649176926;6873;7369.115706115301;7422.777316680508;8025;8524.147585241426;11924.523019079486;11970.76058478843;12828.679283017998;12913.483460661468;12956.611301660589;9429.669961551586;10233.362661974048;10809.35143891369;11066.336019838835;11185.66443006711;8426.494410335154;10480.056794888496;10577.206709496972;10906.509207085859;11066.225364224427;8867.778305542413;10359.585616575252;11455.541778328774;11466.197366184288;11667.241629294409;8130.959345203236;8174.83650327002;8368.581570921551;8388.454750441202;8643.394962352826;9190.540472976458;10246.487675616338;11406.82889756671;11562.76874462523;11573.421328933688 17:29:15 INFO - PROCESS | 2562 | |3;mootools.html;1221.316063004718;1565.1548163991445;1612.7500237463194;1898.1173754204292;2028.9695654565185;7057.435405167587;7900.565468899208;8834.293256539477;8835.646574137036;8837.955810220956;7198.820029499265;8423.199796019384;8657.523836189008;8692.34807389445;8701.347398945072;2140.0262880389387;2364.574376612209;2431.9270421887327;2464.852108873464;2466.3834041489627;2422.6366045093287;2439.329184474275;2443.7067551893797;2447.339218411028;2449.069353645612;2375.869327187004;2383.8212134089927;2399.6520504526834;2410.8432951858163;2416.214730212679;2108.5888251790907;2328.6507023946374;2389.665446837444;2395.3293077938206;2403.038784486202;2414.8913298901593;2435.780779729833;2437.0617312334703;2442.230697330353;2450.803935685141;8634.179752923525;8682.696105636336;8698.652053917836;8705;8710.433821801626;5145.871353216162;5321.388040375329;5324.653897496689;5328.813491527778;5351.036813373591;3356.7818091824197;3447.620761716209;3561.216532362876;3573.624769399202;3589.74871758977;1025.1235193909174;1025.610268098118;1034.9172066234682;1036.5387402605843;1042.9478526073742;11549.999999999916;11618.825717614243;11627.534898604046;11649.650510484698;11654.941725291405;4710.599599034121;4729.069756196634;4812.302216178635;4891.461939186686;4928.186849169919;9845.950770246103;9956.601735930626;9969.850452243221;9982.850257246148;9992;5377.973110134424;5384.030874442638;5397.3523177218985;5409.486098820606;5437.075697131497;3678.668919797231;4257.552956939477;4427.026054268056;4552.544745525487;4967.006598680278;3767.6609105180664;3849.3071247175776;3859.5754467008605;4060.411240370189;4078.5513593504693;1343.7312537492542;1346.077936613423;1346.6094832498466;1363.849976502584;1370.8012338210904;881.7927786970057;906.954652267384;923.3674932671141;926.8517037274139;941.9529023548795;5261.868453288622;5680.920625081221;5876;5984.940150598438;6009.579329446984;936.8079543693526;971.7667759737612;980.1032055669126;980.9950950245203;982.8378317577663;3707.332680117551;3890.9610903891166;3947.2697550953167;3987.900302492461;4023.8994025149605;10275.332103413255;10673.35959842412;10774.569017239222;10787.921207879306;10800.297980631234;10819.621313253998;10850.348979061282;10930.234883558234;10938.070264027489;10961.83557246642;6565.967170164118;8288.171182881664;8317.251447369645;8328.750137495885;8330.416870819106;5770.596058276048;5772.5381969441505;5790.449907258972;5981.611195272293;6113.694315284129;9748.317617766688;9805;9963.850542242017;10014.899851001397;10089.29374943747;9810.626512288429;10080.899191007997;10113.797724045626;10215.795684086128;10345.741356465847;7513.436492262993;7528.698852045857;7567.243275672388;7678.616069196406;7683.154852966281;7517;7728.26581474759;7732.884006740016;7736.2650548197835;8235.464694794819;6765.188177418741;7389.445791565547;7486.9625651873575;7590.316871481593;7631.885521717068;7186.209516953236;7225.385842203472;7252.601106939169;7351.228121047213;7383.852322953619;6826.863462730618;6842.828929276808;6849.657517124024;6867.2102708189195;6874.381305682513;4656.487786343432;4854.854354369375;4902.754862256802;4904;4949.109160351171;4875.512448755097;4919.335889654851;4941.160002799461;4986.501349864984;4988.226824842155 17:29:15 INFO - PROCESS | 2562 | |4;prototype.html;316.05657113516156;318.9808561645542;323.39686484706016;329.3676141807729;333.4697830449585;4594.58648721615;5316.335458067737;5614.747336369863;6038.788642397517;6042.9726946419105;5969.611975221607;6006.339302676713;6020.594668557676;6046.879062418749;6048.758049678018;4246.7451952882775;4252.383404406367;4253.893652658678;4508.797104130322;4559.720139930035;3744.569374521924;3907.577302072719;4432.822687092513;4540.3461452011625;4554.886127846814;4493;4500.549945005506;4509.819607215723;4525.230710779176;4560.475545312282;3672.3022625701033;4148.481439820038;4190.811413486401;4222.598853108965;4533.863984080483;3518.8064656443885;3872.7869967151796;3879.262940041383;3893.396523538856;3893.916754536338;5036.823711170092;5725.828225153252;5750.223719797816;5785.913211301834;5802.506786923117;4115.34154535276;4988.8503344899345;5216.947830521684;5274.973625131888;5297.8145764898045;5327.38735045467;6095.603785753912;6205.565610407273;6244.3755624437645;6313.210848643919;4234.1257015249985;4328.251560259227;4338.956610433886;4344.608985191317;4360.672949528798;6058.606190597597;6059.121427393047;6645.2025756909;6708.094407255007;6719.563228390188;3473.8436770345397;3672.59601443841;3950.071733142709;4344.478662560513;4401.977990110029;4161.854335098257;4478.41780568524;4512.097580483916;4527.615152712056;4550.385697930803;4231.894202644959;4754.453237877619;5090.0912157940775;5092.134337162691;5094.395265491358;6325.936740632626;6354.745810167541;6363.649999250086;6365.204349456318;6369.936300637027;1057.7624179709758;1461.1598330959528;1477.5936617430293;1480.740870347685;1488.2707473337987;788.286600626434;912.5254867468879;948.4593781544592;970.2335155227432;981.6809536900394;5612.915806262909;5799.6230245034785;5821.15593238978;5822.205341436134;5832.387599302097;6108.5418593605655;6150.477209437248;6660.600363978177;6693.866122677519;6699.732010719614;5834.591578589543;6020.277566692025;6021.969890150521;6038.516918646497;6041.637501749821;3441.846981261255;3462.221000274918;3472.374972504923;3487.3548393547276;3492.5808902931813;6579.342065793382;6802.727890884408;6905.482088843357;6919.273476284918;6997.16034075904;5642.4357564244065;5997.190379298826;6565.015247712786;6759.932400676029;6817.352351526696;6043.48630366415;6514.999999999905;6864.7940561783225;6918.446524278046;6940.548864323803;5821.679807610622;6027.276726792813;6535.771248006297;6608.6365249909795;6652.90020649681;5378.515933565998;5431.755570999236;6268.4671802897265;6552.6723663817575;6580;5043.445221025758;6597.1423714916755;6883.965580172267;6937.7571784987285;6987.218259343835;3516.5604299462566;4283.421738065446;4287.142571485653;4287.228298906227;4299.677524185761;6017.368176341596;6307.445653797564;6562.507811914221;6593.208814942238;6645.169353830771;5306.699858534684;5908.350081490947;6019.6990150493175;6392.9041064385;6408;6065.878682426239;6332.176817013758;6345.936540634534;6403.278606592086;6405.967970160305;4871.34236878017;5880.442007726652;6035.155078289131;6264.561480696308;6277.717502712298;5779.826605201851;6037.305709843248;6344.238691357066;6377.394147555975;6393.328700486568;5906.232189815297;6139.201903752483;6199.473044791243;6251.624902505864;6291.245050593958 17:29:15 INFO - PROCESS | 2562 | |5;yui.html;730.8976743255945;735.9080114985627;742.5099434373315;749.6102026945988;785.1598789295455;12341.506339746411;14703.632409189775;14836.629084272885;14837.554873353803;14848.48030318939;13317.26755028473;14134.363953622085;14143.646408839784;14144.504942327021;14168.362423690933;717.5013365710834;804.995975020127;901.3870568013748;923.2752289452781;925.6066171876959;728.4682182007137;883.6774577279311;925.4678559828092;927.4991504587515;927.508420537113;974.6345120579782;977.8728765260506;980.9803803923953;982.6757170133839;983.5573991703726;751.1737089201889;921.7879887625884;974.6929717139114;974.7027156717198;974.8537719342085;719.9784006479814;720.5208536323339;720.92069872314;721.2931327299276;723.4248772226093;14511.637209069751;14592.70814583713;14600.488982885654;14619.269036548236;14697.926510367486;10241.822190448045;11953.342566158857;11982.820257696141;12106.697332566668;12892.162009469355;9802.901970980342;9914.25643076765;9935.354201976848;9954;10366.326188797779;3376.6454522275053;3447.6552344765573;3479.5128681984543;3496.650334966483;3569.5002699622073;66763.66618166926;69103;69348.6532567339;69407.65296173487;69415;9113.817723645623;9166.450012999241;9436.811263774685;9546.522673866279;9630.662926797668;20210.898945505178;20241.595168096555;20438.489037774176;20506.282280120126;20529.589408211752;7203.495755297079;7279.162896266891;7377.0409846719585;7401.851962960818;7475;3929.4302326162547;4030.0327921299263;4031.3549832026733;4072.75563466193;4303.988562687766;3531.3820081485637;3577.3918433866306;3630.0017495189036;3630.2557975614936;3634.47300141478;654.0875478707177;654.5320096131289;659.2879689934858;689.396777819408;703.1948419060147;544.4718622935746;554.1134185303563;587.5681374189967;606.1180981671745;630.2940706408851;7733.033370828646;7745.6127193640095;7749.380049596019;8132.249829562141;8345.374096942633;502.85668584453987;520.6719766547051;530.233812141452;530.4271386902136;531.2005431825079;3755.1926335837925;3759.3421151298962;3774.301754175486;3863.729538932304;3994.320965435883;88256.558717206;90674.40145126934;92323;92645.073549265;93827.53086234526;58165.70917145387;75548.62225688837;75630;76157.85763213556;77531.22468775352;36825;66224.33775662279;66582.66834663377;67776.32223677798;67988.6600566994;7191.2449192834;7552.055993000875;7613.9238607613215;7666.540007599562;7728.8454230916195;38952.41571376488;41905.580944190166;42597.57402425936;43154;44131.11737765291;54245.45754542404;56101.438985609624;58617;58962.705186473795;59054.70472647609;9757.560909758937;10027;10070.949645251727;10182.694519164437;10656.680299591024;11979.28124312544;12045.63863084109;12193.939030304791;12363.567275145328;12383.62849114528;11320.6037788677;11329.71675708114;11329.79940128704;11658.18392712502;11731.671743379657;11219.821918698746;11369.147313951653;11369.31784092957;11396;11511;9085.500297483703;10571.62999295021;11327.943360283145;11416.714582135513;11647.708807279885;9114.589843457194;9268.814623707623;9356.111169438893;9487.193588545051;9823.901760982299;9362;9485.620575176916;9701.466419347003;9783.923768385614;9806.705798826046 17:29:15 INFO - PROCESS | 2562 | __end_tp_report 17:29:15 INFO - PROCESS | 2562 | __start_cc_report 17:29:15 INFO - PROCESS | 2562 | _x_x_mozilla_cycle_collect,1057 17:29:15 INFO - PROCESS | 2562 | __end_cc_report 17:29:15 INFO - PROCESS | 2562 | __startTimestamp1461025755315__endTimestamp 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | ------- Summary: start ------- 17:29:15 INFO - PROCESS | 2562 | Number of tests: 6 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | [#0] dojo.html Cycles:165 Average:5775.90 Median:6055.61 stddev:664.77 (11.0%) stddev-sans-first:629.59 17:29:15 INFO - PROCESS | 2562 | Values: 2979.9 3384.9 3392.0 3455.3 3476.7 5652.4 5727.3 5751.0 5751.4 5761.6 5748.4 5769.2 5847.2 5889.7 5909.6 5157.0 5183.9 5211.8 5223.0 5324.9 5328.4 5329.4 5330.5 5351.7 5393.9 5178.2 5272.9 5278.3 5293.1 5294.1 5256.6 5280.6 5291.9 5295.8 5295.9 4612.0 4927.8 5231.3 5284.8 5307.3 5628.5 5671.3 5699.1 5714.3 5742.7 6249.9 6284.7 6296.2 6305.1 6328.0 6138.4 6224.3 6269.0 6333.8 6341.2 5803.4 5819.5 5844.3 5844.5 5859.0 6331.9 6334.2 6339.8 6342.2 6350.8 6096.5 6104.9 6122.4 6247.6 6251.7 6273.6 6288.1 6291.9 6292.5 6318.7 6108.8 6251.3 6251.9 6267.4 6276.7 6147.7 6176.6 6303.7 6309.6 6316.6 4931.5 4962.5 4974.7 4975.0 4997.7 4357.9 4483.0 4485.0 4489.0 4492.0 5595.1 5623.1 5623.4 5642.0 5646.9 6218.2 6227.5 6228.1 6237.8 6247.8 5642.8 5648.1 5753.1 5795.6 5808.1 4576.8 5327.7 5440.2 5442.8 5474.5 6405.2 6408.1 6413.0 6414.4 6418.3 6174.8 6226.8 6297.4 6317.1 6346.0 6428.2 6440.2 6442.4 6445.8 6448.1 6263.2 6270.2 6272.5 6283.0 6283.2 6136.7 6212.7 6213.6 6240.7 6284.9 6168.5 6171.6 6196.0 6205.2 6208.8 4990.3 6135.4 6152.6 6172.4 6184.4 5980.9 6051.4 6055.6 6055.8 6100.2 6115.4 6131.7 6153.4 6171.2 6178.6 5774.2 5898.2 5986.7 6028.6 6094.5 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | [#1] ext.html Cycles:180 Average:57426.81 Median:18546.04 stddev:118604.18 (639.5%) stddev-sans-first:118859.82 17:29:15 INFO - PROCESS | 2562 | Values: 476.5 503.9 508.9 533.4 540.2 30383.2 31006.0 31128.0 38724.0 40144.2 42634.0 43354.8 43373.6 43386.8 43429.6 9503.4 9702.1 9718.5 9731.5 9733.6 8511.0 8831.9 9494.4 11564.1 11967.7 11075.6 11591.3 11608.0 11668.0 11674.3 8530.4 11507.3 11638.6 11655.5 11673.4 9618.0 11879.5 11884.8 11887.8 11891.3 39224.2 39864.8 39894.4 39903.8 39914.6 3095.5 3132.9 3180.3 3287.2 3375.9 22727.0 28384.6 28972.8 29004.7 29529.6 2676.4 2677.0 2685.9 2707.8 2790.6 387935.1 447774.0 467934.0 473181.0 474383.6 35513.6 65067.7 65657.7 65687.0 65811.0 35539.3 35766.6 35784.0 35793.8 35867.1 14944.7 15182.9 15221.9 15226.2 15256.9 11537.8 11863.8 12770.9 12827.2 12850.0 8001.2 8136.0 8144.4 8212.5 8216.3 3293.5 3331.7 3337.5 3343.7 3360.6 953.8 998.3 1088.7 1088.9 1110.4 29112.4 29565.1 30116.4 30878.2 30908.7 1095.8 1111.9 1130.6 1182.8 1288.3 13326.7 15636.3 15680.8 15843.3 16038.8 282016.0 284166.0 284951.0 638887.0 641730.0 61483.0 62574.0 62598.1 62856.0 62888.7 1313.4 1436.7 1481.3 1484.1 1485.3 24993.9 28901.0 29315.6 29797.0 30039.5 49198.3 49739.0 49889.8 49912.5 49934.8 411201.0 423515.0 423560.0 424392.0 426369.0 22895.0 23782.6 27121.6 27636.9 27727.4 43748.3 43763.8 44122.3 45087.0 45321.6 34861.0 36625.5 36928.3 37355.6 37552.0 35703.6 35895.0 36727.6 36831.0 37379.3 37250.8 37852.9 37895.0 38792.6 38814.6 30417.7 33532.8 33716.2 33835.8 33895.8 24722.0 24833.5 24967.1 25081.4 25432.0 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | [#2] jquery.html Cycles:180 Average:35577.99 Median:15097.77 stddev:99691.13 (660.3%) stddev-sans-first:99935.79 17:29:15 INFO - PROCESS | 2562 | Values: 396.7 398.5 401.8 403.5 413.6 14877.5 17817.7 18541.0 18618.5 18670.5 16096.9 16812.2 20133.6 20149.2 20654.4 5916.2 6058.6 6059.2 6061.2 6090.2 6047.0 6071.0 6078.0 6093.0 6147.6 5811.4 5995.6 6036.8 6043.1 6043.5 5934.4 5994.1 6004.2 6013.8 6023.5 5135.3 5314.6 5337.7 5340.3 5344.2 25515.2 26620.8 26988.1 27005.0 27046.2 2101.4 2241.9 2507.0 2721.8 2722.0 13875.7 15035.5 15153.9 15215.2 15347.0 1753.7 1823.6 1824.2 1840.0 1873.9 118267.4 118476.0 119388.8 119451.0 121560.0 15977.9 17592.5 19385.3 20800.5 20920.6 35082.1 35092.6 35106.0 35118.8 35205.8 9588.3 10125.6 10536.6 10855.5 11084.7 8849.1 8873.4 8892.2 9011.0 9028.9 9159.9 9160.2 9251.8 9339.6 9346.5 3063.4 3271.8 3594.9 3682.4 3700.1 843.9 944.2 956.5 1020.8 1024.4 16468.3 19775.8 20981.9 21063.5 21225.5 886.5 935.9 956.3 987.7 996.4 8992.5 10514.6 10912.7 11213.7 11216.7 586365.0 590541.0 597586.0 603241.0 611752.9 130991.0 132965.7 133808.7 134070.3 135736.0 1035.3 1039.4 1069.2 1093.5 1096.1 18439.4 18527.4 18597.4 18621.3 18647.8 27473.6 30969.2 45089.5 46256.0 47392.5 68422.0 68828.7 69230.3 69607.6 70701.6 6873.0 7369.1 7422.8 8025.0 8524.1 11924.5 11970.8 12828.7 12913.5 12956.6 9429.7 10233.4 10809.4 11066.3 11185.7 8426.5 10480.1 10577.2 10906.5 11066.2 8867.8 10359.6 11455.5 11466.2 11667.2 8131.0 8174.8 8368.6 8388.5 8643.4 9190.5 10246.5 11406.8 11562.8 11573.4 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | [#3] mootools.html Cycles:180 Average:5664.48 Median:4934.67 stddev:3164.55 (64.1%) stddev-sans-first:3155.80 17:29:15 INFO - PROCESS | 2562 | Values: 1221.3 1565.2 1612.8 1898.1 2029.0 7057.4 7900.6 8834.3 8835.6 8838.0 7198.8 8423.2 8657.5 8692.3 8701.3 2140.0 2364.6 2431.9 2464.9 2466.4 2422.6 2439.3 2443.7 2447.3 2449.1 2375.9 2383.8 2399.7 2410.8 2416.2 2108.6 2328.7 2389.7 2395.3 2403.0 2414.9 2435.8 2437.1 2442.2 2450.8 8634.2 8682.7 8698.7 8705.0 8710.4 5145.9 5321.4 5324.7 5328.8 5351.0 3356.8 3447.6 3561.2 3573.6 3589.7 1025.1 1025.6 1034.9 1036.5 1042.9 11550.0 11618.8 11627.5 11649.7 11654.9 4710.6 4729.1 4812.3 4891.5 4928.2 9846.0 9956.6 9969.9 9982.9 9992.0 5378.0 5384.0 5397.4 5409.5 5437.1 3678.7 4257.6 4427.0 4552.5 4967.0 3767.7 3849.3 3859.6 4060.4 4078.6 1343.7 1346.1 1346.6 1363.8 1370.8 881.8 907.0 923.4 926.9 942.0 5261.9 5680.9 5876.0 5984.9 6009.6 936.8 971.8 980.1 981.0 982.8 3707.3 3891.0 3947.3 3987.9 4023.9 10275.3 10673.4 10774.6 10787.9 10800.3 10819.6 10850.3 10930.2 10938.1 10961.8 6566.0 8288.2 8317.3 8328.8 8330.4 5770.6 5772.5 5790.4 5981.6 6113.7 9748.3 9805.0 9963.9 10014.9 10089.3 9810.6 10080.9 10113.8 10215.8 10345.7 7513.4 7528.7 7567.2 7678.6 7683.2 7517.0 7728.3 7732.9 7736.3 8235.5 6765.2 7389.4 7487.0 7590.3 7631.9 7186.2 7225.4 7252.6 7351.2 7383.9 6826.9 6842.8 6849.7 6867.2 6874.4 4656.5 4854.9 4902.8 4904.0 4949.1 4875.5 4919.3 4941.2 4986.5 4988.2 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | [#4] prototype.html Cycles:180 Average:5105.15 Median:5827.30 stddev:1624.76 (27.9%) stddev-sans-first:1589.05 17:29:15 INFO - PROCESS | 2562 | Values: 316.1 319.0 323.4 329.4 333.5 4594.6 5316.3 5614.7 6038.8 6043.0 5969.6 6006.3 6020.6 6046.9 6048.8 4246.7 4252.4 4253.9 4508.8 4559.7 3744.6 3907.6 4432.8 4540.3 4554.9 4493.0 4500.5 4509.8 4525.2 4560.5 3672.3 4148.5 4190.8 4222.6 4533.9 3518.8 3872.8 3879.3 3893.4 3893.9 5036.8 5725.8 5750.2 5785.9 5802.5 4115.3 4988.9 5216.9 5275.0 5297.8 5327.4 6095.6 6205.6 6244.4 6313.2 4234.1 4328.3 4339.0 4344.6 4360.7 6058.6 6059.1 6645.2 6708.1 6719.6 3473.8 3672.6 3950.1 4344.5 4402.0 4161.9 4478.4 4512.1 4527.6 4550.4 4231.9 4754.5 5090.1 5092.1 5094.4 6325.9 6354.7 6363.6 6365.2 6369.9 1057.8 1461.2 1477.6 1480.7 1488.3 788.3 912.5 948.5 970.2 981.7 5612.9 5799.6 5821.2 5822.2 5832.4 6108.5 6150.5 6660.6 6693.9 6699.7 5834.6 6020.3 6022.0 6038.5 6041.6 3441.8 3462.2 3472.4 3487.4 3492.6 6579.3 6802.7 6905.5 6919.3 6997.2 5642.4 5997.2 6565.0 6759.9 6817.4 6043.5 6515.0 6864.8 6918.4 6940.5 5821.7 6027.3 6535.8 6608.6 6652.9 5378.5 5431.8 6268.5 6552.7 6580.0 5043.4 6597.1 6884.0 6937.8 6987.2 3516.6 4283.4 4287.1 4287.2 4299.7 6017.4 6307.4 6562.5 6593.2 6645.2 5306.7 5908.4 6019.7 6392.9 6408.0 6065.9 6332.2 6345.9 6403.3 6406.0 4871.3 5880.4 6035.2 6264.6 6277.7 5779.8 6037.3 6344.2 6377.4 6393.3 5906.2 6139.2 6199.5 6251.6 6291.2 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - PROCESS | 2562 | [#5] yui.html Cycles:180 Average:16883.42 Median:642.19 stddev:23365.49 (3638.4%) stddev-sans-first:23399.56 17:29:15 INFO - PROCESS | 2562 | Values: 730.9 735.9 742.5 749.6 785.2 12341.5 14703.6 14836.6 14837.6 14848.5 13317.3 14134.4 14143.6 14144.5 14168.4 717.5 805.0 901.4 923.3 925.6 728.5 883.7 925.5 927.5 927.5 974.6 977.9 981.0 982.7 983.6 751.2 921.8 974.7 974.7 974.9 720.0 720.5 720.9 721.3 723.4 14511.6 14592.7 14600.5 14619.3 14697.9 10241.8 11953.3 11982.8 12106.7 12892.2 9802.9 9914.3 9935.4 9954.0 10366.3 3376.6 3447.7 3479.5 3496.7 3569.5 66763.7 69103.0 69348.7 69407.7 69415.0 9113.8 9166.5 9436.8 9546.5 9630.7 20210.9 20241.6 20438.5 20506.3 20529.6 7203.5 7279.2 7377.0 7401.9 7475.0 3929.4 4030.0 4031.4 4072.8 4304.0 3531.4 3577.4 3630.0 3630.3 3634.5 654.1 654.5 659.3 689.4 703.2 544.5 554.1 587.6 606.1 630.3 7733.0 7745.6 7749.4 8132.2 8345.4 502.9 520.7 530.2 530.4 531.2 3755.2 3759.3 3774.3 3863.7 3994.3 88256.6 90674.4 92323.0 92645.1 93827.5 58165.7 75548.6 75630.0 76157.9 77531.2 36825.0 66224.3 66582.7 67776.3 67988.7 7191.2 7552.1 7613.9 7666.5 7728.8 38952.4 41905.6 42597.6 43154.0 44131.1 54245.5 56101.4 58617.0 58962.7 59054.7 9757.6 10027.0 10070.9 10182.7 10656.7 11979.3 12045.6 12193.9 12363.6 12383.6 11320.6 11329.7 11329.8 11658.2 11731.7 11219.8 11369.1 11369.3 11396.0 11511.0 9085.5 10571.6 11327.9 11416.7 11647.7 9114.6 9268.8 9356.1 9487.2 9823.9 9362.0 9485.6 9701.5 9783.9 9806.7 17:29:15 INFO - PROCESS | 2562 | -------- Summary: end -------- 17:29:15 INFO - PROCESS | 2562 | 17:29:15 INFO - TEST-INFO | 2562: exit 0 17:29:15 INFO - TEST-OK | dromaeo_css | took 1114406ms 17:29:15 INFO - TEST-START | kraken 17:29:15 INFO - operating with platform_type : mac_ 17:29:15 INFO - Initialising browser for kraken test... 17:29:15 INFO - TEST-INFO | started process 2564 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpeXP1iJ/profile http://localhost:53976/getInfo.html) 17:29:17 INFO - PROCESS | 2564 | __metrics Screen width/height:1600/1200 17:29:17 INFO - PROCESS | 2564 | colorDepth:24 17:29:17 INFO - PROCESS | 2564 | Browser inner width/height: 1024/689 17:29:17 INFO - PROCESS | 2564 | __metrics 17:29:18 INFO - PROCESS | 2564 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:29:18 ERROR - PROCESS | 2564 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:29:18 INFO - TEST-INFO | 2564: exit 0 17:29:18 INFO - Browser initialized. 17:29:18 INFO - Running cycle 1/1 for kraken test... 17:29:18 INFO - TEST-INFO | started process 2566 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpeXP1iJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:29:30 INFO - PROCESS | 2566 | RSS: Main: 237387776 17:29:30 INFO - PROCESS | 2566 | 17:29:33 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53976/tests/kraken/driver.html?testName=audio-beat-detection) 17:29:34 INFO - PROCESS | 2566 | RSS: Main: 285614080 17:29:34 INFO - PROCESS | 2566 | 17:29:36 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53976/tests/kraken/driver.html?testName=audio-dft) 17:29:37 INFO - PROCESS | 2566 | RSS: Main: 301146112 17:29:37 INFO - PROCESS | 2566 | 17:29:40 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53976/tests/kraken/driver.html?testName=audio-fft) 17:29:41 INFO - PROCESS | 2566 | RSS: Main: 386277376 17:29:41 INFO - PROCESS | 2566 | 17:29:43 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53976/tests/kraken/driver.html?testName=audio-oscillator) 17:29:44 INFO - PROCESS | 2566 | RSS: Main: 286535680 17:29:44 INFO - PROCESS | 2566 | 17:29:46 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53976/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:29:47 INFO - PROCESS | 2566 | RSS: Main: 290643968 17:29:47 INFO - PROCESS | 2566 | 17:29:49 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53976/tests/kraken/driver.html?testName=imaging-darkroom) 17:29:50 INFO - PROCESS | 2566 | RSS: Main: 330280960 17:29:50 INFO - PROCESS | 2566 | 17:29:53 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53976/tests/kraken/driver.html?testName=imaging-desaturate) 17:29:54 INFO - PROCESS | 2566 | RSS: Main: 329494528 17:29:54 INFO - PROCESS | 2566 | 17:29:56 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53976/tests/kraken/driver.html?testName=json-parse-financial) 17:29:57 INFO - PROCESS | 2566 | RSS: Main: 328437760 17:29:57 INFO - PROCESS | 2566 | 17:29:59 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53976/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:29:59 INFO - PROCESS | 2566 | RSS: Main: 319938560 17:29:59 INFO - PROCESS | 2566 | 17:30:01 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:30:02 INFO - PROCESS | 2566 | RSS: Main: 326762496 17:30:02 INFO - PROCESS | 2566 | 17:30:04 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:30:05 INFO - PROCESS | 2566 | RSS: Main: 329674752 17:30:05 INFO - PROCESS | 2566 | 17:30:08 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:30:08 INFO - PROCESS | 2566 | RSS: Main: 331145216 17:30:08 INFO - PROCESS | 2566 | 17:30:11 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:30:12 INFO - PROCESS | 2566 | RSS: Main: 326516736 17:30:12 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:53976/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53976/tests/kraken/driver.html?testName=ai-astar) 17:30:14 INFO - PROCESS | 2566 | RSS: Main: 391733248 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | __start_tp_report 17:30:14 INFO - PROCESS | 2566 | _x_x_mozilla_page_load 17:30:14 INFO - PROCESS | 2566 | _x_x_mozilla_page_load_details 17:30:14 INFO - PROCESS | 2566 | |i|pagename|runs| 17:30:14 INFO - PROCESS | 2566 | |0;ai-astar;111;111;110;112;113;112;115;112;113;110 17:30:14 INFO - PROCESS | 2566 | |1;audio-beat-detection;158;125;146;179;102;153;171;148;184;121 17:30:14 INFO - PROCESS | 2566 | |2;audio-dft;185;185;187;186;186;182;185;184;183;186 17:30:14 INFO - PROCESS | 2566 | |3;audio-fft;85;62;85;83;55;78;82;117;115;60 17:30:14 INFO - PROCESS | 2566 | |4;audio-oscillator;90;97;138;78;79;83;159;119;112;126 17:30:14 INFO - PROCESS | 2566 | |5;imaging-gaussian-blur;87;84;87;84;86;83;93;130;87;84 17:30:14 INFO - PROCESS | 2566 | |6;imaging-darkroom;93;93;101;141;92;105;149;94;117;106 17:30:14 INFO - PROCESS | 2566 | |7;imaging-desaturate;85;84;85;84;83;83;84;83;84;85 17:30:14 INFO - PROCESS | 2566 | |8;json-parse-financial;58;60;59;59;59;58;58;78;60;58 17:30:14 INFO - PROCESS | 2566 | |9;json-stringify-tinderbox;64;64;74;72;110;104;87;68;68;69 17:30:14 INFO - PROCESS | 2566 | |10;stanford-crypto-aes;83;86;96;105;83;81;81;76;88;89 17:30:14 INFO - PROCESS | 2566 | |11;stanford-crypto-ccm;140;139;139;136;135;137;133;155;190;148 17:30:14 INFO - PROCESS | 2566 | |12;stanford-crypto-pbkdf2;171;172;169;171;180;171;229;175;168;166 17:30:14 INFO - PROCESS | 2566 | |13;stanford-crypto-sha256-iterative;70;68;70;68;70;68;68;67;70;71 17:30:14 INFO - PROCESS | 2566 | __end_tp_report 17:30:14 INFO - PROCESS | 2566 | __start_cc_report 17:30:14 INFO - PROCESS | 2566 | _x_x_mozilla_cycle_collect,2038 17:30:14 INFO - PROCESS | 2566 | __end_cc_report 17:30:14 INFO - PROCESS | 2566 | __startTimestamp1461025814405__endTimestamp 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | ------- Summary: start ------- 17:30:14 INFO - PROCESS | 2566 | Number of tests: 14 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:1.52 (1.4%) stddev-sans-first:1.58 17:30:14 INFO - PROCESS | 2566 | Values: 111.0 111.0 110.0 112.0 113.0 112.0 115.0 112.0 113.0 110.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#1] audio-beat-detection Cycles:10 Average:148.70 Median:155.50 stddev:26.42 (17.0%) stddev-sans-first:27.81 17:30:14 INFO - PROCESS | 2566 | Values: 158.0 125.0 146.0 179.0 102.0 153.0 171.0 148.0 184.0 121.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:1.52 (0.8%) stddev-sans-first:1.62 17:30:14 INFO - PROCESS | 2566 | Values: 185.0 185.0 187.0 186.0 186.0 182.0 185.0 184.0 183.0 186.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#3] audio-fft Cycles:10 Average:82.20 Median:80.00 stddev:20.98 (26.2%) stddev-sans-first:22.23 17:30:14 INFO - PROCESS | 2566 | Values: 85.0 62.0 85.0 83.0 55.0 78.0 82.0 117.0 115.0 60.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#4] audio-oscillator Cycles:10 Average:108.10 Median:78.50 stddev:27.43 (34.9%) stddev-sans-first:28.30 17:30:14 INFO - PROCESS | 2566 | Values: 90.0 97.0 138.0 78.0 79.0 83.0 159.0 119.0 112.0 126.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:86.50 stddev:14.17 (16.4%) stddev-sans-first:14.97 17:30:14 INFO - PROCESS | 2566 | Values: 87.0 84.0 87.0 84.0 86.0 83.0 93.0 130.0 87.0 84.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#6] imaging-darkroom Cycles:10 Average:109.10 Median:120.50 stddev:20.56 (17.1%) stddev-sans-first:20.96 17:30:14 INFO - PROCESS | 2566 | Values: 93.0 93.0 101.0 141.0 92.0 105.0 149.0 94.0 117.0 106.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 17:30:14 INFO - PROCESS | 2566 | Values: 85.0 84.0 85.0 84.0 83.0 83.0 84.0 83.0 84.0 85.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:59.00 stddev:6.13 (10.4%) stddev-sans-first:6.42 17:30:14 INFO - PROCESS | 2566 | Values: 58.0 60.0 59.0 59.0 59.0 58.0 58.0 78.0 60.0 58.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#9] json-stringify-tinderbox Cycles:10 Average:78.00 Median:68.50 stddev:16.69 (24.4%) stddev-sans-first:16.91 17:30:14 INFO - PROCESS | 2566 | Values: 64.0 64.0 74.0 72.0 110.0 104.0 87.0 68.0 68.0 69.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.50 stddev:8.40 (9.9%) stddev-sans-first:8.80 17:30:14 INFO - PROCESS | 2566 | Values: 83.0 86.0 96.0 105.0 83.0 81.0 81.0 76.0 88.0 89.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:139.50 stddev:17.06 (12.2%) stddev-sans-first:17.99 17:30:14 INFO - PROCESS | 2566 | Values: 140.0 139.0 139.0 136.0 135.0 137.0 133.0 155.0 190.0 148.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:171.50 stddev:18.61 (10.8%) stddev-sans-first:19.60 17:30:14 INFO - PROCESS | 2566 | Values: 171.0 172.0 169.0 171.0 180.0 171.0 229.0 175.0 168.0 166.0 17:30:14 INFO - PROCESS | 2566 | 17:30:14 INFO - PROCESS | 2566 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:70.00 stddev:1.33 (1.9%) stddev-sans-first:1.36 17:30:14 INFO - PROCESS | 2566 | Values: 70.0 68.0 70.0 68.0 70.0 68.0 68.0 67.0 70.0 71.0 17:30:14 INFO - PROCESS | 2566 | -------- Summary: end -------- 17:30:14 INFO - PROCESS | 2566 | 17:30:15 INFO - TEST-INFO | 2566: exit 0 17:30:15 INFO - TEST-OK | kraken | took 59319ms 17:30:15 INFO - SUITE-END | took 1173s 17:30:15 INFO - Completed test suite (00:19:34) 17:30:15 INFO - javascript benchmark 17:30:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2979.9255018624544, 3384.932301353973, 3391.9660803391976, 3455.3434847378994, 3476.72186225102, 5652.406497317784, 5727.341355744085, 5750.971245143774, 5751.4248575142465, 5761.567882408825, 5748.367679555246, 5769.16347129666, 5847.210626565412, 5889.705514724268, 5909.586328956974, 5157.020166168435, 5183.922241166376, 5211.817586384477, 5223.0, 5324.946750532503, 5328.387235467932, 5329.413764485904, 5330.466953304677, 5351.7056561889085, 5393.865153371177, 5178.223266510035, 5272.894542109175, 5278.340207474066, 5293.126634105387, 5294.126469132589, 5256.632035757499, 5280.6039547034125, 5291.947080529184, 5295.761690723926, 5295.947040529622, 4611.976940115278, 4927.768057985503, 5231.2937753403185, 5284.81503147388, 5307.336582927134, 5628.54971602275, 5671.291088613923, 5699.145128230757, 5714.342850572154, 5742.684152371659, 6249.9062514062325, 6284.7486100555925, 6296.181496405484, 6305.14880491137, 6328.0, 6138.357744607992, 6224.253089629228, 6269.028300613412, 6333.80998570039, 6341.207349081365, 5803.400864357385, 5819.476247137778, 5844.298684157929, 5844.47399734026, 5858.974679431082, 6331.905021424683, 6334.176557047646, 6339.809805705836, 6342.175517182736, 6350.841228969312, 6096.481799047131, 6104.913929256131, 6122.448979591858, 6247.5939063960695, 6251.6561589112125, 6273.592216505913, 6288.088227207029, 6291.937080629226, 6292.528060395489, 6318.71565779541, 6108.786192483241, 6251.3123556408755, 6251.937480625226, 6267.435930766162, 6276.686165691697, 6147.66187859672, 6176.629402235881, 6303.6532990685955, 6309.589876658094, 6316.62100273985, 4931.4821943696115, 4962.503749625009, 4974.651774375831, 4975.0, 4997.725125118153, 4357.912841743211, 4483.0, 4485.035717320791, 4489.0, 4491.977540112344, 5595.07681232592, 5623.100303951348, 5623.409541998031, 5642.04085305499, 5646.858828529237, 6218.160548325918, 6227.501799856001, 6228.128062071313, 6237.781677641352, 6247.843803904848, 5642.830715078554, 5648.124540696199, 5753.108268218432, 5795.623284486495, 5808.128780682931, 4576.794044268016, 5327.653702509325, 5440.211169380497, 5442.755076021588, 5474.507294343528, 6405.19935008124, 6408.1349017881985, 6413.0380442933, 6414.358564143548, 6418.326075762071, 6174.783882564268, 6226.844328891814, 6297.370262973666, 6317.115603815561, 6345.96827015862, 6428.164338635947, 6440.227172739301, 6442.35576442352, 6445.774397896051, 6448.1295025171, 6263.248410190807, 6270.2475702915945, 6272.4982001440685, 6282.994720844643, 6283.246010478589, 6136.73353236607, 6212.720427580679, 6213.627182369072, 6240.687965601793, 6284.905726414199, 6168.506519478341, 6171.6297022178815, 6196.0, 6205.162303089206, 6208.844778880383, 4990.326305948651, 6135.447809697149, 6152.630842149485, 6172.413620706206, 6184.443400094013, 5980.910286345621, 6051.364606716407, 6055.6063855849225, 6055.757769689163, 6100.176476175837, 6115.379270641068, 6131.736526946108, 6153.384661533811, 6171.166892469639, 6178.567500275118, 5774.249347584787, 5898.174255604133, 5986.730597123053, 6028.585177578402, 6094.481969032505], "value": 5732.085418121585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.51157563497424, 503.94708555601636, 508.94147173075095, 533.3999250842802, 540.240961449164, 30383.24041898951, 31006.0, 31127.99999999997, 38724.0, 40144.19711605766, 42634.0, 43354.783226083906, 43373.56626433735, 43386.78306608462, 43429.565704342946, 9503.429794212352, 9702.126808587225, 9718.514074296292, 9731.513424328756, 9733.610655573768, 8511.0, 8831.911680883173, 9494.38286511378, 11564.132690048238, 11967.70080747984, 11075.612353567627, 11591.304521728724, 11607.955284024436, 11668.0, 11674.299542027506, 8530.445521041112, 11507.3095614263, 11638.592649257236, 11655.475503602274, 11673.357965311905, 9618.0, 11879.465424055938, 11884.821727674005, 11887.821682674767, 11891.28652280866, 39224.215515689815, 39864.800675996434, 39894.401583976265, 39903.80288591347, 39914.60085399138, 3095.5356696495705, 3132.8746850125967, 3180.2685382361956, 3287.178205448638, 3375.9156021099425, 22727.0, 28384.574231386545, 28972.84108635652, 29004.70995290041, 29529.571033924516, 2676.411189538318, 2676.973230267692, 2685.9194224173307, 2707.756301932816, 2790.6372171617763, 387935.1206487927, 447774.0, 467934.0, 473181.0, 474383.62808186084, 35513.64486355155, 65067.67466162639, 65657.67171164209, 65687.0, 65810.99999999904, 35539.289214216085, 35766.64233357633, 35784.0, 35793.8210308952, 35867.10332241715, 14944.70110597782, 15182.924085379502, 15221.923890380478, 15226.162561059247, 15256.923715381574, 11537.769244615229, 11863.762724745457, 12770.872291277154, 12827.166234194747, 12850.0, 8001.199880011952, 8135.959320203361, 8144.389170812213, 8212.46618969765, 8216.301614362847, 3293.5389045533643, 3331.7334613230882, 3337.466005439118, 3343.6990670839255, 3360.6471320510996, 953.756792018031, 998.3111652959435, 1088.7278180454887, 1088.8911108889047, 1110.4225802582773, 29112.417751644847, 29565.113046608636, 30116.397672046438, 30878.24182808606, 30908.69091309058, 1095.8411030400548, 1111.8832522585012, 1130.598637483698, 1182.8403165572709, 1288.2785640041607, 13326.666833329244, 15636.296366663528, 15680.76478852818, 15843.287052082684, 16038.839611603968, 282016.0, 284166.0, 284951.0, 638887.0, 641730.0, 61483.0, 62574.0, 62598.0610290846, 62856.0, 62888.68555657192, 1313.4352228541627, 1436.7054753775242, 1481.3408033425023, 1484.0724547158027, 1485.3093311609937, 24993.876500257553, 28901.0, 29315.560266595592, 29796.95710650117, 30039.54940675848, 49198.26202606892, 49739.0, 49889.75055124701, 49912.50087499079, 49934.75032624813, 411201.0, 423515.0, 423560.0, 424392.0, 426369.0, 22895.0, 23782.643260350764, 27121.593176102768, 27636.927230457393, 27727.44545109127, 43748.34377484277, 43763.78118109389, 44122.33816492691, 45087.0, 45321.600879933474, 34861.0, 36625.53497860056, 36928.26143477169, 37355.6264437363, 37552.0, 35703.642963571074, 35895.0, 36727.63272367349, 36831.0, 37379.25241495209, 37250.8137459311, 37852.86441406762, 37895.0, 38792.6120738789, 38814.6118538811, 30417.695823041486, 33532.83233583817, 33716.15709607279, 33835.83082084672, 33895.813646522256, 24722.0, 24833.503329932937, 24967.12615058464, 25081.372965676004, 25432.0], "value": 18360.319567593582, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.69256326347084, 398.4541178585338, 401.8312308830291, 403.4895856741223, 413.62153629429076, 14877.479288224915, 17817.732734008983, 18541.0, 18618.53453663659, 18670.533236669075, 16096.919515402407, 16812.243449044792, 20133.59732805343, 20149.17911492392, 20654.38036858893, 5916.230889984317, 6058.636481811083, 6059.182010428602, 6061.181740465025, 6090.208272924514, 6047.0, 6071.0, 6078.0, 6093.025115981444, 6147.631142131464, 5811.389804070575, 5995.550333724988, 6036.818895433122, 6043.123747056674, 6043.486303664194, 5934.376890426486, 5994.07091900756, 6004.159417681528, 6013.789517366893, 6023.488003519684, 5135.3324067870935, 5314.60140489461, 5337.653052551572, 5340.305760251143, 5344.198370244456, 25515.234542963743, 26620.802063906976, 26988.05541806047, 27005.0, 27046.1886143416, 2101.3905967269475, 2241.8542794718455, 2506.9749302506925, 2721.7958653101095, 2721.9520484613568, 13875.722485550232, 15035.54893353201, 15153.924230378887, 15215.163166025864, 15347.0, 1753.7194048952233, 1823.5896923192308, 1824.224704500582, 1840.0339821593636, 1873.9063046847739, 118267.408662957, 118476.0, 119388.8061119395, 119451.0, 121560.0, 15977.920110399373, 17592.504637105732, 19385.321513746952, 20800.51181574632, 20920.581588368146, 35082.12294692653, 35092.64907350945, 35106.0, 35118.82440587781, 35205.82397088049, 9588.328816982745, 10125.594976201017, 10536.578536858593, 10855.457227138611, 11084.667459976214, 8849.115088491099, 8873.378863479495, 8892.199702026848, 9011.0, 9028.8645670315, 9159.89322455839, 9160.22138118254, 9251.814963700688, 9339.579718912666, 9346.48594327305, 3063.3566950940553, 3271.81626725489, 3594.9101272468397, 3682.4476328550395, 3700.148965737895, 843.8945131858518, 944.1596978688972, 956.53129966316, 1020.7754294055296, 1024.3802499487851, 16468.258928348252, 19775.802241977683, 20981.89509052445, 21063.473413164793, 21225.469363266042, 886.4548302793864, 935.9064093590723, 956.3353469338742, 987.7234374375187, 996.4121168510469, 8992.46045237288, 10514.633097697251, 10912.672619821418, 11213.66359009231, 11216.719582010515, 586365.0, 590541.0, 597586.0, 603241.0, 611752.9412352999, 130991.0, 132965.6924015329, 133808.66191338157, 134070.3248546265, 135735.96396054068, 1035.321864178964, 1039.4127318065268, 1069.155367259856, 1093.5352475198008, 1096.0847692177122, 18439.446816595522, 18527.351542695942, 18597.44207673772, 18621.255149793855, 18647.81352186461, 27473.587896181172, 30969.19977403506, 45089.54910450854, 46256.0, 47392.52607473881, 68422.0, 68828.65585672039, 69230.30769692239, 69607.60784784175, 70701.64649176926, 6873.0, 7369.115706115301, 7422.777316680508, 8025.0, 8524.147585241426, 11924.523019079486, 11970.76058478843, 12828.679283017998, 12913.483460661468, 12956.611301660589, 9429.669961551586, 10233.362661974048, 10809.35143891369, 11066.336019838835, 11185.66443006711, 8426.494410335154, 10480.056794888496, 10577.206709496972, 10906.509207085859, 11066.225364224427, 8867.778305542413, 10359.585616575252, 11455.541778328774, 11466.197366184288, 11667.241629294409, 8130.959345203236, 8174.83650327002, 8368.581570921551, 8388.454750441202, 8643.394962352826, 9190.540472976458, 10246.487675616338, 11406.82889756671, 11562.76874462523, 11573.421328933688], "value": 10338.830740084864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1221.316063004718, 1565.1548163991445, 1612.7500237463194, 1898.1173754204292, 2028.9695654565185, 7057.435405167587, 7900.565468899208, 8834.293256539477, 8835.646574137036, 8837.955810220956, 7198.820029499265, 8423.199796019384, 8657.523836189008, 8692.34807389445, 8701.347398945072, 2140.0262880389387, 2364.574376612209, 2431.9270421887327, 2464.852108873464, 2466.3834041489627, 2422.6366045093287, 2439.329184474275, 2443.7067551893797, 2447.339218411028, 2449.069353645612, 2375.869327187004, 2383.8212134089927, 2399.6520504526834, 2410.8432951858163, 2416.214730212679, 2108.5888251790907, 2328.6507023946374, 2389.665446837444, 2395.3293077938206, 2403.038784486202, 2414.8913298901593, 2435.780779729833, 2437.0617312334703, 2442.230697330353, 2450.803935685141, 8634.179752923525, 8682.696105636336, 8698.652053917836, 8705.0, 8710.433821801626, 5145.871353216162, 5321.388040375329, 5324.653897496689, 5328.813491527778, 5351.036813373591, 3356.7818091824197, 3447.620761716209, 3561.216532362876, 3573.624769399202, 3589.74871758977, 1025.1235193909174, 1025.610268098118, 1034.9172066234682, 1036.5387402605843, 1042.9478526073742, 11549.999999999916, 11618.825717614243, 11627.534898604046, 11649.650510484698, 11654.941725291405, 4710.599599034121, 4729.069756196634, 4812.302216178635, 4891.461939186686, 4928.186849169919, 9845.950770246103, 9956.601735930626, 9969.850452243221, 9982.850257246148, 9992.0, 5377.973110134424, 5384.030874442638, 5397.3523177218985, 5409.486098820606, 5437.075697131497, 3678.668919797231, 4257.552956939477, 4427.026054268056, 4552.544745525487, 4967.006598680278, 3767.6609105180664, 3849.3071247175776, 3859.5754467008605, 4060.411240370189, 4078.5513593504693, 1343.7312537492542, 1346.077936613423, 1346.6094832498466, 1363.849976502584, 1370.8012338210904, 881.7927786970057, 906.954652267384, 923.3674932671141, 926.8517037274139, 941.9529023548795, 5261.868453288622, 5680.920625081221, 5876.0, 5984.940150598438, 6009.579329446984, 936.8079543693526, 971.7667759737612, 980.1032055669126, 980.9950950245203, 982.8378317577663, 3707.332680117551, 3890.9610903891166, 3947.2697550953167, 3987.900302492461, 4023.8994025149605, 10275.332103413255, 10673.35959842412, 10774.569017239222, 10787.921207879306, 10800.297980631234, 10819.621313253998, 10850.348979061282, 10930.234883558234, 10938.070264027489, 10961.83557246642, 6565.967170164118, 8288.171182881664, 8317.251447369645, 8328.750137495885, 8330.416870819106, 5770.596058276048, 5772.5381969441505, 5790.449907258972, 5981.611195272293, 6113.694315284129, 9748.317617766688, 9805.0, 9963.850542242017, 10014.899851001397, 10089.29374943747, 9810.626512288429, 10080.899191007997, 10113.797724045626, 10215.795684086128, 10345.741356465847, 7513.436492262993, 7528.698852045857, 7567.243275672388, 7678.616069196406, 7683.154852966281, 7517.0, 7728.26581474759, 7732.884006740016, 7736.2650548197835, 8235.464694794819, 6765.188177418741, 7389.445791565547, 7486.9625651873575, 7590.316871481593, 7631.885521717068, 7186.209516953236, 7225.385842203472, 7252.601106939169, 7351.228121047213, 7383.852322953619, 6826.863462730618, 6842.828929276808, 6849.657517124024, 6867.2102708189195, 6874.381305682513, 4656.487786343432, 4854.854354369375, 4902.754862256802, 4904.0, 4949.109160351171, 4875.512448755097, 4919.335889654851, 4941.160002799461, 4986.501349864984, 4988.226824842155], "value": 4577.160407699092, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.05657113516156, 318.9808561645542, 323.39686484706016, 329.3676141807729, 333.4697830449585, 4594.58648721615, 5316.335458067737, 5614.747336369863, 6038.788642397517, 6042.9726946419105, 5969.611975221607, 6006.339302676713, 6020.594668557676, 6046.879062418749, 6048.758049678018, 4246.7451952882775, 4252.383404406367, 4253.893652658678, 4508.797104130322, 4559.720139930035, 3744.569374521924, 3907.577302072719, 4432.822687092513, 4540.3461452011625, 4554.886127846814, 4493.0, 4500.549945005506, 4509.819607215723, 4525.230710779176, 4560.475545312282, 3672.3022625701033, 4148.481439820038, 4190.811413486401, 4222.598853108965, 4533.863984080483, 3518.8064656443885, 3872.7869967151796, 3879.262940041383, 3893.396523538856, 3893.916754536338, 5036.823711170092, 5725.828225153252, 5750.223719797816, 5785.913211301834, 5802.506786923117, 4115.34154535276, 4988.8503344899345, 5216.947830521684, 5274.973625131888, 5297.8145764898045, 5327.38735045467, 6095.603785753912, 6205.565610407273, 6244.3755624437645, 6313.210848643919, 4234.1257015249985, 4328.251560259227, 4338.956610433886, 4344.608985191317, 4360.672949528798, 6058.606190597597, 6059.121427393047, 6645.2025756909, 6708.094407255007, 6719.563228390188, 3473.8436770345397, 3672.59601443841, 3950.071733142709, 4344.478662560513, 4401.977990110029, 4161.854335098257, 4478.41780568524, 4512.097580483916, 4527.615152712056, 4550.385697930803, 4231.894202644959, 4754.453237877619, 5090.0912157940775, 5092.134337162691, 5094.395265491358, 6325.936740632626, 6354.745810167541, 6363.649999250086, 6365.204349456318, 6369.936300637027, 1057.7624179709758, 1461.1598330959528, 1477.5936617430293, 1480.740870347685, 1488.2707473337987, 788.286600626434, 912.5254867468879, 948.4593781544592, 970.2335155227432, 981.6809536900394, 5612.915806262909, 5799.6230245034785, 5821.15593238978, 5822.205341436134, 5832.387599302097, 6108.5418593605655, 6150.477209437248, 6660.600363978177, 6693.866122677519, 6699.732010719614, 5834.591578589543, 6020.277566692025, 6021.969890150521, 6038.516918646497, 6041.637501749821, 3441.846981261255, 3462.221000274918, 3472.374972504923, 3487.3548393547276, 3492.5808902931813, 6579.342065793382, 6802.727890884408, 6905.482088843357, 6919.273476284918, 6997.16034075904, 5642.4357564244065, 5997.190379298826, 6565.015247712786, 6759.932400676029, 6817.352351526696, 6043.48630366415, 6514.999999999905, 6864.7940561783225, 6918.446524278046, 6940.548864323803, 5821.679807610622, 6027.276726792813, 6535.771248006297, 6608.6365249909795, 6652.90020649681, 5378.515933565998, 5431.755570999236, 6268.4671802897265, 6552.6723663817575, 6580.0, 5043.445221025758, 6597.1423714916755, 6883.965580172267, 6937.7571784987285, 6987.218259343835, 3516.5604299462566, 4283.421738065446, 4287.142571485653, 4287.228298906227, 4299.677524185761, 6017.368176341596, 6307.445653797564, 6562.507811914221, 6593.208814942238, 6645.169353830771, 5306.699858534684, 5908.350081490947, 6019.6990150493175, 6392.9041064385, 6408.0, 6065.878682426239, 6332.176817013758, 6345.936540634534, 6403.278606592086, 6405.967970160305, 4871.34236878017, 5880.442007726652, 6035.155078289131, 6264.561480696308, 6277.717502712298, 5779.826605201851, 6037.305709843248, 6344.238691357066, 6377.394147555975, 6393.328700486568, 5906.232189815297, 6139.201903752483, 6199.473044791243, 6251.624902505864, 6291.245050593958], "value": 4573.720785821516, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.8976743255945, 735.9080114985627, 742.5099434373315, 749.6102026945988, 785.1598789295455, 12341.506339746411, 14703.632409189775, 14836.629084272885, 14837.554873353803, 14848.48030318939, 13317.26755028473, 14134.363953622085, 14143.646408839784, 14144.504942327021, 14168.362423690933, 717.5013365710834, 804.995975020127, 901.3870568013748, 923.2752289452781, 925.6066171876959, 728.4682182007137, 883.6774577279311, 925.4678559828092, 927.4991504587515, 927.508420537113, 974.6345120579782, 977.8728765260506, 980.9803803923953, 982.6757170133839, 983.5573991703726, 751.1737089201889, 921.7879887625884, 974.6929717139114, 974.7027156717198, 974.8537719342085, 719.9784006479814, 720.5208536323339, 720.92069872314, 721.2931327299276, 723.4248772226093, 14511.637209069751, 14592.70814583713, 14600.488982885654, 14619.269036548236, 14697.926510367486, 10241.822190448045, 11953.342566158857, 11982.820257696141, 12106.697332566668, 12892.162009469355, 9802.901970980342, 9914.25643076765, 9935.354201976848, 9954.0, 10366.326188797779, 3376.6454522275053, 3447.6552344765573, 3479.5128681984543, 3496.650334966483, 3569.5002699622073, 66763.66618166926, 69103.0, 69348.6532567339, 69407.65296173487, 69415.0, 9113.817723645623, 9166.450012999241, 9436.811263774685, 9546.522673866279, 9630.662926797668, 20210.898945505178, 20241.595168096555, 20438.489037774176, 20506.282280120126, 20529.589408211752, 7203.495755297079, 7279.162896266891, 7377.0409846719585, 7401.851962960818, 7475.0, 3929.4302326162547, 4030.0327921299263, 4031.3549832026733, 4072.75563466193, 4303.988562687766, 3531.3820081485637, 3577.3918433866306, 3630.0017495189036, 3630.2557975614936, 3634.47300141478, 654.0875478707177, 654.5320096131289, 659.2879689934858, 689.396777819408, 703.1948419060147, 544.4718622935746, 554.1134185303563, 587.5681374189967, 606.1180981671745, 630.2940706408851, 7733.033370828646, 7745.6127193640095, 7749.380049596019, 8132.249829562141, 8345.374096942633, 502.85668584453987, 520.6719766547051, 530.233812141452, 530.4271386902136, 531.2005431825079, 3755.1926335837925, 3759.3421151298962, 3774.301754175486, 3863.729538932304, 3994.320965435883, 88256.558717206, 90674.40145126934, 92323.0, 92645.073549265, 93827.53086234526, 58165.70917145387, 75548.62225688837, 75630.0, 76157.85763213556, 77531.22468775352, 36825.0, 66224.33775662279, 66582.66834663377, 67776.32223677798, 67988.6600566994, 7191.2449192834, 7552.055993000875, 7613.9238607613215, 7666.540007599562, 7728.8454230916195, 38952.41571376488, 41905.580944190166, 42597.57402425936, 43154.0, 44131.11737765291, 54245.45754542404, 56101.438985609624, 58617.0, 58962.705186473795, 59054.70472647609, 9757.560909758937, 10027.0, 10070.949645251727, 10182.694519164437, 10656.680299591024, 11979.28124312544, 12045.63863084109, 12193.939030304791, 12363.567275145328, 12383.62849114528, 11320.6037788677, 11329.71675708114, 11329.79940128704, 11658.18392712502, 11731.671743379657, 11219.821918698746, 11369.147313951653, 11369.31784092957, 11396.0, 11511.0, 9085.500297483703, 10571.62999295021, 11327.943360283145, 11416.714582135513, 11647.708807279885, 9114.589843457194, 9268.814623707623, 9356.111169438893, 9487.193588545051, 9823.901760982299, 9362.0, 9485.620575176916, 9701.466419347003, 9783.923768385614, 9806.705798826046], "value": 6667.47101387187, "unit": "score"}], "name": "dromaeo_css", "value": 7304.466149961868, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 110.0, 112.0, 113.0, 112.0, 115.0, 112.0, 113.0, 110.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 125.0, 146.0, 179.0, 102.0, 153.0, 171.0, 148.0, 184.0, 121.0], "value": 148.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 187.0, 186.0, 186.0, 182.0, 185.0, 184.0, 183.0, 186.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 85.0, 83.0, 55.0, 78.0, 82.0, 117.0, 115.0, 60.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 97.0, 138.0, 78.0, 79.0, 83.0, 159.0, 119.0, 112.0, 126.0], "value": 108.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 84.0, 86.0, 83.0, 93.0, 130.0, 87.0, 84.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 101.0, 141.0, 92.0, 105.0, 149.0, 94.0, 117.0, 106.0], "value": 109.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 84.0, 83.0, 83.0, 84.0, 83.0, 84.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 59.0, 59.0, 58.0, 58.0, 78.0, 60.0, 58.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 74.0, 72.0, 110.0, 104.0, 87.0, 68.0, 68.0, 69.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 96.0, 105.0, 83.0, 81.0, 81.0, 76.0, 88.0, 89.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 139.0, 136.0, 135.0, 137.0, 133.0, 155.0, 190.0, 148.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 169.0, 171.0, 180.0, 171.0, 229.0, 175.0, 168.0, 166.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 70.0, 68.0, 70.0, 68.0, 68.0, 67.0, 70.0, 71.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1536.3000000000002, "alertThreshold": 2.0}]} 17:30:15 INFO - Return code: 0 17:30:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:30:15 ERROR - # TBPL SUCCESS # 17:30:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:30:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:30:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:30:15 INFO - 2016-04-18 17:30:15.728 screenresolution[2571:75691] starting screenresolution argv=screenresolution get 17:30:15 INFO - 2016-04-18 17:30:15.731 screenresolution[2571:75691] Display 0: 1600x1200x32@60 17:30:15 INFO - 2016-04-18 17:30:15.736 screenresolution[2572:75694] starting screenresolution argv=screenresolution list 17:30:15 INFO - Available Modes on Display 0 17:30:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:30:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:30:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:30:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:30:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:30:15 INFO - Intel Iris: 17:30:15 INFO - Chipset Model: Intel Iris 17:30:15 INFO - Type: GPU 17:30:15 INFO - Bus: Built-In 17:30:15 INFO - VRAM (Dynamic, Max): 1536 MB 17:30:15 INFO - Vendor: Intel (0x8086) 17:30:15 INFO - Device ID: 0x0a2e 17:30:15 INFO - Revision ID: 0x0009 17:30:15 INFO - Displays: 17:30:15 INFO - Display: 17:30:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:30:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:30:15 INFO - Display Serial Number: 1600x1200 60 17:30:15 INFO - Main Display: Yes 17:30:15 INFO - Mirror: Off 17:30:15 INFO - Online: Yes 17:30:15 INFO - Rotation: Supported 17:30:15 INFO - Return code: 0 17:30:15 INFO - Running post-action listener: _resource_record_post_action 17:30:15 INFO - Running post-run listener: _resource_record_post_run 17:30:17 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 0; Write bytes: 694296064; Read time: 0; Write time: 1908 17:30:17 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377291264; Write bytes: 372070400; Read time: 27968; Write time: 732 17:30:17 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 0; Write bytes: 297068032; Read time: 0; Write time: 1126 17:30:17 INFO - Running post-run listener: _upload_blobber_files 17:30:17 INFO - Blob upload gear active. 17:30:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:30:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:30:17 INFO - (blobuploader) - INFO - Open directory for files ... 17:30:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:30:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:30:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:18 INFO - (blobuploader) - INFO - Done attempting. 17:30:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:30:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:30:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:19 INFO - (blobuploader) - INFO - Done attempting. 17:30:19 INFO - (blobuploader) - INFO - Iteration through files over. 17:30:19 INFO - Return code: 0 17:30:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:30:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:30:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff2717aed6506f6ec3cde2a14d422509d8c33cf322734a7485390413bebb869f1ba71453143278a433281f9ec5ef496a39b9be9d98d51a60313abf387be66a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d372c576a5689cfdb2615c62c70d39ba1b1faba3ba9327d52342bed1fbbcabb0450cd75d7d8cea6e0cd19ce238a2282db269d0c5aba109ea6825bbb4e162eaf"} 17:30:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:30:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:30:19 INFO - Contents: 17:30:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff2717aed6506f6ec3cde2a14d422509d8c33cf322734a7485390413bebb869f1ba71453143278a433281f9ec5ef496a39b9be9d98d51a60313abf387be66a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d372c576a5689cfdb2615c62c70d39ba1b1faba3ba9327d52342bed1fbbcabb0450cd75d7d8cea6e0cd19ce238a2282db269d0c5aba109ea6825bbb4e162eaf"} 17:30:19 INFO - Running post-run listener: copy_logs_to_upload_dir 17:30:19 INFO - Copying logs to upload dir... 17:30:19 INFO - mkdir: /builds/slave/test/build/upload/logs 17:30:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.005256 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-18 17:30:19.229192) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 17:30:19.233101) ========= 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.AE5pcWzU9i/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.npuBrcTetB/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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/ff2717aed6506f6ec3cde2a14d422509d8c33cf322734a7485390413bebb869f1ba71453143278a433281f9ec5ef496a39b9be9d98d51a60313abf387be66a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d372c576a5689cfdb2615c62c70d39ba1b1faba3ba9327d52342bed1fbbcabb0450cd75d7d8cea6e0cd19ce238a2282db269d0c5aba109ea6825bbb4e162eaf"} build_url:https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/PobJAinbRWeg-Vk4bxRiqA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff2717aed6506f6ec3cde2a14d422509d8c33cf322734a7485390413bebb869f1ba71453143278a433281f9ec5ef496a39b9be9d98d51a60313abf387be66a8d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d372c576a5689cfdb2615c62c70d39ba1b1faba3ba9327d52342bed1fbbcabb0450cd75d7d8cea6e0cd19ce238a2282db269d0c5aba109ea6825bbb4e162eaf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 17:30:19.259920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:30:19.260217) ========= 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.AE5pcWzU9i/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.npuBrcTetB/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.005478 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:30:19.308273) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 17:30:19.308570) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 17:30:19.308969) ========= ========= Total master_lag: 5.21 =========