builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0301 starttime: 1467908889.69 results: success (0) buildid: 20160707082722 builduid: e66344b6701b424499d55f672a9873c8 revision: e2f5a7c70b47be82f2a8f85cac59482c8f77550a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:09.693195) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:09.693683) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:09.694015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:09.752970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 09:28:09.753416) ========= 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.BK33XtxlNj/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.2R5I6CGv4K/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-07-07 09:28:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 126M=0s 2016-07-07 09:28:10 (126 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.983934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 09:28:10.760666) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:10.761216) ========= 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.BK33XtxlNj/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.2R5I6CGv4K/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.021062 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:10.814253) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-07 09:28:10.815227) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e2f5a7c70b47be82f2a8f85cac59482c8f77550a --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 e2f5a7c70b47be82f2a8f85cac59482c8f77550a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BK33XtxlNj/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.2R5I6CGv4K/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-07-07 09:28:10,901 truncating revision to first 12 chars 2016-07-07 09:28:10,901 Setting DEBUG logging. 2016-07-07 09:28:10,901 attempt 1/10 2016-07-07 09:28:10,901 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e2f5a7c70b47?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 09:28:12,200 unpacking tar archive at: fx-team-e2f5a7c70b47/testing/mozharness/ program finished with exit code 0 elapsedTime=1.616469 ========= master_lag: 0.56 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-07 09:28:12.990123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:12.990432) ========= script_repo_revision: e2f5a7c70b47be82f2a8f85cac59482c8f77550a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:12.990858) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:12.991163) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 09:28:13.053588) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-07 09:28:13.053905) ========= /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.BK33XtxlNj/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.2R5I6CGv4K/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:28:13 INFO - MultiFileLogger online at 20160707 09:28:13 in /builds/slave/test 09:28:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:28:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:28:13 INFO - {'append_to_log': False, 09:28:13 INFO - 'base_work_dir': '/builds/slave/test', 09:28:13 INFO - 'blob_upload_branch': 'Fx-Team', 09:28:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:28:13 INFO - 'branch': 'Fx-Team', 09:28:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:28:13 INFO - 'config_files': ('talos/mac_config.py',), 09:28:13 INFO - 'default_actions': ('clobber', 09:28:13 INFO - 'read-buildbot-config', 09:28:13 INFO - 'download-and-extract', 09:28:13 INFO - 'populate-webroot', 09:28:13 INFO - 'create-virtualenv', 09:28:13 INFO - 'install', 09:28:13 INFO - 'run-tests'), 09:28:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:28:13 INFO - 'download_minidump_stackwalk': True, 09:28:13 INFO - 'download_symbols': 'ondemand', 09:28:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:28:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:28:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:28:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:28:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:28:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:28:13 INFO - 'installer_path': 'installer.exe', 09:28:13 INFO - 'log_level': 'info', 09:28:13 INFO - 'log_name': 'talos', 09:28:13 INFO - 'log_to_console': True, 09:28:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:28:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:28:13 INFO - 'opt_config_files': (), 09:28:13 INFO - 'pip_index': False, 09:28:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:13 INFO - 'cmd': ('bash', 09:28:13 INFO - '-c', 09:28:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:13 INFO - 'enabled': True, 09:28:13 INFO - 'halt_on_failure': False, 09:28:13 INFO - 'name': 'check_screen_resolution'},), 09:28:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:28:13 INFO - 'cmd': ('bash', 09:28:13 INFO - '-c', 09:28:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:28:13 INFO - 'enabled': True, 09:28:13 INFO - 'halt_on_failure': False, 09:28:13 INFO - 'name': 'check_screen_resolution'},), 09:28:13 INFO - 'run_cmd_checks_enabled': True, 09:28:13 INFO - 'sps_profile': False, 09:28:13 INFO - 'sps_profile_interval': 0, 09:28:13 INFO - 'suite': 'dromaeojs', 09:28:13 INFO - 'system_bits': '32', 09:28:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:28:13 INFO - 'title': 't-yosemite-r7-0301', 09:28:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:28:13 INFO - 'use_talos_json': True, 09:28:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:28:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:28:13 INFO - 'work_dir': 'build'} 09:28:13 INFO - ##### 09:28:13 INFO - ##### Running clobber step. 09:28:13 INFO - ##### 09:28:13 INFO - Running pre-action listener: _resource_record_pre_action 09:28:13 INFO - Running main action method: clobber 09:28:13 INFO - rmtree: /builds/slave/test/build 09:28:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:28:14 INFO - Running post-action listener: _resource_record_post_action 09:28:14 INFO - ##### 09:28:14 INFO - ##### Finished clobber step (success) 09:28:14 INFO - ##### 09:28:14 INFO - ##### 09:28:14 INFO - ##### Running read-buildbot-config step. 09:28:14 INFO - ##### 09:28:14 INFO - Running pre-action listener: _resource_record_pre_action 09:28:14 INFO - Running main action method: read_buildbot_config 09:28:14 INFO - Using buildbot properties: 09:28:14 INFO - { 09:28:14 INFO - "project": "", 09:28:14 INFO - "product": "firefox", 09:28:14 INFO - "script_repo_revision": "production", 09:28:14 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:28:14 INFO - "repository": "", 09:28:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:28:14 INFO - "buildid": "20160707082722", 09:28:14 INFO - "pgo_build": "False", 09:28:14 INFO - "basedir": "/builds/slave/test", 09:28:14 INFO - "buildnumber": 93, 09:28:14 INFO - "slavename": "t-yosemite-r7-0301", 09:28:14 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:28:14 INFO - "platform": "yosemite_r7", 09:28:14 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:28:14 INFO - "branch": "fx-team", 09:28:14 INFO - "repo_path": "integration/fx-team", 09:28:14 INFO - "revision": "e2f5a7c70b47be82f2a8f85cac59482c8f77550a", 09:28:14 INFO - "stage_platform": "macosx64", 09:28:14 INFO - "builduid": "e66344b6701b424499d55f672a9873c8", 09:28:14 INFO - "slavebuilddir": "test" 09:28:14 INFO - } 09:28:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:28:14 INFO - Running post-action listener: _resource_record_post_action 09:28:14 INFO - ##### 09:28:14 INFO - ##### Finished read-buildbot-config step (success) 09:28:14 INFO - ##### 09:28:14 INFO - ##### 09:28:14 INFO - ##### Running download-and-extract step. 09:28:14 INFO - ##### 09:28:14 INFO - Running pre-action listener: _resource_record_pre_action 09:28:14 INFO - Running main action method: download_and_extract 09:28:14 INFO - mkdir: /builds/slave/test/build/tests 09:28: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')]} 09:28:14 INFO - https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:28:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:28:18 INFO - Downloaded 1199 bytes. 09:28:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:28:18 INFO - Using the following test package requirements: 09:28:18 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:28:18 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:28:18 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:28:18 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'jsshell-mac.zip'], 09:28:18 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:28:18 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:28:18 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:28:18 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:28:18 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:28:18 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:28:18 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:28:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:28: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')]} 09:28:18 INFO - https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:28:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:28:21 INFO - Downloaded 18806173 bytes. 09:28:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:28:24 INFO - Return code: 0 09:28:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:28:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:24 INFO - https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:28:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:28:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:28:26 INFO - Downloaded 18806173 bytes. 09:28:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:28:29 INFO - Return code: 0 09:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:29 INFO - https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:28:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:28:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:28:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:28:31 INFO - Downloaded 14403333 bytes. 09:28:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:28:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:28:31 INFO - Return code: 0 09:28:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:31 INFO - https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:28:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:28:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:28:36 INFO - Downloaded 112423862 bytes. 09:28:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:36 INFO - mkdir: /builds/slave/test/properties 09:28:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:28:36 INFO - Writing to file /builds/slave/test/properties/build_url 09:28:36 INFO - Contents: 09:28:36 INFO - build_url:https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:28:37 INFO - Running post-action listener: _resource_record_post_action 09:28:37 INFO - Running post-action listener: set_extra_try_arguments 09:28:37 INFO - ##### 09:28:37 INFO - ##### Finished download-and-extract step (success) 09:28:37 INFO - ##### 09:28:37 INFO - ##### 09:28:37 INFO - ##### Running populate-webroot step. 09:28:37 INFO - ##### 09:28:37 INFO - Running pre-action listener: _resource_record_pre_action 09:28:37 INFO - Running main action method: populate_webroot 09:28:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:28:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:28:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:28:37 INFO - '--tppagecycles', 09:28:37 INFO - '7'], 09:28:37 INFO - 'tests': ['tsvgm']}}, 09:28:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:28:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:28:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:28:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:28:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:28:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:28:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['damp', 'tps']}, 09:28:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'tests': ['damp', 'tps']}, 09:28:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['dromaeo_dom']}, 09:28:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:28:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['basic_compositor_video']}, 09:28:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:28:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['a11yr', 09:28:37 INFO - 'ts_paint', 09:28:37 INFO - 'tpaint', 09:28:37 INFO - 'sessionrestore', 09:28:37 INFO - 'sessionrestore_no_auto_restore', 09:28:37 INFO - 'tabpaint']}, 09:28:37 INFO - 'other-e10s': {'tests': ['a11yr', 09:28:37 INFO - 'ts_paint', 09:28:37 INFO - 'tpaint', 09:28:37 INFO - 'sessionrestore', 09:28:37 INFO - 'sessionrestore_no_auto_restore', 09:28:37 INFO - 'tabpaint']}, 09:28:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:28:37 INFO - 'ts_paint', 09:28:37 INFO - 'tpaint', 09:28:37 INFO - 'sessionrestore', 09:28:37 INFO - 'sessionrestore_no_auto_restore', 09:28:37 INFO - 'tabpaint']}, 09:28:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:28:37 INFO - 'ts_paint', 09:28:37 INFO - 'tpaint', 09:28:37 INFO - 'sessionrestore', 09:28:37 INFO - 'sessionrestore_no_auto_restore', 09:28:37 INFO - 'tabpaint']}, 09:28:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['a11yr', 09:28:37 INFO - 'ts_paint', 09:28:37 INFO - 'tpaint', 09:28:37 INFO - 'sessionrestore', 09:28:37 INFO - 'sessionrestore_no_auto_restore', 09:28:37 INFO - 'tabpaint']}, 09:28:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['a11yr', 09:28:37 INFO - 'ts_paint', 09:28:37 INFO - 'tpaint', 09:28:37 INFO - 'sessionrestore', 09:28:37 INFO - 'sessionrestore_no_auto_restore', 09:28:37 INFO - 'tabpaint']}, 09:28:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['tsvgx', 09:28:37 INFO - 'tsvgr_opacity', 09:28:37 INFO - 'tart', 09:28:37 INFO - 'tscrollx', 09:28:37 INFO - 'cart']}, 09:28:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:28:37 INFO - 'tsvgr_opacity', 09:28:37 INFO - 'tart', 09:28:37 INFO - 'tscrollx', 09:28:37 INFO - 'cart']}, 09:28:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'talos_options': ['--disable-e10s'], 09:28:37 INFO - 'tests': ['tp5o']}, 09:28:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'tests': ['tp5o']}, 09:28:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'talos_options': ['--disable-e10s', 09:28:37 INFO - '--xperf_path', 09:28:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:28:37 INFO - 'tests': ['tp5n']}, 09:28:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:28:37 INFO - 'talos_options': ['--xperf_path', 09:28:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:28:37 INFO - 'tests': ['tp5n']}}, 09:28:37 INFO - 'talos.zip': {'path': '', 09:28:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:28:37 INFO - Running post-action listener: _resource_record_post_action 09:28:37 INFO - ##### 09:28:37 INFO - ##### Finished populate-webroot step (success) 09:28:37 INFO - ##### 09:28:37 INFO - ##### 09:28:37 INFO - ##### Running create-virtualenv step. 09:28:37 INFO - ##### 09:28:37 INFO - Running pre-action listener: _resource_record_pre_action 09:28:37 INFO - Running main action method: create_virtualenv 09:28:37 INFO - Creating virtualenv /builds/slave/test/build/venv 09:28:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:28:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:28:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:28:37 INFO - Using real prefix '/tools/python27' 09:28:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:28:38 INFO - Installing distribute.............................................................................................................................................................................................done. 09:28:40 INFO - Installing pip.................done. 09:28:40 INFO - Return code: 0 09:28:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:40 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 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:28:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:28:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:28:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:40 INFO - 'HOME': '/Users/cltbld', 09:28:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:40 INFO - 'LOGNAME': 'cltbld', 09:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:40 INFO - 'MOZ_NO_REMOTE': '1', 09:28:40 INFO - 'NO_EM_RESTART': '1', 09:28:40 INFO - 'PAGER': '/bin/cat', 09:28:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:40 INFO - 'PWD': '/builds/slave/test', 09:28:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:40 INFO - 'SHELL': '/bin/bash', 09:28:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:28:40 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:28:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:40 INFO - 'USER': 'cltbld', 09:28:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:40 INFO - 'XPC_FLAGS': '0x0', 09:28:40 INFO - 'XPC_SERVICE_NAME': '0', 09:28:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:28:41 INFO - Cleaning up... 09:28:41 INFO - Return code: 0 09:28:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:28:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:28:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:28:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:41 INFO - 'HOME': '/Users/cltbld', 09:28:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:41 INFO - 'LOGNAME': 'cltbld', 09:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:41 INFO - 'MOZ_NO_REMOTE': '1', 09:28:41 INFO - 'NO_EM_RESTART': '1', 09:28:41 INFO - 'PAGER': '/bin/cat', 09:28:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:41 INFO - 'PWD': '/builds/slave/test', 09:28:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:41 INFO - 'SHELL': '/bin/bash', 09:28:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:28:41 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:28:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:41 INFO - 'USER': 'cltbld', 09:28:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:41 INFO - 'XPC_FLAGS': '0x0', 09:28:41 INFO - 'XPC_SERVICE_NAME': '0', 09:28:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:41 INFO - Downloading/unpacking psutil>=3.1.1 09:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:28:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:28:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:28:44 INFO - Installing collected packages: psutil 09:28:44 INFO - Running setup.py install for psutil 09:28:45 INFO - building 'psutil._psutil_osx' extension 09:28:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:28:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:28:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:28:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:28:45 INFO - building 'psutil._psutil_posix' extension 09:28:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:28:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:28:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:28:45 INFO - ^ 09:28:45 INFO - 1 warning generated. 09:28:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:28:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:28:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:28:45 INFO - Successfully installed psutil 09:28:45 INFO - Cleaning up... 09:28:45 INFO - Return code: 0 09:28:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:28:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:28:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:28:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:45 INFO - 'HOME': '/Users/cltbld', 09:28:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:45 INFO - 'LOGNAME': 'cltbld', 09:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:45 INFO - 'MOZ_NO_REMOTE': '1', 09:28:45 INFO - 'NO_EM_RESTART': '1', 09:28:45 INFO - 'PAGER': '/bin/cat', 09:28:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:45 INFO - 'PWD': '/builds/slave/test', 09:28:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:45 INFO - 'SHELL': '/bin/bash', 09:28:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:28:45 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:28:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:45 INFO - 'USER': 'cltbld', 09:28:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:45 INFO - 'XPC_FLAGS': '0x0', 09:28:45 INFO - 'XPC_SERVICE_NAME': '0', 09:28:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:28: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 09:28: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 09:28: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 09:28: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 09:28: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 09:28: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 09:28:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:28:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:28:48 INFO - Installing collected packages: mozsystemmonitor 09:28:48 INFO - Running setup.py install for mozsystemmonitor 09:28:48 INFO - Successfully installed mozsystemmonitor 09:28:48 INFO - Cleaning up... 09:28:48 INFO - Return code: 0 09:28:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:48 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:48 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==2.5.1'] in /builds/slave/test/build 09:28:48 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==2.5.1 09:28:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:28:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:48 INFO - 'HOME': '/Users/cltbld', 09:28:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:48 INFO - 'LOGNAME': 'cltbld', 09:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:48 INFO - 'MOZ_NO_REMOTE': '1', 09:28:48 INFO - 'NO_EM_RESTART': '1', 09:28:48 INFO - 'PAGER': '/bin/cat', 09:28:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:48 INFO - 'PWD': '/builds/slave/test', 09:28:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:48 INFO - 'SHELL': '/bin/bash', 09:28:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:28:48 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:28:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:48 INFO - 'USER': 'cltbld', 09:28:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:48 INFO - 'XPC_FLAGS': '0x0', 09:28:48 INFO - 'XPC_SERVICE_NAME': '0', 09:28:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:49 INFO - Downloading/unpacking jsonschema==2.5.1 09:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:28:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:28:52 INFO - warning: no files found matching '*.txt' 09:28:52 INFO - no previously-included directories found matching 'build' 09:28:52 INFO - no previously-included directories found matching 'dist' 09:28:52 INFO - no previously-included directories found matching '.git*' 09:28:52 INFO - Installing collected packages: jsonschema, functools32 09:28:52 INFO - Running setup.py install for functools32 09:28:52 INFO - warning: no files found matching '*.txt' 09:28:52 INFO - no previously-included directories found matching 'build' 09:28:52 INFO - no previously-included directories found matching 'dist' 09:28:52 INFO - no previously-included directories found matching '.git*' 09:28:52 INFO - Successfully installed jsonschema functools32 09:28:52 INFO - Cleaning up... 09:28:52 INFO - Return code: 0 09:28:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:28: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')]} 09:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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')]} 09:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:52 INFO - 'HOME': '/Users/cltbld', 09:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:52 INFO - 'LOGNAME': 'cltbld', 09:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:52 INFO - 'MOZ_NO_REMOTE': '1', 09:28:52 INFO - 'NO_EM_RESTART': '1', 09:28:52 INFO - 'PAGER': '/bin/cat', 09:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:52 INFO - 'PWD': '/builds/slave/test', 09:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:52 INFO - 'SHELL': '/bin/bash', 09:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:28:52 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:52 INFO - 'USER': 'cltbld', 09:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:52 INFO - 'XPC_FLAGS': '0x0', 09:28:52 INFO - 'XPC_SERVICE_NAME': '0', 09:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:28:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:28:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:28:56 INFO - Downloading docopt-0.6.1.tar.gz 09:28:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:28:56 INFO - Installing collected packages: blobuploader, requests, docopt 09:28:56 INFO - Running setup.py install for blobuploader 09:28:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:28:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:28:56 INFO - Running setup.py install for requests 09:28:56 INFO - Running setup.py install for docopt 09:28:57 INFO - Successfully installed blobuploader requests docopt 09:28:57 INFO - Cleaning up... 09:28:57 INFO - Return code: 0 09:28:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:28: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')]} 09:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:28:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28: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')]} 09:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:28:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:28:57 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 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:28:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:28:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:28:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:28:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:28:57 INFO - 'HOME': '/Users/cltbld', 09:28:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:28:57 INFO - 'LOGNAME': 'cltbld', 09:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:28:57 INFO - 'MOZ_NO_REMOTE': '1', 09:28:57 INFO - 'NO_EM_RESTART': '1', 09:28:57 INFO - 'PAGER': '/bin/cat', 09:28:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:28:57 INFO - 'PWD': '/builds/slave/test', 09:28:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:28:57 INFO - 'SHELL': '/bin/bash', 09:28:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:28:57 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:28:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:28:57 INFO - 'USER': 'cltbld', 09:28:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:28:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:28:57 INFO - 'XPC_FLAGS': '0x0', 09:28:57 INFO - 'XPC_SERVICE_NAME': '0', 09:28:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:28:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-tCLISa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:28:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RE5X66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:28:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Bpe7EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:28:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-qBn386-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:28:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-qRjFti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:28:57 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ay6Y0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-djGpve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-xlUaAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-9wuxU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-d3WmqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-IvQfni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-hatx2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-TlejNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-67thR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-evGY5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:28:58 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-GiVeQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:28:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:28:59 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-XksNgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:28:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:28:59 INFO - Running setup.py install for manifestparser 09:28:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:28:59 INFO - Running setup.py install for mozcrash 09:28:59 INFO - Running setup.py install for mozdebug 09:28:59 INFO - Running setup.py install for mozdevice 09:28:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:28:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:28:59 INFO - Running setup.py install for mozfile 09:28:59 INFO - Running setup.py install for mozhttpd 09:29:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Running setup.py install for mozinfo 09:29:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Running setup.py install for mozInstall 09:29:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Running setup.py install for mozleak 09:29:00 INFO - Running setup.py install for mozlog 09:29:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Running setup.py install for moznetwork 09:29:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:29:00 INFO - Running setup.py install for mozprocess 09:29:00 INFO - Running setup.py install for mozprofile 09:29:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:29:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:29:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:29:01 INFO - Running setup.py install for mozrunner 09:29:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:29:01 INFO - Running setup.py install for mozscreenshot 09:29:01 INFO - Running setup.py install for moztest 09:29:01 INFO - Running setup.py install for mozversion 09:29:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:29:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:29:01 INFO - Cleaning up... 09:29:01 INFO - Return code: 0 09:29:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29: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')]} 09:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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')]} 09:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:01 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 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:29:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:29:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:29:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:29:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:01 INFO - 'HOME': '/Users/cltbld', 09:29:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:01 INFO - 'LOGNAME': 'cltbld', 09:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:01 INFO - 'MOZ_NO_REMOTE': '1', 09:29:01 INFO - 'NO_EM_RESTART': '1', 09:29:01 INFO - 'PAGER': '/bin/cat', 09:29:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:01 INFO - 'PWD': '/builds/slave/test', 09:29:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:01 INFO - 'SHELL': '/bin/bash', 09:29:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:29:01 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:29:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:01 INFO - 'USER': 'cltbld', 09:29:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:01 INFO - 'XPC_FLAGS': '0x0', 09:29:01 INFO - 'XPC_SERVICE_NAME': '0', 09:29:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Jt9w5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-6NwfcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-kbjKat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-DOJ80e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RFFi49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-NbKs6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-IIM13_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-UoE18A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:02 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-2JtcWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-t_jSXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-xLXmcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-I9rGAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-nDTNpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-rWvfXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-9EQrjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Vb3vuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:03 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-uKCojB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:29:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:06 INFO - Downloading blessings-1.6.tar.gz 09:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:29:06 INFO - Installing collected packages: blessings 09:29:06 INFO - Running setup.py install for blessings 09:29:06 INFO - Successfully installed blessings 09:29:06 INFO - Cleaning up... 09:29:06 INFO - Return code: 0 09:29:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:07 INFO - Reading from file tmpfile_stdout 09:29:07 INFO - Current package versions: 09:29:07 INFO - blessings == 1.6 09:29:07 INFO - blobuploader == 1.2.4 09:29:07 INFO - docopt == 0.6.1 09:29:07 INFO - functools32 == 3.2.3-2 09:29:07 INFO - jsonschema == 2.5.1 09:29:07 INFO - manifestparser == 1.1 09:29:07 INFO - mozInstall == 1.12 09:29:07 INFO - mozcrash == 0.17 09:29:07 INFO - mozdebug == 0.1 09:29:07 INFO - mozdevice == 0.48 09:29:07 INFO - mozfile == 1.2 09:29:07 INFO - mozhttpd == 0.7 09:29:07 INFO - mozinfo == 0.9 09:29:07 INFO - mozleak == 0.1 09:29:07 INFO - mozlog == 3.2 09:29:07 INFO - moznetwork == 0.27 09:29:07 INFO - mozprocess == 0.23 09:29:07 INFO - mozprofile == 0.28 09:29:07 INFO - mozrunner == 6.12 09:29:07 INFO - mozscreenshot == 0.1 09:29:07 INFO - mozsystemmonitor == 0.3 09:29:07 INFO - moztest == 0.7 09:29:07 INFO - mozversion == 1.4 09:29:07 INFO - psutil == 3.1.1 09:29:07 INFO - requests == 1.2.3 09:29:07 INFO - wsgiref == 0.1.2 09:29:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:07 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 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:07 INFO - 'HOME': '/Users/cltbld', 09:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:07 INFO - 'LOGNAME': 'cltbld', 09:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:07 INFO - 'MOZ_NO_REMOTE': '1', 09:29:07 INFO - 'NO_EM_RESTART': '1', 09:29:07 INFO - 'PAGER': '/bin/cat', 09:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:07 INFO - 'PWD': '/builds/slave/test', 09:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:07 INFO - 'SHELL': '/bin/bash', 09:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:29:07 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:07 INFO - 'USER': 'cltbld', 09:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:07 INFO - 'XPC_FLAGS': '0x0', 09:29:07 INFO - 'XPC_SERVICE_NAME': '0', 09:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:29:07 INFO - Cleaning up... 09:29:07 INFO - Return code: 0 09:29:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:07 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 0x107c74a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d09618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a959fb90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, '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.BK33XtxlNj/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.2R5I6CGv4K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:07 INFO - 'HOME': '/Users/cltbld', 09:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:07 INFO - 'LOGNAME': 'cltbld', 09:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:07 INFO - 'MOZ_NO_REMOTE': '1', 09:29:07 INFO - 'NO_EM_RESTART': '1', 09:29:07 INFO - 'PAGER': '/bin/cat', 09:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:07 INFO - 'PWD': '/builds/slave/test', 09:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:07 INFO - 'SHELL': '/bin/bash', 09:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:29:07 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:07 INFO - 'USER': 'cltbld', 09:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:07 INFO - 'XPC_FLAGS': '0x0', 09:29:07 INFO - 'XPC_SERVICE_NAME': '0', 09:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:29:07 INFO - Cleaning up... 09:29:07 INFO - Return code: 0 09:29:07 INFO - Running post-action listener: _resource_record_post_action 09:29:07 INFO - Running post-action listener: _start_resource_monitoring 09:29:07 INFO - Starting resource monitoring. 09:29:07 INFO - ##### 09:29:07 INFO - ##### Finished create-virtualenv step (success) 09:29:07 INFO - ##### 09:29:07 INFO - ##### 09:29:07 INFO - ##### Running install step. 09:29:07 INFO - ##### 09:29:07 INFO - Running pre-action listener: _resource_record_pre_action 09:29:07 INFO - Running main action method: install 09:29:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:07 INFO - Reading from file tmpfile_stdout 09:29:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:29:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:29:07 INFO - Reading from file tmpfile_stdout 09:29:07 INFO - Output received: 09:29:07 INFO - Usage: mozinstall [options] installer 09:29:07 INFO - Options: 09:29:07 INFO - -h, --help show this help message and exit 09:29:07 INFO - -d DEST, --destination=DEST 09:29:07 INFO - Directory to install application into. [default: 09:29:07 INFO - "/builds/slave/test"] 09:29:07 INFO - --app=APP Application being installed. [default: firefox] 09:29:07 INFO - mkdir: /builds/slave/test/build/application 09:29:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:29:43 INFO - Reading from file tmpfile_stdout 09:29:43 INFO - Output received: 09:29:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:29:43 INFO - Running post-action listener: _resource_record_post_action 09:29:43 INFO - ##### 09:29:43 INFO - ##### Finished install step (success) 09:29:43 INFO - ##### 09:29:43 INFO - ##### 09:29:43 INFO - ##### Running run-tests step. 09:29:43 INFO - ##### 09:29:43 INFO - Running pre-action listener: _resource_record_pre_action 09:29:43 INFO - Running main action method: run_tests 09:29:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:29:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:29:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:29:43 INFO - 2016-07-07 09:29:43.162 screenresolution[2326:22518] starting screenresolution argv=screenresolution get 09:29:43 INFO - 2016-07-07 09:29:43.164 screenresolution[2326:22518] Display 0: 1600x1200x32@60 09:29:43 INFO - 2016-07-07 09:29:43.170 screenresolution[2327:22521] starting screenresolution argv=screenresolution list 09:29:43 INFO - Available Modes on Display 0 09:29:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:29:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:29:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:29:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:29:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:29:43 INFO - Intel Iris: 09:29:43 INFO - Chipset Model: Intel Iris 09:29:43 INFO - Type: GPU 09:29:43 INFO - Bus: Built-In 09:29:43 INFO - VRAM (Dynamic, Max): 1536 MB 09:29:43 INFO - Vendor: Intel (0x8086) 09:29:43 INFO - Device ID: 0x0a2e 09:29:43 INFO - Revision ID: 0x0009 09:29:43 INFO - Displays: 09:29:43 INFO - Display: 09:29:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:29:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:29:43 INFO - Display Serial Number: 1600x1200 60 09:29:43 INFO - Main Display: Yes 09:29:43 INFO - Mirror: Off 09:29:43 INFO - Online: Yes 09:29:43 INFO - Rotation: Supported 09:29:43 INFO - Return code: 0 09:29:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:29:43 INFO - Python 2.7.3 09:29:43 INFO - Return code: 0 09:29:43 INFO - grabbing minidump binary from tooltool 09:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:43 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bd8d30>, '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 0x10793ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107bd0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:29:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:29:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:29:43 INFO - Calling ['/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'] with output_timeout 600 09:29:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:29:43 INFO - Return code: 0 09:29:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:29:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:29:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:29:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:29:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:29:43 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/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:29:43 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/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0301 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BK33XtxlNj/Render', 09:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:43 INFO - 'HOME': '/Users/cltbld', 09:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:43 INFO - 'LOGNAME': 'cltbld', 09:29:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:29:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:43 INFO - 'MOZ_NO_REMOTE': '1', 09:29:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:29:43 INFO - 'NO_EM_RESTART': '1', 09:29:43 INFO - 'PAGER': '/bin/cat', 09:29:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:43 INFO - 'PWD': '/builds/slave/test', 09:29:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:43 INFO - 'SHELL': '/bin/bash', 09:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2R5I6CGv4K/Listeners', 09:29:43 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 09:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:43 INFO - 'USER': 'cltbld', 09:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:43 INFO - 'XPC_FLAGS': '0x0', 09:29:43 INFO - 'XPC_SERVICE_NAME': '0', 09:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:43 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/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:29:43 INFO - mozversion application_buildid: 20160707082722 09:29:43 INFO - mozversion application_changeset: e2f5a7c70b47be82f2a8f85cac59482c8f77550a 09:29:43 INFO - mozversion application_display_name: Nightly 09:29:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:29:43 INFO - mozversion application_name: Firefox 09:29:43 INFO - mozversion application_remotingname: firefox 09:29:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:29:43 INFO - mozversion application_vendor: Mozilla 09:29:43 INFO - mozversion application_version: 50.0a1 09:29:43 INFO - mozversion platform_buildid: 20160707082722 09:29:43 INFO - mozversion platform_changeset: e2f5a7c70b47be82f2a8f85cac59482c8f77550a 09:29:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:29:43 INFO - mozversion platform_version: 50.0a1 09:29:43 INFO - using testdate: 1467908983 09:29:43 INFO - actual date: 1467908983 09:29:43 INFO - starting webserver on 'localhost:53351' 09:29:43 INFO - SUITE-START | Running 2 tests 09:29:43 INFO - TEST-START | dromaeo_css 09:29:43 INFO - operating with platform_type : mac_ 09:29:43 INFO - Initialising browser for dromaeo_css test... 09:29:43 INFO - TEST-INFO | started process 2337 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpRmhgXN/profile http://localhost:53351/getInfo.html) 09:29:46 INFO - PROCESS | 2337 | __metrics Screen width/height:1600/1200 09:29:46 INFO - PROCESS | 2337 | colorDepth:24 09:29:46 INFO - PROCESS | 2337 | Browser inner width/height: 1024/689 09:29:46 INFO - PROCESS | 2337 | __metrics 09:29:46 INFO - PROCESS | 2337 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:29:46 INFO - PROCESS | 2337 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:29:46 INFO - TEST-INFO | 2337: exit 0 09:29:46 INFO - Browser initialized. 09:29:46 INFO - Running cycle 1/1 for dromaeo_css test... 09:29:46 INFO - TEST-INFO | started process 2339 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpRmhgXN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:58 INFO - PROCESS | 2339 | RSS: Main: 243822592 09:29:58 INFO - PROCESS | 2339 | 09:30:50 INFO - PROCESS | 2339 | 1467909050117 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:33:01 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:53351/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53351/tests/dromaeo/cssquery-ext.html) 09:33:02 INFO - PROCESS | 2339 | RSS: Main: 374259712 09:33:02 INFO - PROCESS | 2339 | 09:36:05 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:53351/tests/dromaeo/cssquery-ext.html (next: http://localhost:53351/tests/dromaeo/cssquery-jquery.html) 09:36:06 INFO - PROCESS | 2339 | RSS: Main: 379625472 09:36:06 INFO - PROCESS | 2339 | 09:39:08 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:53351/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53351/tests/dromaeo/cssquery-mootools.html) 09:39:09 INFO - PROCESS | 2339 | RSS: Main: 377217024 09:39:09 INFO - PROCESS | 2339 | 09:42:11 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:53351/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53351/tests/dromaeo/cssquery-prototype.html) 09:42:12 INFO - PROCESS | 2339 | RSS: Main: 373276672 09:42:12 INFO - PROCESS | 2339 | 09:45:15 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:53351/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53351/tests/dromaeo/cssquery-yui.html) 09:45:16 INFO - PROCESS | 2339 | RSS: Main: 344584192 09:45:16 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:53351/tests/dromaeo/cssquery-yui.html (next: http://localhost:53351/tests/dromaeo/cssquery-dojo.html) 09:48:19 INFO - PROCESS | 2339 | RSS: Main: 389857280 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | __start_tp_report 09:48:19 INFO - PROCESS | 2339 | _x_x_mozilla_page_load 09:48:19 INFO - PROCESS | 2339 | _x_x_mozilla_page_load_details 09:48:19 INFO - PROCESS | 2339 | |i|pagename|runs| 09:48:19 INFO - PROCESS | 2339 | |0;dojo.html;2688.6504754381926;2704.9594256086175;2786.8188567743096;3334.2497937963954;3379.7803142795715;5527.668339899609;5584.441555844413;5591.1054231323;5607.4112218217115;5690.118031705088;5638.295213098363;5668.801591944282;5676.318841738997;5681.431856814316;5708.286464191976;4420.668449866257;5136;5175.430702622709;5263.0526505229045;5267.23625074364;5163.948360516403;5267.420583735786;5268.367795864501;5275.419703832576;5283.128283833182;4554.975130595622;4833.879153021167;5172.586193104561;5206.271122042917;5264.315638966929;5266.078436273656;5275.683458992473;5280.313559237313;5283.973580132113;5352.812651557216;5050.090983622947;5061.291419201315;5139.203423469368;5190.662606930538;5205.791768329263;5036.622253331015;5154.20109882965;5593.776248950037;5640.266765320482;5657.066584013633;6160.4455598995855;6179.19670442844;6186.505079593621;6192.040233763774;6207.81376558704;5500.697461639608;6129.69351532426;6148.538859585504;6165.630062196283;6185.56701030928;5770.7403166857175;5799.681017544076;5807.274090738658;5829.47534721877;5832.883342333172;6127.20346354971;6184;6193.535484838655;6208.441240288305;6209.2548894133;5932.940670593325;5986.191864098378;6044.848878778066;6135.938640613536;6147.323794382614;6179.567430279927;6231.096491008779;6241.438270555672;6245.156903817927;6273.811785646438;6181.567290289727;6190.876182476415;6192.256929168529;6194.690265486798;6206.255249370014;6047.637141771508;6059.333473317932;6115.633062016204;6235.314115447297;6236.719347629368;4901.460839307673;4934.802607895644;4942.308076869242;4944.802207911715;4951.3810773653295;4466.330050492452;4486.887827804331;4494.12364588902;4496.437945256843;4499.842505512292;5676.460736230051;5678.375378708339;5680.744366503517;5694.743736531866;5696.174054762037;6127.18952814863;6151.66165860873;6166.290876549165;6223.813285601439;6247.156633854463;5715.2855893013375;5718.142278658152;5733.3406658234;5738.856528586819;5773.018586840247;5413.512783849394;5430.864228394243;5433.239346491494;5434.755436005389;5437.102878025082;6221.595596286227;6223.097650840603;6237.906431403442;6274.592151510137;6276.02721578147;6145.938540614537;6188;6217.316095229611;6255.593386429868;6276.74893004293;6209.906851397142;6215.378462153749;6219.40915613016;6226.40849119333;6238.407351301619;6000;6135.202423684892;6144.354842741448;6161.907571386343;6184.412480814315;6114.266954506099;6139.614800607497;6153.4154255345675;6211.223597050369;6275.560710750204;6056.024944178904;6063.636181829104;6088.939110609014;6104.519788040188;6123.510119190543;5570.053090974463;5867.882642347215;6064.363241859542;6072.362401947908;6086.330503644507;5801.535877129735;5875.3243377010485;5938.673372964528;5963.224780778644;6003.939960600338;6086.870191114421;6119.868162944114;6128.044828652092;6132.695218635937;6133.426475724657;5768.423157684198;5956.13636022783;5970.820875373745;5972.552058595709;6021.337652858269 09:48:19 INFO - PROCESS | 2339 | |1;ext.html;462.9953700462995;481.8120932836192;516.3906590223535;533.3173537871526;536.7975734355045;24609.26172214834;30872.845635771817;35249.590016399314;40726.38910416346;41865.58134418655;42986.92532686685;43971.90070248245;44175.77912110435;44214.11571768563;44297.55702442975;9634.229261659086;9795.363301385423;9799;9801;9804.068613481708;8653.610587523544;8871.334649901282;8877.822443551126;8899.02110767815;8920.50937198457;11762.52949882008;12020.69948251292;12029.819552706716;12254.938725306405;12277.631671049838;11969.700757481045;12077.758444831143;12081.87918120825;12085.395730213542;12087.093467989893;8600.311975041981;11391.373474458902;11790.23363481371;11904.16670833042;11977.820332695017;41800;41946.37080443796;42048.78975605133;42083.15833683309;42138.52515161955;3602.475210616571;3671.834767435445;3808.009917421463;4183.937240941388;4233.957660423387;19821.306254281175;25208.51080690213;25327.87336063308;32902.83548582242;33258.83370583132;2895.4788138135127;3006.428778532094;3015.969840301591;3017.1250337402125;3121.4693502104697;544964;546047;546623.266883667;548063;548249;60216;61139;61267.38732612706;61291;61465.07802382968;63131.684341577995;63163.684181579716;63256.36743632597;63263.989920957436;63330.68334658297;15991.28039238237;16555.834441655672;16572.419965301156;16574.502764917073;16636.001839889646;13005.21968681882;13022.47910083586;13032.80450793239;13064.412101455457;13103.868961310456;14285.85713142946;14639.634009149857;15932;16242.75635865463;16361.754573681405;7088.574685518885;7124.893126603105;7156.856862862714;7208.819779505449;7243;911.8541033434618;980.9950950245346;1033.9793204136026;1045.29442626227;1050.4537640426936;29110.563341549892;30353.93761218347;30525.236869078006;30580;31064.68935310618;1033.5194134727387;1059.994700026495;1073.9677809665723;1132.5809450503366;1135.8239472881717;12514.49942002328;13919;15979.680406392039;16247.268872900748;16276.674466510603;693108;697709;701894;703079;703212;60433.69783151144;60463;60590.39409605935;60657.39342606605;60797.39202608006;1374.979375309371;1404.8243969503812;1436.7629341158593;1437.3244575049919;1438.3024233247077;27165;30753.231169220948;30778.846105769328;31090.03889616606;31624.36751265008;52195.47804522058;52229.73885130702;52517.7374113127;52599.47400525946;52695.73652131715;630434;633032;634212;635862;636299;42141.78929105335;42719;43041;43181.784091079346;43197.78401107974;36383.636163638024;41083.58916410798;48194;51736.965260693825;52946.2058069137;39963.60036399715;41062.64120962265;41820.79089604533;41940;42234.78882605567;40681.59318406897;40762.38856417216;40943;41145.79427102965;42371.7881410591;39267.60732392639;39598.40602390909;39863;39879.20241595094;39882.60117398789;33508.8271910482;34463.96608101761;35509.17981729626;35896.820515897256;36243.27513449769;26092.217233483025;26232;26443.603345949443;26590;26804.06185783488 09:48:19 INFO - PROCESS | 2339 | |2;jquery.html;371.4984770559744;380.65550676637645;381.90643292393366;392.4603669953814;398.3844959537514;18361.45781328008;20836;22091.116355345788;22224.77775222247;22259.220927267528;10293.330933489318;11867.940660296686;12312.938435307811;12324.445399957001;17312.740308895376;5483.479069488402;5597.076482380402;5654.660720356771;5662.348829884575;5664.376918538957;3986.4817573715522;4142.2543942090415;4152.294110001307;6888.965555172217;6919.8962015569805;6893.551919125266;6901.896471552931;6920.858058420355;6932.757353492629;6933.098697169361;5662.122371032497;5845.970770146143;6163.907541386883;6164.229471316085;6164.537659675542;3819.3698039823394;5727.284089488814;5988.041913293852;6050.27396712393;6066.090086487018;13531.39108740109;13621.931890340584;13635.181889086687;23256;26410.339741506425;1893.5266183454137;2144.978550214509;2200.8129309008755;2375.168690958168;2851.458222937635;10298.227632927561;10526.157907367393;10569.73575660607;10863.18526110545;16377.426790062409;1691.2727527163315;1855.656703509855;1926.6532024235632;1927.2772710233662;1943.543267332176;106679.46660266726;115632;115644.42177789057;115777.42111289474;116818;11010.339379637247;11441;11730.824037639442;11802.46888890002;12073.818892716616;14519.709605807824;14572.927135364256;14647;14661.120332780067;14669.853301466848;7705.075390953121;8043.316318112909;8761.167689069529;8813.911860881437;8825.911740882637;7489.176190619137;8615.396922215385;8750.82498350042;8781.25359344463;8924.018357980616;3755.9061023474087;4467.620252278528;4529.32060190974;4551.340055691906;4587.63298936091;1576.4482431149006;1893.3184053740642;1893.725409815569;1896.1183049881872;1896.6396384686866;896.3859756067114;931.9161275485238;948.4025064209504;954.2985905359556;973.5375696544227;20515.974201289875;21232.36302910915;21390.679139812913;21437.46406339854;21537.80482357085;926.7266156483853;944.093670076734;958.322934863988;968.2205824311496;969.6121551379505;10773.353598784099;11365.54537818478;11376.658700239006;11427.48576314068;11439.59961401347;569085;587064;595673;611798;613003;134448;142768;142778;143790;144385;949.3259785552198;950.8098380323963;964.6961207219704;966.7341481092756;1001.2540657210425;18108.90945545264;18852.34016809405;19140.521486962938;19162;19172.98683638198;44124.117517648825;56908;57411.71294143503;57606.847863041665;57949.71025144847;83560;84323;85140.14859851322;85280.294394113;86119.8470038254;5540.196671482693;5658.44903656794;6020.916235077728;7918.129005809241;8576.142385761623;10965.396903170084;13023.283719395524;13087.869121309046;13289;13594.796078059035;11552.537898483966;11558.768824623292;11721.355325457182;11760;11777.411129443664;10710.839337410102;11057.612983545538;11345.035671967646;11398.886011140114;11658.6502404928;11617.477213525242;11955.701107472383;11998.940005299917;12006.579769708018;12008.279503229835;8171.7548473545885;8241.876371854547;8270.627821748156;8338.87491687613;8502.404831661725;4690.085433340466;4803.927941080817;10314.948425257826;11369.090472761995;11637.301761894314 09:48:19 INFO - PROCESS | 2339 | |3;mootools.html;1369.630199846041;1509.554681368996;1550.953471395858;1741.9738703919443;1921.3275353626225;7909.564973926432;8631;8855.690050848214;8858.247049000827;8990.325725570576;8569.057403685605;8669.526180549306;8746.518941458216;8748.650053997833;8754.299656027519;2064.1743302678974;2146.4955735402245;2298.310506847943;2328.685627440291;2333.603287441139;2016.4656366063004;2316.6409206573007;2395.988020059906;2398.796102331304;2398.8200884933613;2334.6964894563766;2359.221456919221;2364.6098393765096;2369.052379048377;2385.057902128658;1754.359658724569;2248.2243625949022;2333.1717240379676;2347.448349637834;2354.3643216331493;2337.719473663154;2385.403649087728;2387.0809738250723;2387.4866903615634;2392.5214957008493;8675.696350627697;8740.650373985032;8743.169398907094;8751.912480875173;8763.474191548528;5162.45794191612;5278.076336641111;5291.312129423189;5294.788208471657;5312.9734351328;3109.2226943264186;3426.674465925733;3437.5187473753695;3623.1123374773088;3636.7999760013454;1069.5080263078914;1071.6035067025173;1085.1535802074395;1086.2450596835204;1101.9173561982802;11472;11717.765644687144;11718.945294923498;11728.944395004406;11730.765384692344;4514.796834142471;4545.795439205243;4739.2891066339635;4752.334673145762;4752.453467851172;10115.848262276071;10135.949320253352;10142.695719128438;10148.949255253676;10184.694459166238;5313.0436521425745;5315.654482458628;5321.813736519203;5327;5327.413984461706;4087.8734968876893;4296.828126874952;4454.309582014793;4456.041950980555;4876.683015603974;3460.1349662584353;3635.2911182319194;3825.923481530354;4034.3343348347194;4088.182363527247;1256.6104507602672;1267.5753622536554;1284.0947132271726;1284.389914790467;1286.6397408725572;781.316348195329;817.3461231015164;888.626776753765;934.3086116273909;970.315927271272;5735.340435849847;5916.319623243296;6000;6064.878702425926;6085.5435842312;927.1562877781322;934.2199263614957;949.6343907595488;951.5432592355708;988.991228946485;3382.712469440076;3962.128331767007;4034.717569770147;4094.733842300241;4161.334186530141;10295.588176472857;10776.191785615953;10785.298955567863;10791.244612877023;10852;10540.209484288556;10721.83917241242;10722.839157412645;10733.624323148651;10737.78524429507;8101.068377136587;8215.671373145127;8230.753077407688;8240.175982401832;8241.917580824234;5555.2778138841695;5880.382559831327;5881.5588830837005;5917.556183286185;5962.105684147239;9802.75493112678;9907.356021858555;10086.445245511273;10141.036601522843;10183.745406364604;9916;10239.027292407209;10258.538365773707;10296.639617613348;10327.380357178594;6991.405730512964;7039.648017598997;7109.116084237785;7116.786496405116;7120.394766444911;8107.797305067421;8190.262876341158;8269.875951860606;8295.460795048302;8296.958515207385;7152.570845749272;7454.440916931143;7463;7475.028246327943;7547.660355283915;7359.816004599714;7385.9261407387385;7428;7495;7551.962240188764;6775;6877.862442751217;6920.861582768417;6925.722971081101;6943.444524438134;4851.611871050237;4913.189323761682;4924.310596516562;4979.626528010486;5062.291279220838;5051.469595692546;5058.721770302522;5106.208537676666;5147.562457191031;5245.186996015471 09:48:19 INFO - PROCESS | 2339 | |4;prototype.html;300.8856634478898;307.9153232860963;309.995350069749;312.5967501922519;317.55542240862803;5440.972795136024;5472.93277810827;5495.120780675093;5500.752466139023;5552.361478429972;5355.223492593572;5356.553730492765;5450.809221677242;5459.317585301837;5472.315960504937;3929.5088113985753;3966.603339666039;4088.6524645405175;4103.671706263507;4243.236217480852;4084.97957510212;4104.97947510262;4164.562720914306;4191.392248124021;4213.51544572375;3384.4415671414185;3886.106195575005;3979.323515002442;3992.2414741199077;4075.551689314173;3743.419769935664;4036.6367026967714;4115.456703736099;4163.292240319153;4186.413902053715;3644.125409901631;3649.197176621139;3650.1969566695284;3654.798986055766;3676.7977761223124;4796.04079184161;5256.553192978601;5382.59630527708;5384.488473595002;5396.83809485716;4004.4593979812935;4628.352030715702;4720.386349774507;4826.879328016793;4894.192458244385;4642.048380082075;4984.725840078794;5242.79028838846;5529.972350138264;5669.688167150805;3446.327966046622;3468.809215493147;3665.9552027671984;3720.1815600567834;3959.9604003960167;4805.548674758484;5912.556558258148;5987.970060149715;5995.400459954013;6004.3395226525045;3793.7942438639307;4054.736442131252;4060.553339132693;4089.652379547772;4118.279301122292;4149;4159.251334759772;4172.436721042681;4180.205760905418;4192.371144328315;3791.8862434127022;4561.221123761736;4821.252705830602;4833.564979151823;4866.004937975413;4605.562471565196;4619;5655.406182350876;5772.913406298909;5854.590178687451;1285.2673975833684;1413.6324555615613;1419.8012278281049;1427.5217802036434;1432.4699861051477;765.4450523370623;810.2626609785067;835.7618078847499;882.6734108379812;903.1826197291462;4685.6017238535105;4689.390379250675;4755.857324280277;5313.0967735485065;5330.773921997962;5445.754941027663;5870.827793716352;5871.089981052944;5909.704514774244;6012.458878700938;4269.316909294498;4466.307722303049;4639.638545436932;5047.848564543069;5363.517283444508;3064.417760625474;3070.4473194825146;3103.239706271933;3106.7980581262145;3109.176068341846;5357.473120160814;6035.517158627299;6066.514678825683;6107.908381374283;6190.350013248634;4864.8783780405065;4905.541593176802;5090.77091530882;6061.212042434456;6099.329073801879;6034.577579569475;6062.787802426894;6112.694365281629;6128.540359473057;6145.84635384119;5482.834280459276;5792.275965504312;5826.41735826414;5827.796027139199;5861.501772349398;5680.318361796611;5722.941255867678;5730.656160630376;5740.827775166752;5771.884562308815;5962.4633782959745;6040.003399439043;6053.182820319376;6132.945463269676;6191.288001879841;3820.3696390095324;4033.91932161361;4050.4734384529825;4053.8986525337104;4067.2272268268935;5128.7179205144075;5163.148080566814;5551.196645693899;5912.640092778771;5980.9401905980385;4660.370849935215;5491.560675145899;5741.363711342247;5760.337561180518;5796.608983744557;5005.649604527794;5671.6597004179885;5693.857653558694;5712.80005199816;5736.856578585569;4549.906063876596;4925.9655472351205;5513.566472717042;5575.609707320448;5699.5155411790465;5144.356955380577;5496.642718223303;5724.738728202254;5729.828105156852;5749.367569567261;4766.856994290177;5600.4959553640365;5625.971870140623;5626.774929002958;5640.502888209337 09:48:19 INFO - PROCESS | 2339 | |5;yui.html;730.6346826586706;758.0600055930644;773.4585789947039;844.9746507604774;857.7684025313164;17474.03892785896;18155.818441815576;18170.545736356566;18217.27130914765;18246.27014919403;16883.409080682177;16923.407680731176;17131.91434042828;17175;17266.482005539823;876.2026555834208;895.6462197432011;895.807401408697;896.4397251717677;896.6144557840126;712.7291629180904;725.4776560876161;727.9017332660076;738.3613174603955;738.5605564689001;763.362592235484;905.2848249882585;922.8015976565038;927.4249965021696;944.5418971798672;923.3398120343919;933.4679232837285;936.0826390137669;936.6487567162314;937.2782957122977;552.1221258199442;691.1982100763091;691.7751730687546;693.0885885061128;693.6323748413349;13667.658308542266;15337.46318878845;15990.360385584563;16049.037057776453;16256.268467918853;12648.114631975764;13071.281079540622;14590.197539135343;14611.853881461155;14650.413983440649;11523.423828808525;11793.469293881795;11979.341136237503;12409.813852792215;12479.625611231677;3763.604821493731;3801.3917773156163;3803.9809800951093;3813.504244448232;3938.4683067786054;95514.52242738812;98563.50718246435;101748;102093;109295.45352273267;10717.196210284259;10770.138388929023;10806.51370688321;10843.83734243987;11060.17048721349;22120.2257920972;22149;22197.223097191843;22214.444638883833;22262.666060009113;7982.481138725964;8042.758717238492;8057.194280572013;8115.837683246302;8165.510069395856;4472.487335914263;4561.384213131251;4569.817207311737;4611.5619016193405;4750.976245118753;3447.6897079262985;3527.617268431796;3552.662497062775;3560.8219589020446;3565.5186549815962;637.1175921348872;644.6647743173523;646.1438593863151;652.2140820311513;659.7723785294066;541.7914103070347;624.9000159974382;666.160637596622;694.6665600511682;703.8908969109796;9168.81662366749;9204.63181472747;9205.493697846683;9382.343235973553;9474.810503789886;611.1779656362186;618.6288227063816;619.3094699410216;622.9657368844667;630.3570358234575;4371.388005679144;4536.705114167568;4539.636829053734;4596.954030459719;4650.558196971282;88517.55741221253;89316.5534172325;113287.43356283166;131626;141807;125254.74745252614;126390.73609263974;126760;128615;128900;99090.00909990807;103417.96582034233;104841;105138;105451.94548054421;7869.48848324857;8652.956735216283;8684.131586841266;9015.954920225357;9053.864192036994;51551;58786.11820822606;60126.79746405135;60789;60820;86306.56846715727;93510.06489935014;93706.53146734223;94049.76235954755;95120.57319140357;10376.533056012346;11801.17391782567;11941;12019.519219231133;12212;13115.868841311465;13133.671658208621;13298.335083245993;13500.662483437993;13909.930450347683;12188.268703877795;12298.446569904198;12365.526894621291;12396.938015309866;12436.37818109109;12058.879411205775;12288.877111228774;12411.069169812481;12547.435365408395;12675.366231688195;11619.01238394746;11760;12112.878871211527;12549.43527541281;12612.810807837706;9524.85712714296;10096.495175241356;10223.131033861908;10353.585856565653;10409.323393979366;10068.44623545712;10379.325343852626;10383.792324153323;10406.479676016017;10419.42693151884 09:48:19 INFO - PROCESS | 2339 | __end_tp_report 09:48:19 INFO - PROCESS | 2339 | __start_cc_report 09:48:19 INFO - PROCESS | 2339 | _x_x_mozilla_cycle_collect,1286 09:48:19 INFO - PROCESS | 2339 | __end_cc_report 09:48:19 INFO - PROCESS | 2339 | __startTimestamp1467910099572__endTimestamp 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | ------- Summary: start ------- 09:48:19 INFO - PROCESS | 2339 | Number of tests: 6 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | [#0] dojo.html Cycles:165 Average:5686.74 Median:5938.67 stddev:678.79 (11.4%) stddev-sans-first:638.83 09:48:19 INFO - PROCESS | 2339 | Values: 2688.7 2705.0 2786.8 3334.2 3379.8 5527.7 5584.4 5591.1 5607.4 5690.1 5638.3 5668.8 5676.3 5681.4 5708.3 4420.7 5136.0 5175.4 5263.1 5267.2 5163.9 5267.4 5268.4 5275.4 5283.1 4555.0 4833.9 5172.6 5206.3 5264.3 5266.1 5275.7 5280.3 5284.0 5352.8 5050.1 5061.3 5139.2 5190.7 5205.8 5036.6 5154.2 5593.8 5640.3 5657.1 6160.4 6179.2 6186.5 6192.0 6207.8 5500.7 6129.7 6148.5 6165.6 6185.6 5770.7 5799.7 5807.3 5829.5 5832.9 6127.2 6184.0 6193.5 6208.4 6209.3 5932.9 5986.2 6044.8 6135.9 6147.3 6179.6 6231.1 6241.4 6245.2 6273.8 6181.6 6190.9 6192.3 6194.7 6206.3 6047.6 6059.3 6115.6 6235.3 6236.7 4901.5 4934.8 4942.3 4944.8 4951.4 4466.3 4486.9 4494.1 4496.4 4499.8 5676.5 5678.4 5680.7 5694.7 5696.2 6127.2 6151.7 6166.3 6223.8 6247.2 5715.3 5718.1 5733.3 5738.9 5773.0 5413.5 5430.9 5433.2 5434.8 5437.1 6221.6 6223.1 6237.9 6274.6 6276.0 6145.9 6188.0 6217.3 6255.6 6276.7 6209.9 6215.4 6219.4 6226.4 6238.4 6000.0 6135.2 6144.4 6161.9 6184.4 6114.3 6139.6 6153.4 6211.2 6275.6 6056.0 6063.6 6088.9 6104.5 6123.5 5570.1 5867.9 6064.4 6072.4 6086.3 5801.5 5875.3 5938.7 5963.2 6003.9 6086.9 6119.9 6128.0 6132.7 6133.4 5768.4 5956.1 5970.8 5972.6 6021.3 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | [#1] ext.html Cycles:180 Average:75864.76 Median:40703.99 stddev:168598.99 (414.2%) stddev-sans-first:168976.91 09:48:19 INFO - PROCESS | 2339 | Values: 463.0 481.8 516.4 533.3 536.8 24609.3 30872.8 35249.6 40726.4 41865.6 42986.9 43971.9 44175.8 44214.1 44297.6 9634.2 9795.4 9799.0 9801.0 9804.1 8653.6 8871.3 8877.8 8899.0 8920.5 11762.5 12020.7 12029.8 12254.9 12277.6 11969.7 12077.8 12081.9 12085.4 12087.1 8600.3 11391.4 11790.2 11904.2 11977.8 41800.0 41946.4 42048.8 42083.2 42138.5 3602.5 3671.8 3808.0 4183.9 4234.0 19821.3 25208.5 25327.9 32902.8 33258.8 2895.5 3006.4 3016.0 3017.1 3121.5 544964.0 546047.0 546623.3 548063.0 548249.0 60216.0 61139.0 61267.4 61291.0 61465.1 63131.7 63163.7 63256.4 63264.0 63330.7 15991.3 16555.8 16572.4 16574.5 16636.0 13005.2 13022.5 13032.8 13064.4 13103.9 14285.9 14639.6 15932.0 16242.8 16361.8 7088.6 7124.9 7156.9 7208.8 7243.0 911.9 981.0 1034.0 1045.3 1050.5 29110.6 30353.9 30525.2 30580.0 31064.7 1033.5 1060.0 1074.0 1132.6 1135.8 12514.5 13919.0 15979.7 16247.3 16276.7 693108.0 697709.0 701894.0 703079.0 703212.0 60433.7 60463.0 60590.4 60657.4 60797.4 1375.0 1404.8 1436.8 1437.3 1438.3 27165.0 30753.2 30778.8 31090.0 31624.4 52195.5 52229.7 52517.7 52599.5 52695.7 630434.0 633032.0 634212.0 635862.0 636299.0 42141.8 42719.0 43041.0 43181.8 43197.8 36383.6 41083.6 48194.0 51737.0 52946.2 39963.6 41062.6 41820.8 41940.0 42234.8 40681.6 40762.4 40943.0 41145.8 42371.8 39267.6 39598.4 39863.0 39879.2 39882.6 33508.8 34464.0 35509.2 35896.8 36243.3 26092.2 26232.0 26443.6 26590.0 26804.1 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | [#2] jquery.html Cycles:180 Average:35089.61 Median:12815.58 stddev:99891.98 (779.5%) stddev-sans-first:100138.19 09:48:19 INFO - PROCESS | 2339 | Values: 371.5 380.7 381.9 392.5 398.4 18361.5 20836.0 22091.1 22224.8 22259.2 10293.3 11867.9 12312.9 12324.4 17312.7 5483.5 5597.1 5654.7 5662.3 5664.4 3986.5 4142.3 4152.3 6889.0 6919.9 6893.6 6901.9 6920.9 6932.8 6933.1 5662.1 5846.0 6163.9 6164.2 6164.5 3819.4 5727.3 5988.0 6050.3 6066.1 13531.4 13621.9 13635.2 23256.0 26410.3 1893.5 2145.0 2200.8 2375.2 2851.5 10298.2 10526.2 10569.7 10863.2 16377.4 1691.3 1855.7 1926.7 1927.3 1943.5 106679.5 115632.0 115644.4 115777.4 116818.0 11010.3 11441.0 11730.8 11802.5 12073.8 14519.7 14572.9 14647.0 14661.1 14669.9 7705.1 8043.3 8761.2 8813.9 8825.9 7489.2 8615.4 8750.8 8781.3 8924.0 3755.9 4467.6 4529.3 4551.3 4587.6 1576.4 1893.3 1893.7 1896.1 1896.6 896.4 931.9 948.4 954.3 973.5 20516.0 21232.4 21390.7 21437.5 21537.8 926.7 944.1 958.3 968.2 969.6 10773.4 11365.5 11376.7 11427.5 11439.6 569085.0 587064.0 595673.0 611798.0 613003.0 134448.0 142768.0 142778.0 143790.0 144385.0 949.3 950.8 964.7 966.7 1001.3 18108.9 18852.3 19140.5 19162.0 19173.0 44124.1 56908.0 57411.7 57606.8 57949.7 83560.0 84323.0 85140.1 85280.3 86119.8 5540.2 5658.4 6020.9 7918.1 8576.1 10965.4 13023.3 13087.9 13289.0 13594.8 11552.5 11558.8 11721.4 11760.0 11777.4 10710.8 11057.6 11345.0 11398.9 11658.7 11617.5 11955.7 11998.9 12006.6 12008.3 8171.8 8241.9 8270.6 8338.9 8502.4 4690.1 4803.9 10314.9 11369.1 11637.3 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | [#3] mootools.html Cycles:180 Average:5689.36 Median:4802.03 stddev:3219.38 (67.0%) stddev-sans-first:3212.04 09:48:19 INFO - PROCESS | 2339 | Values: 1369.6 1509.6 1551.0 1742.0 1921.3 7909.6 8631.0 8855.7 8858.2 8990.3 8569.1 8669.5 8746.5 8748.7 8754.3 2064.2 2146.5 2298.3 2328.7 2333.6 2016.5 2316.6 2396.0 2398.8 2398.8 2334.7 2359.2 2364.6 2369.1 2385.1 1754.4 2248.2 2333.2 2347.4 2354.4 2337.7 2385.4 2387.1 2387.5 2392.5 8675.7 8740.7 8743.2 8751.9 8763.5 5162.5 5278.1 5291.3 5294.8 5313.0 3109.2 3426.7 3437.5 3623.1 3636.8 1069.5 1071.6 1085.2 1086.2 1101.9 11472.0 11717.8 11718.9 11728.9 11730.8 4514.8 4545.8 4739.3 4752.3 4752.5 10115.8 10135.9 10142.7 10148.9 10184.7 5313.0 5315.7 5321.8 5327.0 5327.4 4087.9 4296.8 4454.3 4456.0 4876.7 3460.1 3635.3 3825.9 4034.3 4088.2 1256.6 1267.6 1284.1 1284.4 1286.6 781.3 817.3 888.6 934.3 970.3 5735.3 5916.3 6000.0 6064.9 6085.5 927.2 934.2 949.6 951.5 989.0 3382.7 3962.1 4034.7 4094.7 4161.3 10295.6 10776.2 10785.3 10791.2 10852.0 10540.2 10721.8 10722.8 10733.6 10737.8 8101.1 8215.7 8230.8 8240.2 8241.9 5555.3 5880.4 5881.6 5917.6 5962.1 9802.8 9907.4 10086.4 10141.0 10183.7 9916.0 10239.0 10258.5 10296.6 10327.4 6991.4 7039.6 7109.1 7116.8 7120.4 8107.8 8190.3 8269.9 8295.5 8297.0 7152.6 7454.4 7463.0 7475.0 7547.7 7359.8 7385.9 7428.0 7495.0 7552.0 6775.0 6877.9 6920.9 6925.7 6943.4 4851.6 4913.2 4924.3 4979.6 5062.3 5051.5 5058.7 5106.2 5147.6 5245.2 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | [#4] prototype.html Cycles:180 Average:4619.44 Median:5109.74 stddev:1414.50 (27.7%) stddev-sans-first:1380.83 09:48:19 INFO - PROCESS | 2339 | Values: 300.9 307.9 310.0 312.6 317.6 5441.0 5472.9 5495.1 5500.8 5552.4 5355.2 5356.6 5450.8 5459.3 5472.3 3929.5 3966.6 4088.7 4103.7 4243.2 4085.0 4105.0 4164.6 4191.4 4213.5 3384.4 3886.1 3979.3 3992.2 4075.6 3743.4 4036.6 4115.5 4163.3 4186.4 3644.1 3649.2 3650.2 3654.8 3676.8 4796.0 5256.6 5382.6 5384.5 5396.8 4004.5 4628.4 4720.4 4826.9 4894.2 4642.0 4984.7 5242.8 5530.0 5669.7 3446.3 3468.8 3666.0 3720.2 3960.0 4805.5 5912.6 5988.0 5995.4 6004.3 3793.8 4054.7 4060.6 4089.7 4118.3 4149.0 4159.3 4172.4 4180.2 4192.4 3791.9 4561.2 4821.3 4833.6 4866.0 4605.6 4619.0 5655.4 5772.9 5854.6 1285.3 1413.6 1419.8 1427.5 1432.5 765.4 810.3 835.8 882.7 903.2 4685.6 4689.4 4755.9 5313.1 5330.8 5445.8 5870.8 5871.1 5909.7 6012.5 4269.3 4466.3 4639.6 5047.8 5363.5 3064.4 3070.4 3103.2 3106.8 3109.2 5357.5 6035.5 6066.5 6107.9 6190.4 4864.9 4905.5 5090.8 6061.2 6099.3 6034.6 6062.8 6112.7 6128.5 6145.8 5482.8 5792.3 5826.4 5827.8 5861.5 5680.3 5722.9 5730.7 5740.8 5771.9 5962.5 6040.0 6053.2 6132.9 6191.3 3820.4 4033.9 4050.5 4053.9 4067.2 5128.7 5163.1 5551.2 5912.6 5980.9 4660.4 5491.6 5741.4 5760.3 5796.6 5005.6 5671.7 5693.9 5712.8 5736.9 4549.9 4926.0 5513.6 5575.6 5699.5 5144.4 5496.6 5724.7 5729.8 5749.4 4766.9 5600.5 5626.0 5626.8 5640.5 09:48:19 INFO - PROCESS | 2339 | 09:48:19 INFO - PROCESS | 2339 | [#5] yui.html Cycles:180 Average:23259.67 Median:3802.69 stddev:35864.94 (943.1%) stddev-sans-first:35925.66 09:48:19 INFO - PROCESS | 2339 | Values: 730.6 758.1 773.5 845.0 857.8 17474.0 18155.8 18170.5 18217.3 18246.3 16883.4 16923.4 17131.9 17175.0 17266.5 876.2 895.6 895.8 896.4 896.6 712.7 725.5 727.9 738.4 738.6 763.4 905.3 922.8 927.4 944.5 923.3 933.5 936.1 936.6 937.3 552.1 691.2 691.8 693.1 693.6 13667.7 15337.5 15990.4 16049.0 16256.3 12648.1 13071.3 14590.2 14611.9 14650.4 11523.4 11793.5 11979.3 12409.8 12479.6 3763.6 3801.4 3804.0 3813.5 3938.5 95514.5 98563.5 101748.0 102093.0 109295.5 10717.2 10770.1 10806.5 10843.8 11060.2 22120.2 22149.0 22197.2 22214.4 22262.7 7982.5 8042.8 8057.2 8115.8 8165.5 4472.5 4561.4 4569.8 4611.6 4751.0 3447.7 3527.6 3552.7 3560.8 3565.5 637.1 644.7 646.1 652.2 659.8 541.8 624.9 666.2 694.7 703.9 9168.8 9204.6 9205.5 9382.3 9474.8 611.2 618.6 619.3 623.0 630.4 4371.4 4536.7 4539.6 4597.0 4650.6 88517.6 89316.6 113287.4 131626.0 141807.0 125254.7 126390.7 126760.0 128615.0 128900.0 99090.0 103418.0 104841.0 105138.0 105451.9 7869.5 8653.0 8684.1 9016.0 9053.9 51551.0 58786.1 60126.8 60789.0 60820.0 86306.6 93510.1 93706.5 94049.8 95120.6 10376.5 11801.2 11941.0 12019.5 12212.0 13115.9 13133.7 13298.3 13500.7 13909.9 12188.3 12298.4 12365.5 12396.9 12436.4 12058.9 12288.9 12411.1 12547.4 12675.4 11619.0 11760.0 12112.9 12549.4 12612.8 9524.9 10096.5 10223.1 10353.6 10409.3 10068.4 10379.3 10383.8 10406.5 10419.4 09:48:19 INFO - PROCESS | 2339 | -------- Summary: end -------- 09:48:19 INFO - PROCESS | 2339 | 09:48:20 INFO - TEST-INFO | 2339: exit 0 09:48:20 INFO - TEST-OK | dromaeo_css | took 1116430ms 09:48:20 INFO - TEST-START | kraken 09:48:20 INFO - operating with platform_type : mac_ 09:48:20 INFO - Initialising browser for kraken test... 09:48:20 INFO - TEST-INFO | started process 2342 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpKStNY8/profile http://localhost:53351/getInfo.html) 09:48:22 INFO - PROCESS | 2342 | __metrics Screen width/height:1600/1200 09:48:22 INFO - PROCESS | 2342 | colorDepth:24 09:48:22 INFO - PROCESS | 2342 | Browser inner width/height: 1024/689 09:48:22 INFO - PROCESS | 2342 | __metrics 09:48:22 INFO - PROCESS | 2342 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:48:22 INFO - PROCESS | 2342 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:48:23 INFO - TEST-INFO | 2342: exit 0 09:48:23 INFO - Browser initialized. 09:48:23 INFO - Running cycle 1/1 for kraken test... 09:48:23 INFO - TEST-INFO | started process 2343 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpKStNY8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:35 INFO - PROCESS | 2343 | RSS: Main: 243666944 09:48:35 INFO - PROCESS | 2343 | 09:48:37 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53351/tests/kraken/driver.html?testName=audio-beat-detection) 09:48:38 INFO - PROCESS | 2343 | RSS: Main: 294383616 09:48:38 INFO - PROCESS | 2343 | 09:48:41 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53351/tests/kraken/driver.html?testName=audio-dft) 09:48:42 INFO - PROCESS | 2343 | RSS: Main: 304320512 09:48:42 INFO - PROCESS | 2343 | 09:48:45 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53351/tests/kraken/driver.html?testName=audio-fft) 09:48:46 INFO - PROCESS | 2343 | RSS: Main: 387723264 09:48:46 INFO - PROCESS | 2343 | 09:48:48 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53351/tests/kraken/driver.html?testName=audio-oscillator) 09:48:48 INFO - PROCESS | 2343 | RSS: Main: 290074624 09:48:48 INFO - PROCESS | 2343 | 09:48:50 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53351/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:48:51 INFO - PROCESS | 2343 | RSS: Main: 294084608 09:48:51 INFO - PROCESS | 2343 | 09:48:54 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53351/tests/kraken/driver.html?testName=imaging-darkroom) 09:48:54 INFO - PROCESS | 2343 | RSS: Main: 329740288 09:48:54 INFO - PROCESS | 2343 | 09:48:57 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53351/tests/kraken/driver.html?testName=imaging-desaturate) 09:48:58 INFO - PROCESS | 2343 | RSS: Main: 331890688 09:48:58 INFO - PROCESS | 2343 | 09:49:01 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53351/tests/kraken/driver.html?testName=json-parse-financial) 09:49:01 INFO - PROCESS | 2343 | RSS: Main: 329986048 09:49:01 INFO - PROCESS | 2343 | 09:49:03 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53351/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:03 INFO - PROCESS | 2343 | RSS: Main: 311693312 09:49:03 INFO - PROCESS | 2343 | 09:49:05 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:06 INFO - PROCESS | 2343 | RSS: Main: 318504960 09:49:06 INFO - PROCESS | 2343 | 09:49:08 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:09 INFO - PROCESS | 2343 | RSS: Main: 316076032 09:49:09 INFO - PROCESS | 2343 | 09:49:12 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:49:13 INFO - PROCESS | 2343 | RSS: Main: 326868992 09:49:13 INFO - PROCESS | 2343 | 09:49:16 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:49:16 INFO - PROCESS | 2343 | RSS: Main: 330121216 09:49:16 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | Cycle 1(1): loaded http://localhost:53351/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53351/tests/kraken/driver.html?testName=ai-astar) 09:49:18 INFO - PROCESS | 2343 | RSS: Main: 373673984 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | __start_tp_report 09:49:18 INFO - PROCESS | 2343 | _x_x_mozilla_page_load 09:49:18 INFO - PROCESS | 2343 | _x_x_mozilla_page_load_details 09:49:18 INFO - PROCESS | 2343 | |i|pagename|runs| 09:49:18 INFO - PROCESS | 2343 | |0;ai-astar;101;113;114;114;115;112;114;112;114;113 09:49:18 INFO - PROCESS | 2343 | |1;audio-beat-detection;140;109;103;160;116;136;159;106;156;104 09:49:18 INFO - PROCESS | 2343 | |2;audio-dft;184;186;184;185;185;180;183;182;183;184 09:49:18 INFO - PROCESS | 2343 | |3;audio-fft;78;74;58;123;82;81;84;66;83;56 09:49:18 INFO - PROCESS | 2343 | |4;audio-oscillator;83;92;93;70;70;68;97;70;68;103 09:49:18 INFO - PROCESS | 2343 | |5;imaging-gaussian-blur;88;85;90;86;88;85;89;86;88;85 09:49:18 INFO - PROCESS | 2343 | |6;imaging-darkroom;95;94;94;94;94;94;94;94;94;93 09:49:18 INFO - PROCESS | 2343 | |7;imaging-desaturate;84;84;86;84;84;84;86;84;87;85 09:49:18 INFO - PROCESS | 2343 | |8;json-parse-financial;54;54;56;57;56;55;54;54;54;56 09:49:18 INFO - PROCESS | 2343 | |9;json-stringify-tinderbox;64;69;73;103;102;96;79;69;69;69 09:49:18 INFO - PROCESS | 2343 | |10;stanford-crypto-aes;82;82;82;83;84;86;78;87;80;80 09:49:18 INFO - PROCESS | 2343 | |11;stanford-crypto-ccm;164;148;148;133;135;152;165;144;243;138 09:49:18 INFO - PROCESS | 2343 | |12;stanford-crypto-pbkdf2;170;164;170;164;162;216;212;162;169;172 09:49:18 INFO - PROCESS | 2343 | |13;stanford-crypto-sha256-iterative;68;67;66;67;67;68;66;65;65;66 09:49:18 INFO - PROCESS | 2343 | __end_tp_report 09:49:18 INFO - PROCESS | 2343 | __start_cc_report 09:49:18 INFO - PROCESS | 2343 | _x_x_mozilla_cycle_collect,1989 09:49:18 INFO - PROCESS | 2343 | __end_cc_report 09:49:18 INFO - PROCESS | 2343 | __startTimestamp1467910158658__endTimestamp 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | ------- Summary: start ------- 09:49:18 INFO - PROCESS | 2343 | Number of tests: 14 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#0] ai-astar Cycles:10 Average:112.20 Median:114.00 stddev:4.05 (3.6%) stddev-sans-first:1.01 09:49:18 INFO - PROCESS | 2343 | Values: 101.0 113.0 114.0 114.0 115.0 112.0 114.0 112.0 114.0 113.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:138.00 stddev:23.94 (17.3%) stddev-sans-first:25.05 09:49:18 INFO - PROCESS | 2343 | Values: 140.0 109.0 103.0 160.0 116.0 136.0 159.0 106.0 156.0 104.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.71 (0.9%) stddev-sans-first:1.81 09:49:18 INFO - PROCESS | 2343 | Values: 184.0 186.0 184.0 185.0 185.0 180.0 183.0 182.0 183.0 184.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#3] audio-fft Cycles:10 Average:78.50 Median:79.50 stddev:18.72 (23.5%) stddev-sans-first:19.85 09:49:18 INFO - PROCESS | 2343 | Values: 78.0 74.0 58.0 123.0 82.0 81.0 84.0 66.0 83.0 56.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:76.50 stddev:13.78 (18.0%) stddev-sans-first:14.60 09:49:18 INFO - PROCESS | 2343 | Values: 83.0 92.0 93.0 70.0 70.0 68.0 97.0 70.0 68.0 103.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.83 (2.1%) stddev-sans-first:1.90 09:49:18 INFO - PROCESS | 2343 | Values: 88.0 85.0 90.0 86.0 88.0 85.0 89.0 86.0 88.0 85.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 09:49:18 INFO - PROCESS | 2343 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.50 stddev:1.14 (1.3%) stddev-sans-first:1.17 09:49:18 INFO - PROCESS | 2343 | Values: 84.0 84.0 86.0 84.0 84.0 84.0 86.0 84.0 87.0 85.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.50 stddev:1.15 (2.1%) stddev-sans-first:1.17 09:49:18 INFO - PROCESS | 2343 | Values: 54.0 54.0 56.0 57.0 56.0 55.0 54.0 54.0 54.0 56.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:69.00 stddev:15.11 (21.9%) stddev-sans-first:14.97 09:49:18 INFO - PROCESS | 2343 | Values: 64.0 69.0 73.0 103.0 102.0 96.0 79.0 69.0 69.0 69.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.50 stddev:2.76 (3.3%) stddev-sans-first:2.92 09:49:18 INFO - PROCESS | 2343 | Values: 82.0 82.0 82.0 83.0 84.0 86.0 78.0 87.0 80.0 80.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:150.00 stddev:32.12 (21.4%) stddev-sans-first:33.97 09:49:18 INFO - PROCESS | 2343 | Values: 164.0 148.0 148.0 133.0 135.0 152.0 165.0 144.0 243.0 138.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:170.00 stddev:20.31 (11.9%) stddev-sans-first:21.42 09:49:18 INFO - PROCESS | 2343 | Values: 170.0 164.0 170.0 164.0 162.0 216.0 212.0 162.0 169.0 172.0 09:49:18 INFO - PROCESS | 2343 | 09:49:18 INFO - PROCESS | 2343 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.08 (1.6%) stddev-sans-first:1.00 09:49:18 INFO - PROCESS | 2343 | Values: 68.0 67.0 66.0 67.0 67.0 68.0 66.0 65.0 65.0 66.0 09:49:18 INFO - PROCESS | 2343 | -------- Summary: end -------- 09:49:18 INFO - PROCESS | 2343 | 09:49:19 INFO - TEST-INFO | 2343: exit 0 09:49:19 INFO - TEST-OK | kraken | took 59307ms 09:49:19 INFO - SUITE-END | took 1175s 09:49:19 INFO - Completed test suite (00:19:36) 09:49:19 INFO - javascript benchmark 09:49:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2688.6504754381926, 2704.9594256086175, 2786.8188567743096, 3334.2497937963954, 3379.7803142795715, 5527.668339899609, 5584.441555844413, 5591.1054231323, 5607.4112218217115, 5690.118031705088, 5638.295213098363, 5668.801591944282, 5676.318841738997, 5681.431856814316, 5708.286464191976, 4420.668449866257, 5136.0, 5175.430702622709, 5263.0526505229045, 5267.23625074364, 5163.948360516403, 5267.420583735786, 5268.367795864501, 5275.419703832576, 5283.128283833182, 4554.975130595622, 4833.879153021167, 5172.586193104561, 5206.271122042917, 5264.315638966929, 5266.078436273656, 5275.683458992473, 5280.313559237313, 5283.973580132113, 5352.812651557216, 5050.090983622947, 5061.291419201315, 5139.203423469368, 5190.662606930538, 5205.791768329263, 5036.622253331015, 5154.20109882965, 5593.776248950037, 5640.266765320482, 5657.066584013633, 6160.4455598995855, 6179.19670442844, 6186.505079593621, 6192.040233763774, 6207.81376558704, 5500.697461639608, 6129.69351532426, 6148.538859585504, 6165.630062196283, 6185.56701030928, 5770.7403166857175, 5799.681017544076, 5807.274090738658, 5829.47534721877, 5832.883342333172, 6127.20346354971, 6184.0, 6193.535484838655, 6208.441240288305, 6209.2548894133, 5932.940670593325, 5986.191864098378, 6044.848878778066, 6135.938640613536, 6147.323794382614, 6179.567430279927, 6231.096491008779, 6241.438270555672, 6245.156903817927, 6273.811785646438, 6181.567290289727, 6190.876182476415, 6192.256929168529, 6194.690265486798, 6206.255249370014, 6047.637141771508, 6059.333473317932, 6115.633062016204, 6235.314115447297, 6236.719347629368, 4901.460839307673, 4934.802607895644, 4942.308076869242, 4944.802207911715, 4951.3810773653295, 4466.330050492452, 4486.887827804331, 4494.12364588902, 4496.437945256843, 4499.842505512292, 5676.460736230051, 5678.375378708339, 5680.744366503517, 5694.743736531866, 5696.174054762037, 6127.18952814863, 6151.66165860873, 6166.290876549165, 6223.813285601439, 6247.156633854463, 5715.2855893013375, 5718.142278658152, 5733.3406658234, 5738.856528586819, 5773.018586840247, 5413.512783849394, 5430.864228394243, 5433.239346491494, 5434.755436005389, 5437.102878025082, 6221.595596286227, 6223.097650840603, 6237.906431403442, 6274.592151510137, 6276.02721578147, 6145.938540614537, 6188.0, 6217.316095229611, 6255.593386429868, 6276.74893004293, 6209.906851397142, 6215.378462153749, 6219.40915613016, 6226.40849119333, 6238.407351301619, 6000.0, 6135.202423684892, 6144.354842741448, 6161.907571386343, 6184.412480814315, 6114.266954506099, 6139.614800607497, 6153.4154255345675, 6211.223597050369, 6275.560710750204, 6056.024944178904, 6063.636181829104, 6088.939110609014, 6104.519788040188, 6123.510119190543, 5570.053090974463, 5867.882642347215, 6064.363241859542, 6072.362401947908, 6086.330503644507, 5801.535877129735, 5875.3243377010485, 5938.673372964528, 5963.224780778644, 6003.939960600338, 6086.870191114421, 6119.868162944114, 6128.044828652092, 6132.695218635937, 6133.426475724657, 5768.423157684198, 5956.13636022783, 5970.820875373745, 5972.552058595709, 6021.337652858269], "value": 5637.617247247514, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.9953700462995, 481.8120932836192, 516.3906590223535, 533.3173537871526, 536.7975734355045, 24609.26172214834, 30872.845635771817, 35249.590016399314, 40726.38910416346, 41865.58134418655, 42986.92532686685, 43971.90070248245, 44175.77912110435, 44214.11571768563, 44297.55702442975, 9634.229261659086, 9795.363301385423, 9799.0, 9801.0, 9804.068613481708, 8653.610587523544, 8871.334649901282, 8877.822443551126, 8899.02110767815, 8920.50937198457, 11762.52949882008, 12020.69948251292, 12029.819552706716, 12254.938725306405, 12277.631671049838, 11969.700757481045, 12077.758444831143, 12081.87918120825, 12085.395730213542, 12087.093467989893, 8600.311975041981, 11391.373474458902, 11790.23363481371, 11904.16670833042, 11977.820332695017, 41800.0, 41946.37080443796, 42048.78975605133, 42083.15833683309, 42138.52515161955, 3602.475210616571, 3671.834767435445, 3808.009917421463, 4183.937240941388, 4233.957660423387, 19821.306254281175, 25208.51080690213, 25327.87336063308, 32902.83548582242, 33258.83370583132, 2895.4788138135127, 3006.428778532094, 3015.969840301591, 3017.1250337402125, 3121.4693502104697, 544964.0, 546047.0, 546623.266883667, 548063.0, 548249.0, 60216.0, 61139.0, 61267.38732612706, 61291.0, 61465.07802382968, 63131.684341577995, 63163.684181579716, 63256.36743632597, 63263.989920957436, 63330.68334658297, 15991.28039238237, 16555.834441655672, 16572.419965301156, 16574.502764917073, 16636.001839889646, 13005.21968681882, 13022.47910083586, 13032.80450793239, 13064.412101455457, 13103.868961310456, 14285.85713142946, 14639.634009149857, 15932.0, 16242.75635865463, 16361.754573681405, 7088.574685518885, 7124.893126603105, 7156.856862862714, 7208.819779505449, 7243.0, 911.8541033434618, 980.9950950245346, 1033.9793204136026, 1045.29442626227, 1050.4537640426936, 29110.563341549892, 30353.93761218347, 30525.236869078006, 30580.0, 31064.68935310618, 1033.5194134727387, 1059.994700026495, 1073.9677809665723, 1132.5809450503366, 1135.8239472881717, 12514.49942002328, 13919.0, 15979.680406392039, 16247.268872900748, 16276.674466510603, 693108.0, 697709.0, 701894.0, 703079.0, 703212.0, 60433.69783151144, 60463.0, 60590.39409605935, 60657.39342606605, 60797.39202608006, 1374.979375309371, 1404.8243969503812, 1436.7629341158593, 1437.3244575049919, 1438.3024233247077, 27165.0, 30753.231169220948, 30778.846105769328, 31090.03889616606, 31624.36751265008, 52195.47804522058, 52229.73885130702, 52517.7374113127, 52599.47400525946, 52695.73652131715, 630434.0, 633032.0, 634212.0, 635862.0, 636299.0, 42141.78929105335, 42719.0, 43041.0, 43181.784091079346, 43197.78401107974, 36383.636163638024, 41083.58916410798, 48194.0, 51736.965260693825, 52946.2058069137, 39963.60036399715, 41062.64120962265, 41820.79089604533, 41940.0, 42234.78882605567, 40681.59318406897, 40762.38856417216, 40943.0, 41145.79427102965, 42371.7881410591, 39267.60732392639, 39598.40602390909, 39863.0, 39879.20241595094, 39882.60117398789, 33508.8271910482, 34463.96608101761, 35509.17981729626, 35896.820515897256, 36243.27513449769, 26092.217233483025, 26232.0, 26443.603345949443, 26590.0, 26804.06185783488], "value": 20783.788791296487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.4984770559744, 380.65550676637645, 381.90643292393366, 392.4603669953814, 398.3844959537514, 18361.45781328008, 20836.0, 22091.116355345788, 22224.77775222247, 22259.220927267528, 10293.330933489318, 11867.940660296686, 12312.938435307811, 12324.445399957001, 17312.740308895376, 5483.479069488402, 5597.076482380402, 5654.660720356771, 5662.348829884575, 5664.376918538957, 3986.4817573715522, 4142.2543942090415, 4152.294110001307, 6888.965555172217, 6919.8962015569805, 6893.551919125266, 6901.896471552931, 6920.858058420355, 6932.757353492629, 6933.098697169361, 5662.122371032497, 5845.970770146143, 6163.907541386883, 6164.229471316085, 6164.537659675542, 3819.3698039823394, 5727.284089488814, 5988.041913293852, 6050.27396712393, 6066.090086487018, 13531.39108740109, 13621.931890340584, 13635.181889086687, 23256.0, 26410.339741506425, 1893.5266183454137, 2144.978550214509, 2200.8129309008755, 2375.168690958168, 2851.458222937635, 10298.227632927561, 10526.157907367393, 10569.73575660607, 10863.18526110545, 16377.426790062409, 1691.2727527163315, 1855.656703509855, 1926.6532024235632, 1927.2772710233662, 1943.543267332176, 106679.46660266726, 115632.0, 115644.42177789057, 115777.42111289474, 116818.0, 11010.339379637247, 11441.0, 11730.824037639442, 11802.46888890002, 12073.818892716616, 14519.709605807824, 14572.927135364256, 14647.0, 14661.120332780067, 14669.853301466848, 7705.075390953121, 8043.316318112909, 8761.167689069529, 8813.911860881437, 8825.911740882637, 7489.176190619137, 8615.396922215385, 8750.82498350042, 8781.25359344463, 8924.018357980616, 3755.9061023474087, 4467.620252278528, 4529.32060190974, 4551.340055691906, 4587.63298936091, 1576.4482431149006, 1893.3184053740642, 1893.725409815569, 1896.1183049881872, 1896.6396384686866, 896.3859756067114, 931.9161275485238, 948.4025064209504, 954.2985905359556, 973.5375696544227, 20515.974201289875, 21232.36302910915, 21390.679139812913, 21437.46406339854, 21537.80482357085, 926.7266156483853, 944.093670076734, 958.322934863988, 968.2205824311496, 969.6121551379505, 10773.353598784099, 11365.54537818478, 11376.658700239006, 11427.48576314068, 11439.59961401347, 569085.0, 587064.0, 595673.0, 611798.0, 613003.0, 134448.0, 142768.0, 142778.0, 143790.0, 144385.0, 949.3259785552198, 950.8098380323963, 964.6961207219704, 966.7341481092756, 1001.2540657210425, 18108.90945545264, 18852.34016809405, 19140.521486962938, 19162.0, 19172.98683638198, 44124.117517648825, 56908.0, 57411.71294143503, 57606.847863041665, 57949.71025144847, 83560.0, 84323.0, 85140.14859851322, 85280.294394113, 86119.8470038254, 5540.196671482693, 5658.44903656794, 6020.916235077728, 7918.129005809241, 8576.142385761623, 10965.396903170084, 13023.283719395524, 13087.869121309046, 13289.0, 13594.796078059035, 11552.537898483966, 11558.768824623292, 11721.355325457182, 11760.0, 11777.411129443664, 10710.839337410102, 11057.612983545538, 11345.035671967646, 11398.886011140114, 11658.6502404928, 11617.477213525242, 11955.701107472383, 11998.940005299917, 12006.579769708018, 12008.279503229835, 8171.7548473545885, 8241.876371854547, 8270.627821748156, 8338.87491687613, 8502.404831661725, 4690.085433340466, 4803.927941080817, 10314.948425257826, 11369.090472761995, 11637.301761894314], "value": 9394.177728072495, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.630199846041, 1509.554681368996, 1550.953471395858, 1741.9738703919443, 1921.3275353626225, 7909.564973926432, 8631.0, 8855.690050848214, 8858.247049000827, 8990.325725570576, 8569.057403685605, 8669.526180549306, 8746.518941458216, 8748.650053997833, 8754.299656027519, 2064.1743302678974, 2146.4955735402245, 2298.310506847943, 2328.685627440291, 2333.603287441139, 2016.4656366063004, 2316.6409206573007, 2395.988020059906, 2398.796102331304, 2398.8200884933613, 2334.6964894563766, 2359.221456919221, 2364.6098393765096, 2369.052379048377, 2385.057902128658, 1754.359658724569, 2248.2243625949022, 2333.1717240379676, 2347.448349637834, 2354.3643216331493, 2337.719473663154, 2385.403649087728, 2387.0809738250723, 2387.4866903615634, 2392.5214957008493, 8675.696350627697, 8740.650373985032, 8743.169398907094, 8751.912480875173, 8763.474191548528, 5162.45794191612, 5278.076336641111, 5291.312129423189, 5294.788208471657, 5312.9734351328, 3109.2226943264186, 3426.674465925733, 3437.5187473753695, 3623.1123374773088, 3636.7999760013454, 1069.5080263078914, 1071.6035067025173, 1085.1535802074395, 1086.2450596835204, 1101.9173561982802, 11472.0, 11717.765644687144, 11718.945294923498, 11728.944395004406, 11730.765384692344, 4514.796834142471, 4545.795439205243, 4739.2891066339635, 4752.334673145762, 4752.453467851172, 10115.848262276071, 10135.949320253352, 10142.695719128438, 10148.949255253676, 10184.694459166238, 5313.0436521425745, 5315.654482458628, 5321.813736519203, 5327.0, 5327.413984461706, 4087.8734968876893, 4296.828126874952, 4454.309582014793, 4456.041950980555, 4876.683015603974, 3460.1349662584353, 3635.2911182319194, 3825.923481530354, 4034.3343348347194, 4088.182363527247, 1256.6104507602672, 1267.5753622536554, 1284.0947132271726, 1284.389914790467, 1286.6397408725572, 781.316348195329, 817.3461231015164, 888.626776753765, 934.3086116273909, 970.315927271272, 5735.340435849847, 5916.319623243296, 6000.0, 6064.878702425926, 6085.5435842312, 927.1562877781322, 934.2199263614957, 949.6343907595488, 951.5432592355708, 988.991228946485, 3382.712469440076, 3962.128331767007, 4034.717569770147, 4094.733842300241, 4161.334186530141, 10295.588176472857, 10776.191785615953, 10785.298955567863, 10791.244612877023, 10852.0, 10540.209484288556, 10721.83917241242, 10722.839157412645, 10733.624323148651, 10737.78524429507, 8101.068377136587, 8215.671373145127, 8230.753077407688, 8240.175982401832, 8241.917580824234, 5555.2778138841695, 5880.382559831327, 5881.5588830837005, 5917.556183286185, 5962.105684147239, 9802.75493112678, 9907.356021858555, 10086.445245511273, 10141.036601522843, 10183.745406364604, 9916.0, 10239.027292407209, 10258.538365773707, 10296.639617613348, 10327.380357178594, 6991.405730512964, 7039.648017598997, 7109.116084237785, 7116.786496405116, 7120.394766444911, 8107.797305067421, 8190.262876341158, 8269.875951860606, 8295.460795048302, 8296.958515207385, 7152.570845749272, 7454.440916931143, 7463.0, 7475.028246327943, 7547.660355283915, 7359.816004599714, 7385.9261407387385, 7428.0, 7495.0, 7551.962240188764, 6775.0, 6877.862442751217, 6920.861582768417, 6925.722971081101, 6943.444524438134, 4851.611871050237, 4913.189323761682, 4924.310596516562, 4979.626528010486, 5062.291279220838, 5051.469595692546, 5058.721770302522, 5106.208537676666, 5147.562457191031, 5245.186996015471], "value": 4558.444565742991, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.8856634478898, 307.9153232860963, 309.995350069749, 312.5967501922519, 317.55542240862803, 5440.972795136024, 5472.93277810827, 5495.120780675093, 5500.752466139023, 5552.361478429972, 5355.223492593572, 5356.553730492765, 5450.809221677242, 5459.317585301837, 5472.315960504937, 3929.5088113985753, 3966.603339666039, 4088.6524645405175, 4103.671706263507, 4243.236217480852, 4084.97957510212, 4104.97947510262, 4164.562720914306, 4191.392248124021, 4213.51544572375, 3384.4415671414185, 3886.106195575005, 3979.323515002442, 3992.2414741199077, 4075.551689314173, 3743.419769935664, 4036.6367026967714, 4115.456703736099, 4163.292240319153, 4186.413902053715, 3644.125409901631, 3649.197176621139, 3650.1969566695284, 3654.798986055766, 3676.7977761223124, 4796.04079184161, 5256.553192978601, 5382.59630527708, 5384.488473595002, 5396.83809485716, 4004.4593979812935, 4628.352030715702, 4720.386349774507, 4826.879328016793, 4894.192458244385, 4642.048380082075, 4984.725840078794, 5242.79028838846, 5529.972350138264, 5669.688167150805, 3446.327966046622, 3468.809215493147, 3665.9552027671984, 3720.1815600567834, 3959.9604003960167, 4805.548674758484, 5912.556558258148, 5987.970060149715, 5995.400459954013, 6004.3395226525045, 3793.7942438639307, 4054.736442131252, 4060.553339132693, 4089.652379547772, 4118.279301122292, 4149.0, 4159.251334759772, 4172.436721042681, 4180.205760905418, 4192.371144328315, 3791.8862434127022, 4561.221123761736, 4821.252705830602, 4833.564979151823, 4866.004937975413, 4605.562471565196, 4619.0, 5655.406182350876, 5772.913406298909, 5854.590178687451, 1285.2673975833684, 1413.6324555615613, 1419.8012278281049, 1427.5217802036434, 1432.4699861051477, 765.4450523370623, 810.2626609785067, 835.7618078847499, 882.6734108379812, 903.1826197291462, 4685.6017238535105, 4689.390379250675, 4755.857324280277, 5313.0967735485065, 5330.773921997962, 5445.754941027663, 5870.827793716352, 5871.089981052944, 5909.704514774244, 6012.458878700938, 4269.316909294498, 4466.307722303049, 4639.638545436932, 5047.848564543069, 5363.517283444508, 3064.417760625474, 3070.4473194825146, 3103.239706271933, 3106.7980581262145, 3109.176068341846, 5357.473120160814, 6035.517158627299, 6066.514678825683, 6107.908381374283, 6190.350013248634, 4864.8783780405065, 4905.541593176802, 5090.77091530882, 6061.212042434456, 6099.329073801879, 6034.577579569475, 6062.787802426894, 6112.694365281629, 6128.540359473057, 6145.84635384119, 5482.834280459276, 5792.275965504312, 5826.41735826414, 5827.796027139199, 5861.501772349398, 5680.318361796611, 5722.941255867678, 5730.656160630376, 5740.827775166752, 5771.884562308815, 5962.4633782959745, 6040.003399439043, 6053.182820319376, 6132.945463269676, 6191.288001879841, 3820.3696390095324, 4033.91932161361, 4050.4734384529825, 4053.8986525337104, 4067.2272268268935, 5128.7179205144075, 5163.148080566814, 5551.196645693899, 5912.640092778771, 5980.9401905980385, 4660.370849935215, 5491.560675145899, 5741.363711342247, 5760.337561180518, 5796.608983744557, 5005.649604527794, 5671.6597004179885, 5693.857653558694, 5712.80005199816, 5736.856578585569, 4549.906063876596, 4925.9655472351205, 5513.566472717042, 5575.609707320448, 5699.5155411790465, 5144.356955380577, 5496.642718223303, 5724.738728202254, 5729.828105156852, 5749.367569567261, 4766.856994290177, 5600.4959553640365, 5625.971870140623, 5626.774929002958, 5640.502888209337], "value": 4165.542036245691, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.6346826586706, 758.0600055930644, 773.4585789947039, 844.9746507604774, 857.7684025313164, 17474.03892785896, 18155.818441815576, 18170.545736356566, 18217.27130914765, 18246.27014919403, 16883.409080682177, 16923.407680731176, 17131.91434042828, 17175.0, 17266.482005539823, 876.2026555834208, 895.6462197432011, 895.807401408697, 896.4397251717677, 896.6144557840126, 712.7291629180904, 725.4776560876161, 727.9017332660076, 738.3613174603955, 738.5605564689001, 763.362592235484, 905.2848249882585, 922.8015976565038, 927.4249965021696, 944.5418971798672, 923.3398120343919, 933.4679232837285, 936.0826390137669, 936.6487567162314, 937.2782957122977, 552.1221258199442, 691.1982100763091, 691.7751730687546, 693.0885885061128, 693.6323748413349, 13667.658308542266, 15337.46318878845, 15990.360385584563, 16049.037057776453, 16256.268467918853, 12648.114631975764, 13071.281079540622, 14590.197539135343, 14611.853881461155, 14650.413983440649, 11523.423828808525, 11793.469293881795, 11979.341136237503, 12409.813852792215, 12479.625611231677, 3763.604821493731, 3801.3917773156163, 3803.9809800951093, 3813.504244448232, 3938.4683067786054, 95514.52242738812, 98563.50718246435, 101748.0, 102093.0, 109295.45352273267, 10717.196210284259, 10770.138388929023, 10806.51370688321, 10843.83734243987, 11060.17048721349, 22120.2257920972, 22149.0, 22197.223097191843, 22214.444638883833, 22262.666060009113, 7982.481138725964, 8042.758717238492, 8057.194280572013, 8115.837683246302, 8165.510069395856, 4472.487335914263, 4561.384213131251, 4569.817207311737, 4611.5619016193405, 4750.976245118753, 3447.6897079262985, 3527.617268431796, 3552.662497062775, 3560.8219589020446, 3565.5186549815962, 637.1175921348872, 644.6647743173523, 646.1438593863151, 652.2140820311513, 659.7723785294066, 541.7914103070347, 624.9000159974382, 666.160637596622, 694.6665600511682, 703.8908969109796, 9168.81662366749, 9204.63181472747, 9205.493697846683, 9382.343235973553, 9474.810503789886, 611.1779656362186, 618.6288227063816, 619.3094699410216, 622.9657368844667, 630.3570358234575, 4371.388005679144, 4536.705114167568, 4539.636829053734, 4596.954030459719, 4650.558196971282, 88517.55741221253, 89316.5534172325, 113287.43356283166, 131626.0, 141807.0, 125254.74745252614, 126390.73609263974, 126760.0, 128615.0, 128900.0, 99090.00909990807, 103417.96582034233, 104841.0, 105138.0, 105451.94548054421, 7869.48848324857, 8652.956735216283, 8684.131586841266, 9015.954920225357, 9053.864192036994, 51551.0, 58786.11820822606, 60126.79746405135, 60789.0, 60820.0, 86306.56846715727, 93510.06489935014, 93706.53146734223, 94049.76235954755, 95120.57319140357, 10376.533056012346, 11801.17391782567, 11941.0, 12019.519219231133, 12212.0, 13115.868841311465, 13133.671658208621, 13298.335083245993, 13500.662483437993, 13909.930450347683, 12188.268703877795, 12298.446569904198, 12365.526894621291, 12396.938015309866, 12436.37818109109, 12058.879411205775, 12288.877111228774, 12411.069169812481, 12547.435365408395, 12675.366231688195, 11619.01238394746, 11760.0, 12112.878871211527, 12549.43527541281, 12612.810807837706, 9524.85712714296, 10096.495175241356, 10223.131033861908, 10353.585856565653, 10409.323393979366, 10068.44623545712, 10379.325343852626, 10383.792324153323, 10406.479676016017, 10419.42693151884], "value": 7650.379753976542, "unit": "score"}], "name": "dromaeo_css", "value": 7367.43261213446, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 114.0, 114.0, 115.0, 112.0, 114.0, 112.0, 114.0, 113.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 103.0, 160.0, 116.0, 136.0, 159.0, 106.0, 156.0, 104.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 185.0, 185.0, 180.0, 183.0, 182.0, 183.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 123.0, 82.0, 81.0, 84.0, 66.0, 83.0, 56.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 92.0, 93.0, 70.0, 70.0, 68.0, 97.0, 70.0, 68.0, 103.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 90.0, 86.0, 88.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 84.0, 84.0, 84.0, 86.0, 84.0, 87.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 57.0, 56.0, 55.0, 54.0, 54.0, 54.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 73.0, 103.0, 102.0, 96.0, 79.0, 69.0, 69.0, 69.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 83.0, 84.0, 86.0, 78.0, 87.0, 80.0, 80.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 148.0, 148.0, 133.0, 135.0, 152.0, 165.0, 144.0, 243.0, 138.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 164.0, 170.0, 164.0, 162.0, 216.0, 212.0, 162.0, 169.0, 172.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 67.0, 67.0, 68.0, 66.0, 65.0, 65.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1466.6999999999998, "alertThreshold": 2.0}]} 09:49:19 INFO - Return code: 0 09:49:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:49:19 INFO - # TBPL SUCCESS # 09:49:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:19 INFO - 2016-07-07 09:49:19.975 screenresolution[2347:24397] starting screenresolution argv=screenresolution get 09:49:19 INFO - 2016-07-07 09:49:19.978 screenresolution[2347:24397] Display 0: 1600x1200x32@60 09:49:19 INFO - 2016-07-07 09:49:19.984 screenresolution[2348:24400] starting screenresolution argv=screenresolution list 09:49:19 INFO - Available Modes on Display 0 09:49:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:20 INFO - Intel Iris: 09:49:20 INFO - Chipset Model: Intel Iris 09:49:20 INFO - Type: GPU 09:49:20 INFO - Bus: Built-In 09:49:20 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:20 INFO - Vendor: Intel (0x8086) 09:49:20 INFO - Device ID: 0x0a2e 09:49:20 INFO - Revision ID: 0x0009 09:49:20 INFO - Displays: 09:49:20 INFO - Display: 09:49:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:20 INFO - Display Serial Number: 1600x1200 60 09:49:20 INFO - Main Display: Yes 09:49:20 INFO - Mirror: Off 09:49:20 INFO - Online: Yes 09:49:20 INFO - Rotation: Supported 09:49:20 INFO - Return code: 0 09:49:20 INFO - Running post-action listener: _resource_record_post_action 09:49:20 INFO - ##### 09:49:20 INFO - ##### Finished run-tests step (success) 09:49:20 INFO - ##### 09:49:20 INFO - Running post-run listener: _resource_record_post_run 09:49:20 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 692736; Write bytes: 702952448; Read time: 6; Write time: 1930 09:49:20 INFO - TinderboxPrint: CPU usage
53.3% 09:49:20 INFO - TinderboxPrint: I/O read bytes / time
692,736 / 6 09:49:20 INFO - TinderboxPrint: I/O write bytes / time
702,952,448 / 1,930 09:49:20 INFO - TinderboxPrint: CPU idle
2,264.8 (46.7%) 09:49:20 INFO - TinderboxPrint: CPU system
967.3 (19.9%) 09:49:20 INFO - TinderboxPrint: CPU user
1,617.6 (33.4%) 09:49:20 INFO - TinderboxPrint: Swap in / out
406,986,752 / 0 09:49:20 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 398533120; Write bytes: 392799744; Read time: 30216; Write time: 821 09:49:20 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 692736; Write bytes: 292568576; Read time: 6; Write time: 1064 09:49:20 INFO - Running post-run listener: _upload_blobber_files 09:49:20 INFO - Blob upload gear active. 09:49:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:49:20 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 09:49:20 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'] 09:49:20 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 09:49:21 INFO - (blobuploader) - INFO - Open directory for files ... 09:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:49:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:21 INFO - (blobuploader) - INFO - Done attempting. 09:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:49:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:22 INFO - (blobuploader) - INFO - Done attempting. 09:49:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:49:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:49:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:26 INFO - (blobuploader) - INFO - Done attempting. 09:49:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:49:26 INFO - Return code: 0 09:49:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:49:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:49:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10241cec01a7bc6a937ed99115dc62c69119909f0acdba0f9cbd7ade3f81f4b6bcf94f91f3a7f8324cbd5bf11bf506c9e77012162d01cb69b391f90ddbc84b40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab9933dee6b41e779498990effa67d206025a418ab118a6e30e7394655109e5708016edf0dd58020b635d2ec1baf6e7866eeb80c4b94ebd16774e0d349c7b32f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b326bf00c7c45ea7ff85fd6d75281de0e2423aaa409a7812b7676ee7281bbe4a3b81bbde93fd894e14e11c761370f8ad35a48d247336c5264d48897a1ccfc2f"} 09:49:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:49:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:49:26 INFO - Contents: 09:49:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10241cec01a7bc6a937ed99115dc62c69119909f0acdba0f9cbd7ade3f81f4b6bcf94f91f3a7f8324cbd5bf11bf506c9e77012162d01cb69b391f90ddbc84b40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab9933dee6b41e779498990effa67d206025a418ab118a6e30e7394655109e5708016edf0dd58020b635d2ec1baf6e7866eeb80c4b94ebd16774e0d349c7b32f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b326bf00c7c45ea7ff85fd6d75281de0e2423aaa409a7812b7676ee7281bbe4a3b81bbde93fd894e14e11c761370f8ad35a48d247336c5264d48897a1ccfc2f"} 09:49:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:49:26 INFO - Copying logs to upload dir... 09:49:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:49:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.501848 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-07 09:49:26.601235) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 09:49:26.605288) ========= 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.BK33XtxlNj/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.2R5I6CGv4K/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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/10241cec01a7bc6a937ed99115dc62c69119909f0acdba0f9cbd7ade3f81f4b6bcf94f91f3a7f8324cbd5bf11bf506c9e77012162d01cb69b391f90ddbc84b40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab9933dee6b41e779498990effa67d206025a418ab118a6e30e7394655109e5708016edf0dd58020b635d2ec1baf6e7866eeb80c4b94ebd16774e0d349c7b32f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b326bf00c7c45ea7ff85fd6d75281de0e2423aaa409a7812b7676ee7281bbe4a3b81bbde93fd894e14e11c761370f8ad35a48d247336c5264d48897a1ccfc2f"} build_url:https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/GE5VqBLxSzyWS2-yeiahCQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10241cec01a7bc6a937ed99115dc62c69119909f0acdba0f9cbd7ade3f81f4b6bcf94f91f3a7f8324cbd5bf11bf506c9e77012162d01cb69b391f90ddbc84b40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab9933dee6b41e779498990effa67d206025a418ab118a6e30e7394655109e5708016edf0dd58020b635d2ec1baf6e7866eeb80c4b94ebd16774e0d349c7b32f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2b326bf00c7c45ea7ff85fd6d75281de0e2423aaa409a7812b7676ee7281bbe4a3b81bbde93fd894e14e11c761370f8ad35a48d247336c5264d48897a1ccfc2f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 09:49:26.641822) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:49:26.642207) ========= 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.BK33XtxlNj/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.2R5I6CGv4K/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.005233 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 09:49:26.695340) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 09:49:26.695697) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 09:49:26.696104) ========= ========= Total master_lag: 0.73 =========