builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0076 starttime: 1467205010.71 results: success (0) buildid: 20160629030209 builduid: 288a9013a362430191d43622a19db4df revision: e45890951ce77c3df05575bd54072b9f300d77b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:50.714769) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:50.715251) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:50.715584) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:50.742565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:50.743142) ========= 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.lmhyF38MRb/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.WTNLDD79BM/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-06-29 05:56:50-- 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% 24.7M=0s 2016-06-29 05:56:51 (24.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.530864 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:51.302116) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:51.302459) ========= 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.lmhyF38MRb/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.WTNLDD79BM/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.039265 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:51.362118) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 05:56:51.362473) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e45890951ce77c3df05575bd54072b9f300d77b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e45890951ce77c3df05575bd54072b9f300d77b0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lmhyF38MRb/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.WTNLDD79BM/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-06-29 05:56:51,440 truncating revision to first 12 chars 2016-06-29 05:56:51,440 Setting DEBUG logging. 2016-06-29 05:56:51,440 attempt 1/10 2016-06-29 05:56:51,440 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e45890951ce7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 05:56:52,205 unpacking tar archive at: mozilla-central-e45890951ce7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.024384 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-29 05:56:52.410035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:52.410499) ========= script_repo_revision: e45890951ce77c3df05575bd54072b9f300d77b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:52.411293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:52.411704) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 05:56:52.436314) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-29 05:56:52.437061) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lmhyF38MRb/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.WTNLDD79BM/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:56:52 INFO - MultiFileLogger online at 20160629 05:56:52 in /builds/slave/test 05:56:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:56:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:56:52 INFO - {'append_to_log': False, 05:56:52 INFO - 'base_work_dir': '/builds/slave/test', 05:56:52 INFO - 'blob_upload_branch': 'Firefox', 05:56:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:56:52 INFO - 'branch': 'Firefox', 05:56:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:56:52 INFO - 'config_files': ('talos/mac_config.py',), 05:56:52 INFO - 'default_actions': ('clobber', 05:56:52 INFO - 'read-buildbot-config', 05:56:52 INFO - 'download-and-extract', 05:56:52 INFO - 'populate-webroot', 05:56:52 INFO - 'create-virtualenv', 05:56:52 INFO - 'install', 05:56:52 INFO - 'run-tests'), 05:56:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:56:52 INFO - 'download_minidump_stackwalk': True, 05:56:52 INFO - 'download_symbols': 'ondemand', 05:56:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:56:52 INFO - 'tooltool.py': '/tools/tooltool.py', 05:56:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:56:52 INFO - '/tools/misc-python/virtualenv.py')}, 05:56:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:56:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:56:52 INFO - 'installer_path': 'installer.exe', 05:56:52 INFO - 'log_level': 'info', 05:56:52 INFO - 'log_name': 'talos', 05:56:52 INFO - 'log_to_console': True, 05:56:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:56:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:56:52 INFO - 'opt_config_files': (), 05:56:52 INFO - 'pip_index': False, 05:56:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:56:52 INFO - 'cmd': ('bash', 05:56:52 INFO - '-c', 05:56:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:56:52 INFO - 'enabled': True, 05:56:52 INFO - 'halt_on_failure': False, 05:56:52 INFO - 'name': 'check_screen_resolution'},), 05:56:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:56:52 INFO - 'cmd': ('bash', 05:56:52 INFO - '-c', 05:56:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:56:52 INFO - 'enabled': True, 05:56:52 INFO - 'halt_on_failure': False, 05:56:52 INFO - 'name': 'check_screen_resolution'},), 05:56:52 INFO - 'run_cmd_checks_enabled': True, 05:56:52 INFO - 'sps_profile': False, 05:56:52 INFO - 'sps_profile_interval': 0, 05:56:52 INFO - 'suite': 'dromaeojs', 05:56:52 INFO - 'system_bits': '32', 05:56:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:56:52 INFO - 'title': 't-yosemite-r7-0076', 05:56:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:56:52 INFO - 'use_talos_json': True, 05:56:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:56:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:56:52 INFO - 'work_dir': 'build'} 05:56:52 INFO - ##### 05:56:52 INFO - ##### Running clobber step. 05:56:52 INFO - ##### 05:56:52 INFO - Running pre-action listener: _resource_record_pre_action 05:56:52 INFO - Running main action method: clobber 05:56:52 INFO - rmtree: /builds/slave/test/build 05:56:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:56:55 INFO - Running post-action listener: _resource_record_post_action 05:56:55 INFO - ##### 05:56:55 INFO - ##### Finished clobber step (success) 05:56:55 INFO - ##### 05:56:55 INFO - ##### 05:56:55 INFO - ##### Running read-buildbot-config step. 05:56:55 INFO - ##### 05:56:55 INFO - Running pre-action listener: _resource_record_pre_action 05:56:55 INFO - Running main action method: read_buildbot_config 05:56:55 INFO - Using buildbot properties: 05:56:55 INFO - { 05:56:55 INFO - "project": "", 05:56:55 INFO - "product": "firefox", 05:56:55 INFO - "script_repo_revision": "production", 05:56:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:56:55 INFO - "repository": "", 05:56:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:56:55 INFO - "buildid": "20160629030209", 05:56:55 INFO - "stage_platform": "macosx64", 05:56:55 INFO - "basedir": "/builds/slave/test", 05:56:55 INFO - "nightly_build": "True", 05:56:55 INFO - "buildnumber": 3, 05:56:55 INFO - "slavename": "t-yosemite-r7-0076", 05:56:55 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:56:55 INFO - "platform": "yosemite_r7", 05:56:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:56:55 INFO - "branch": "mozilla-central", 05:56:55 INFO - "repo_path": "mozilla-central", 05:56:55 INFO - "revision": "e45890951ce77c3df05575bd54072b9f300d77b0", 05:56:55 INFO - "pgo_build": "False", 05:56:55 INFO - "builduid": "288a9013a362430191d43622a19db4df", 05:56:55 INFO - "slavebuilddir": "test" 05:56:55 INFO - } 05:56:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:56:55 INFO - Running post-action listener: _resource_record_post_action 05:56:55 INFO - ##### 05:56:55 INFO - ##### Finished read-buildbot-config step (success) 05:56:55 INFO - ##### 05:56:55 INFO - ##### 05:56:55 INFO - ##### Running download-and-extract step. 05:56:55 INFO - ##### 05:56:55 INFO - Running pre-action listener: _resource_record_pre_action 05:56:55 INFO - Running main action method: download_and_extract 05:56:55 INFO - mkdir: /builds/slave/test/build/tests 05:56:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:56:55 INFO - https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:56:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:56:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:56:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:56:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:02 INFO - Downloaded 1199 bytes. 05:57:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:57:02 INFO - Using the following test package requirements: 05:57:02 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:57:02 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:57:02 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:57:02 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'jsshell-mac.zip'], 05:57:02 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:57:02 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:57:02 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:57:02 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:57:02 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:57:02 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:57:02 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:57:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:57:02 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')]} 05:57:02 INFO - https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:57:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:05 INFO - Downloaded 18786979 bytes. 05:57:05 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'] 05:57:05 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 05:57:08 INFO - Return code: 0 05:57:08 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 05:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:08 INFO - https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:57:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:57:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:10 INFO - Downloaded 18786979 bytes. 05:57:10 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'] 05:57:10 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 05:57:13 INFO - Return code: 0 05:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:13 INFO - https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:57:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:57:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:57:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:14 INFO - Downloaded 14405245 bytes. 05:57:14 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'] 05:57:14 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 05:57:14 INFO - Return code: 0 05:57: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')]} 05:57:14 INFO - https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:57:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:57:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cefMevC6TbW8gUAcXQxjPw/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 05:57:18 INFO - Downloaded 112484836 bytes. 05:57:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:18 INFO - mkdir: /builds/slave/test/properties 05:57:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:57:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:57:18 INFO - Contents: 05:57:18 INFO - build_url:https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:57:19 INFO - Running post-action listener: _resource_record_post_action 05:57:19 INFO - Running post-action listener: set_extra_try_arguments 05:57:19 INFO - ##### 05:57:19 INFO - ##### Finished download-and-extract step (success) 05:57:19 INFO - ##### 05:57:19 INFO - ##### 05:57:19 INFO - ##### Running populate-webroot step. 05:57:19 INFO - ##### 05:57:19 INFO - Running pre-action listener: _resource_record_pre_action 05:57:19 INFO - Running main action method: populate_webroot 05:57:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:57:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:57:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:57:19 INFO - '--tppagecycles', 05:57:19 INFO - '7'], 05:57:19 INFO - 'tests': ['tsvgm']}}, 05:57:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:57:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:57:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:57:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:57:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:57:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['damp', 'tps']}, 05:57:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'tests': ['damp', 'tps']}, 05:57:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['dromaeo_dom']}, 05:57:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:57:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['basic_compositor_video']}, 05:57:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:57:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['a11yr', 05:57:19 INFO - 'ts_paint', 05:57:19 INFO - 'tpaint', 05:57:19 INFO - 'sessionrestore', 05:57:19 INFO - 'sessionrestore_no_auto_restore', 05:57:19 INFO - 'tabpaint']}, 05:57:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:57:19 INFO - 'ts_paint', 05:57:19 INFO - 'tpaint', 05:57:19 INFO - 'sessionrestore', 05:57:19 INFO - 'sessionrestore_no_auto_restore', 05:57:19 INFO - 'tabpaint']}, 05:57:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:57:19 INFO - 'ts_paint', 05:57:19 INFO - 'tpaint', 05:57:19 INFO - 'sessionrestore', 05:57:19 INFO - 'sessionrestore_no_auto_restore', 05:57:19 INFO - 'tabpaint']}, 05:57:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:57:19 INFO - 'ts_paint', 05:57:19 INFO - 'tpaint', 05:57:19 INFO - 'sessionrestore', 05:57:19 INFO - 'sessionrestore_no_auto_restore', 05:57:19 INFO - 'tabpaint']}, 05:57:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['a11yr', 05:57:19 INFO - 'ts_paint', 05:57:19 INFO - 'tpaint', 05:57:19 INFO - 'sessionrestore', 05:57:19 INFO - 'sessionrestore_no_auto_restore', 05:57:19 INFO - 'tabpaint']}, 05:57:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['a11yr', 05:57:19 INFO - 'ts_paint', 05:57:19 INFO - 'tpaint', 05:57:19 INFO - 'sessionrestore', 05:57:19 INFO - 'sessionrestore_no_auto_restore', 05:57:19 INFO - 'tabpaint']}, 05:57:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['tsvgx', 05:57:19 INFO - 'tsvgr_opacity', 05:57:19 INFO - 'tart', 05:57:19 INFO - 'tscrollx', 05:57:19 INFO - 'cart']}, 05:57:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:57:19 INFO - 'tsvgr_opacity', 05:57:19 INFO - 'tart', 05:57:19 INFO - 'tscrollx', 05:57:19 INFO - 'cart']}, 05:57:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'talos_options': ['--disable-e10s'], 05:57:19 INFO - 'tests': ['tp5o']}, 05:57:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'tests': ['tp5o']}, 05:57:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'talos_options': ['--disable-e10s', 05:57:19 INFO - '--xperf_path', 05:57:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:19 INFO - 'tests': ['tp5n']}, 05:57:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:57:19 INFO - 'talos_options': ['--xperf_path', 05:57:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:57:19 INFO - 'tests': ['tp5n']}}, 05:57:19 INFO - 'talos.zip': {'path': '', 05:57:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:57:19 INFO - Running post-action listener: _resource_record_post_action 05:57:19 INFO - ##### 05:57:19 INFO - ##### Finished populate-webroot step (success) 05:57:19 INFO - ##### 05:57:19 INFO - ##### 05:57:19 INFO - ##### Running create-virtualenv step. 05:57:19 INFO - ##### 05:57:19 INFO - Running pre-action listener: _resource_record_pre_action 05:57:19 INFO - Running main action method: create_virtualenv 05:57:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:57:19 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 05:57:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:57:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:57:19 INFO - Using real prefix '/tools/python27' 05:57:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:57:20 INFO - Installing distribute.............................................................................................................................................................................................done. 05:57:22 INFO - Installing pip.................done. 05:57:22 INFO - Return code: 0 05:57:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:57:22 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')]} 05:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:22 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')]} 05:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:22 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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:22 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 05:57:22 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 05:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:22 INFO - 'HOME': '/Users/cltbld', 05:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:22 INFO - 'LOGNAME': 'cltbld', 05:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:22 INFO - 'MOZ_NO_REMOTE': '1', 05:57:22 INFO - 'NO_EM_RESTART': '1', 05:57:22 INFO - 'PAGER': '/bin/cat', 05:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:22 INFO - 'PWD': '/builds/slave/test', 05:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:22 INFO - 'SHELL': '/bin/bash', 05:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:22 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:22 INFO - 'USER': 'cltbld', 05:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:22 INFO - 'XPC_FLAGS': '0x0', 05:57:22 INFO - 'XPC_SERVICE_NAME': '0', 05:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:23 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 05:57:23 INFO - Cleaning up... 05:57:23 INFO - Return code: 0 05:57:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:23 INFO - 'HOME': '/Users/cltbld', 05:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:23 INFO - 'LOGNAME': 'cltbld', 05:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:23 INFO - 'MOZ_NO_REMOTE': '1', 05:57:23 INFO - 'NO_EM_RESTART': '1', 05:57:23 INFO - 'PAGER': '/bin/cat', 05:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:23 INFO - 'PWD': '/builds/slave/test', 05:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:23 INFO - 'SHELL': '/bin/bash', 05:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:23 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:23 INFO - 'USER': 'cltbld', 05:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:23 INFO - 'XPC_FLAGS': '0x0', 05:57:23 INFO - 'XPC_SERVICE_NAME': '0', 05:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:23 INFO - Downloading/unpacking psutil>=3.1.1 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:57:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:26 INFO - Installing collected packages: psutil 05:57:26 INFO - Running setup.py install for psutil 05:57:26 INFO - building 'psutil._psutil_osx' extension 05:57:26 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 05:57:26 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 05:57:26 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 05:57:26 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 05:57:26 INFO - building 'psutil._psutil_posix' extension 05:57:26 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 05:57:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:57:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:57:27 INFO - ^ 05:57:27 INFO - 1 warning generated. 05:57:27 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 05:57:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:57:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:57:27 INFO - Successfully installed psutil 05:57:27 INFO - Cleaning up... 05:57:27 INFO - Return code: 0 05:57:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:57:27 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')]} 05:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:27 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')]} 05:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:27 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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:27 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 05:57:27 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 05:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:27 INFO - 'HOME': '/Users/cltbld', 05:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:27 INFO - 'LOGNAME': 'cltbld', 05:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:27 INFO - 'MOZ_NO_REMOTE': '1', 05:57:27 INFO - 'NO_EM_RESTART': '1', 05:57:27 INFO - 'PAGER': '/bin/cat', 05:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:27 INFO - 'PWD': '/builds/slave/test', 05:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:27 INFO - 'SHELL': '/bin/bash', 05:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:27 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:27 INFO - 'USER': 'cltbld', 05:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:27 INFO - 'XPC_FLAGS': '0x0', 05:57:27 INFO - 'XPC_SERVICE_NAME': '0', 05:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:57:30 INFO - Installing collected packages: mozsystemmonitor 05:57:30 INFO - Running setup.py install for mozsystemmonitor 05:57:30 INFO - Successfully installed mozsystemmonitor 05:57:30 INFO - Cleaning up... 05:57:30 INFO - Return code: 0 05:57:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:57:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:30 INFO - 'HOME': '/Users/cltbld', 05:57:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:30 INFO - 'LOGNAME': 'cltbld', 05:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:30 INFO - 'MOZ_NO_REMOTE': '1', 05:57:30 INFO - 'NO_EM_RESTART': '1', 05:57:30 INFO - 'PAGER': '/bin/cat', 05:57:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:30 INFO - 'PWD': '/builds/slave/test', 05:57:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:30 INFO - 'SHELL': '/bin/bash', 05:57:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:30 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:30 INFO - 'USER': 'cltbld', 05:57:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:30 INFO - 'XPC_FLAGS': '0x0', 05:57:30 INFO - 'XPC_SERVICE_NAME': '0', 05:57:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:30 INFO - Downloading/unpacking jsonschema==2.5.1 05:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:57:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:33 INFO - Downloading functools32-3.2.3-2.tar.gz 05:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:57:33 INFO - warning: no files found matching '*.txt' 05:57:33 INFO - no previously-included directories found matching 'build' 05:57:33 INFO - no previously-included directories found matching 'dist' 05:57:33 INFO - no previously-included directories found matching '.git*' 05:57:33 INFO - Installing collected packages: jsonschema, functools32 05:57:33 INFO - Running setup.py install for functools32 05:57:33 INFO - warning: no files found matching '*.txt' 05:57:33 INFO - no previously-included directories found matching 'build' 05:57:33 INFO - no previously-included directories found matching 'dist' 05:57:33 INFO - no previously-included directories found matching '.git*' 05:57:33 INFO - Successfully installed jsonschema functools32 05:57:33 INFO - Cleaning up... 05:57:33 INFO - Return code: 0 05:57:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:57:33 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')]} 05:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:33 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')]} 05:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:33 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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:33 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 05:57:33 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 05:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:33 INFO - 'HOME': '/Users/cltbld', 05:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:33 INFO - 'LOGNAME': 'cltbld', 05:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:33 INFO - 'MOZ_NO_REMOTE': '1', 05:57:33 INFO - 'NO_EM_RESTART': '1', 05:57:33 INFO - 'PAGER': '/bin/cat', 05:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:33 INFO - 'PWD': '/builds/slave/test', 05:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:33 INFO - 'SHELL': '/bin/bash', 05:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:33 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:33 INFO - 'USER': 'cltbld', 05:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:33 INFO - 'XPC_FLAGS': '0x0', 05:57:33 INFO - 'XPC_SERVICE_NAME': '0', 05:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:33 INFO - Downloading/unpacking blobuploader==1.2.4 05:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:57:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:57:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 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 05:57:37 INFO - Downloading docopt-0.6.1.tar.gz 05:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:57:37 INFO - Installing collected packages: blobuploader, requests, docopt 05:57:37 INFO - Running setup.py install for blobuploader 05:57:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:57:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:57:37 INFO - Running setup.py install for requests 05:57:38 INFO - Running setup.py install for docopt 05:57:38 INFO - Successfully installed blobuploader requests docopt 05:57:38 INFO - Cleaning up... 05:57:38 INFO - Return code: 0 05:57:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:38 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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:38 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 05:57:38 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 05:57:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:38 INFO - 'HOME': '/Users/cltbld', 05:57:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:38 INFO - 'LOGNAME': 'cltbld', 05:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:38 INFO - 'MOZ_NO_REMOTE': '1', 05:57:38 INFO - 'NO_EM_RESTART': '1', 05:57:38 INFO - 'PAGER': '/bin/cat', 05:57:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:38 INFO - 'PWD': '/builds/slave/test', 05:57:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:38 INFO - 'SHELL': '/bin/bash', 05:57:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:38 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:38 INFO - 'USER': 'cltbld', 05:57:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:38 INFO - 'XPC_FLAGS': '0x0', 05:57:38 INFO - 'XPC_SERVICE_NAME': '0', 05:57:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:38 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-50wkpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:38 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-p2kY9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:38 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Z8o5_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-l6US1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-rOcBmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-VvJqCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-3BGyG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-KVNthE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-vPC7sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-0SuoTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-xvgYMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:39 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-lVUva5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:40 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-W1tZfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:40 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-_KAxew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:40 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-BTDfpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:40 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-4OtLZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:40 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Nu1nne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:57:40 INFO - Running setup.py install for manifestparser 05:57:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:57:40 INFO - Running setup.py install for mozcrash 05:57:40 INFO - Running setup.py install for mozdebug 05:57:41 INFO - Running setup.py install for mozdevice 05:57:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Running setup.py install for mozfile 05:57:41 INFO - Running setup.py install for mozhttpd 05:57:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Running setup.py install for mozinfo 05:57:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Running setup.py install for mozInstall 05:57:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:57:41 INFO - Running setup.py install for mozleak 05:57:41 INFO - Running setup.py install for mozlog 05:57:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for moznetwork 05:57:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozprocess 05:57:42 INFO - Running setup.py install for mozprofile 05:57:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozrunner 05:57:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:57:42 INFO - Running setup.py install for mozscreenshot 05:57:42 INFO - Running setup.py install for moztest 05:57:43 INFO - Running setup.py install for mozversion 05:57:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:57:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:57:43 INFO - Cleaning up... 05:57:43 INFO - Return code: 0 05:57:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57: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')]} 05:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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')]} 05:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:43 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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:43 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 05:57:43 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 05:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:43 INFO - 'HOME': '/Users/cltbld', 05:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:43 INFO - 'LOGNAME': 'cltbld', 05:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:43 INFO - 'MOZ_NO_REMOTE': '1', 05:57:43 INFO - 'NO_EM_RESTART': '1', 05:57:43 INFO - 'PAGER': '/bin/cat', 05:57:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:43 INFO - 'PWD': '/builds/slave/test', 05:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:43 INFO - 'SHELL': '/bin/bash', 05:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:43 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:43 INFO - 'USER': 'cltbld', 05:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:43 INFO - 'XPC_FLAGS': '0x0', 05:57:43 INFO - 'XPC_SERVICE_NAME': '0', 05:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:57:43 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-dKQejp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:57:43 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)) 05:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:57:43 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-lze1mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:57:43 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)) 05:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:57:43 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-odqV1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:57:43 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)) 05:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:57:43 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-_juMda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:57:43 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)) 05:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:57:43 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-4mLGvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:57:43 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)) 05:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:57:43 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-b7xGmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Aac8ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-IZs0rY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-2qPi7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-pOwAeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-zShoHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-6z40Le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:57:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-TzHTef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:57:44 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)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-J79Cf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:57:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:44 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-byi7iF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:57:45 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-N2TFwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:57:45 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)) 05:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:57:45 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-V4cdd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:57:45 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)) 05:57:45 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)) 05:57:45 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)) 05:57:45 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)) 05:57:45 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)) 05:57:45 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)) 05:57:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:57:47 INFO - Downloading blessings-1.6.tar.gz 05:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:57:47 INFO - Installing collected packages: blessings 05:57:47 INFO - Running setup.py install for blessings 05:57:47 INFO - Successfully installed blessings 05:57:47 INFO - Cleaning up... 05:57:48 INFO - Return code: 0 05:57:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:57:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:48 INFO - Reading from file tmpfile_stdout 05:57:48 INFO - Current package versions: 05:57:48 INFO - blessings == 1.6 05:57:48 INFO - blobuploader == 1.2.4 05:57:48 INFO - docopt == 0.6.1 05:57:48 INFO - functools32 == 3.2.3-2 05:57:48 INFO - jsonschema == 2.5.1 05:57:48 INFO - manifestparser == 1.1 05:57:48 INFO - mozInstall == 1.12 05:57:48 INFO - mozcrash == 0.17 05:57:48 INFO - mozdebug == 0.1 05:57:48 INFO - mozdevice == 0.48 05:57:48 INFO - mozfile == 1.2 05:57:48 INFO - mozhttpd == 0.7 05:57:48 INFO - mozinfo == 0.9 05:57:48 INFO - mozleak == 0.1 05:57:48 INFO - mozlog == 3.2 05:57:48 INFO - moznetwork == 0.27 05:57:48 INFO - mozprocess == 0.22 05:57:48 INFO - mozprofile == 0.28 05:57:48 INFO - mozrunner == 6.11 05:57:48 INFO - mozscreenshot == 0.1 05:57:48 INFO - mozsystemmonitor == 0.3 05:57:48 INFO - moztest == 0.7 05:57:48 INFO - mozversion == 1.4 05:57:48 INFO - psutil == 3.1.1 05:57:48 INFO - requests == 1.2.3 05:57:48 INFO - wsgiref == 0.1.2 05:57:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:57: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')]} 05:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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')]} 05:57:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57:48 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 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57:48 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 05:57:48 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 05:57:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:48 INFO - 'HOME': '/Users/cltbld', 05:57:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:48 INFO - 'LOGNAME': 'cltbld', 05:57:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:48 INFO - 'MOZ_NO_REMOTE': '1', 05:57:48 INFO - 'NO_EM_RESTART': '1', 05:57:48 INFO - 'PAGER': '/bin/cat', 05:57:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:48 INFO - 'PWD': '/builds/slave/test', 05:57:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:48 INFO - 'SHELL': '/bin/bash', 05:57:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:48 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:48 INFO - 'USER': 'cltbld', 05:57:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:48 INFO - 'XPC_FLAGS': '0x0', 05:57:48 INFO - 'XPC_SERVICE_NAME': '0', 05:57:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 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)) 05:57:48 INFO - Cleaning up... 05:57:48 INFO - Return code: 0 05:57:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:57: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')]} 05:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:57:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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')]} 05:57:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:57:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:57: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10408fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104124618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9019cb7920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, '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.lmhyF38MRb/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.WTNLDD79BM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 05:57: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'] in /builds/slave/test/build 05:57: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 05:57:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:57:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:57:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:57:48 INFO - 'HOME': '/Users/cltbld', 05:57:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:57:48 INFO - 'LOGNAME': 'cltbld', 05:57:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:57:48 INFO - 'MOZ_NO_REMOTE': '1', 05:57:48 INFO - 'NO_EM_RESTART': '1', 05:57:48 INFO - 'PAGER': '/bin/cat', 05:57:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:57:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:57:48 INFO - 'PWD': '/builds/slave/test', 05:57:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:57:48 INFO - 'SHELL': '/bin/bash', 05:57:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:57:48 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:57:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:57:48 INFO - 'USER': 'cltbld', 05:57:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:57:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:57:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:57:48 INFO - 'XPC_FLAGS': '0x0', 05:57:48 INFO - 'XPC_SERVICE_NAME': '0', 05:57:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:57:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:57:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:57:48 INFO - Cleaning up... 05:57:48 INFO - Return code: 0 05:57:48 INFO - Running post-action listener: _resource_record_post_action 05:57:48 INFO - Running post-action listener: _start_resource_monitoring 05:57:48 INFO - Starting resource monitoring. 05:57:48 INFO - ##### 05:57:48 INFO - ##### Finished create-virtualenv step (success) 05:57:48 INFO - ##### 05:57:48 INFO - ##### 05:57:48 INFO - ##### Running install step. 05:57:48 INFO - ##### 05:57:48 INFO - Running pre-action listener: _resource_record_pre_action 05:57:48 INFO - Running main action method: install 05:57:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:57:49 INFO - Reading from file tmpfile_stdout 05:57:49 INFO - Detecting whether we're running mozinstall >=1.0... 05:57:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:57:49 INFO - Reading from file tmpfile_stdout 05:57:49 INFO - Output received: 05:57:49 INFO - Usage: mozinstall [options] installer 05:57:49 INFO - Options: 05:57:49 INFO - -h, --help show this help message and exit 05:57:49 INFO - -d DEST, --destination=DEST 05:57:49 INFO - Directory to install application into. [default: 05:57:49 INFO - "/builds/slave/test"] 05:57:49 INFO - --app=APP Application being installed. [default: firefox] 05:57:49 INFO - mkdir: /builds/slave/test/build/application 05:57:49 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'] 05:57:49 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 05:58:23 INFO - Reading from file tmpfile_stdout 05:58:23 INFO - Output received: 05:58:23 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:58:23 INFO - Running post-action listener: _resource_record_post_action 05:58:23 INFO - ##### 05:58:23 INFO - ##### Finished install step (success) 05:58:23 INFO - ##### 05:58:23 INFO - ##### 05:58:23 INFO - ##### Running run-tests step. 05:58:23 INFO - ##### 05:58:23 INFO - Running pre-action listener: _resource_record_pre_action 05:58:23 INFO - Running main action method: run_tests 05:58:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:58:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:58:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:58:23 INFO - 2016-06-29 05:58:23.604 screenresolution[1646:8104] starting screenresolution argv=screenresolution get 05:58:23 INFO - 2016-06-29 05:58:23.607 screenresolution[1646:8104] Display 0: 1600x1200x32@60 05:58:23 INFO - 2016-06-29 05:58:23.613 screenresolution[1647:8107] starting screenresolution argv=screenresolution list 05:58:23 INFO - Available Modes on Display 0 05:58:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:58:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:58:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:58:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:58:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:58:23 INFO - Intel Iris: 05:58:23 INFO - Chipset Model: Intel Iris 05:58:23 INFO - Type: GPU 05:58:23 INFO - Bus: Built-In 05:58:23 INFO - VRAM (Dynamic, Max): 1536 MB 05:58:23 INFO - Vendor: Intel (0x8086) 05:58:23 INFO - Device ID: 0x0a2e 05:58:23 INFO - Revision ID: 0x0009 05:58:23 INFO - Displays: 05:58:23 INFO - Display: 05:58:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:58:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:58:23 INFO - Display Serial Number: 1600x1200 60 05:58:23 INFO - Main Display: Yes 05:58:23 INFO - Mirror: Off 05:58:23 INFO - Online: Yes 05:58:23 INFO - Rotation: Supported 05:58:23 INFO - Return code: 0 05:58:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:58:23 INFO - Python 2.7.3 05:58:23 INFO - Return code: 0 05:58:23 INFO - grabbing minidump binary from tooltool 05:58:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ff3d30>, '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 0x103d58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103febb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:58:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:58:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:58:23 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 05:58:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:58:23 INFO - Return code: 0 05:58:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:58:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:58:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:58:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:58:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:58:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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 05:58:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0076 --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 05:58:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lmhyF38MRb/Render', 05:58:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:58:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:58:23 INFO - 'HOME': '/Users/cltbld', 05:58:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:58:23 INFO - 'LOGNAME': 'cltbld', 05:58:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:58:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:58:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:58:23 INFO - 'MOZ_NO_REMOTE': '1', 05:58:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:58:23 INFO - 'NO_EM_RESTART': '1', 05:58:23 INFO - 'PAGER': '/bin/cat', 05:58:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:58:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:58:23 INFO - 'PWD': '/builds/slave/test', 05:58:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:58:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:58:23 INFO - 'SHELL': '/bin/bash', 05:58:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WTNLDD79BM/Listeners', 05:58:23 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 05:58:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:58:23 INFO - 'USER': 'cltbld', 05:58:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:58:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:58:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:58:23 INFO - 'XPC_FLAGS': '0x0', 05:58:23 INFO - 'XPC_SERVICE_NAME': '0', 05:58:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:58:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--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 05:58:24 INFO - mozversion application_buildid: 20160629030209 05:58:24 INFO - mozversion application_changeset: e45890951ce77c3df05575bd54072b9f300d77b0 05:58:24 INFO - mozversion application_display_name: Nightly 05:58:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:58:24 INFO - mozversion application_name: Firefox 05:58:24 INFO - mozversion application_remotingname: firefox 05:58:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:58:24 INFO - mozversion application_vendor: Mozilla 05:58:24 INFO - mozversion application_version: 50.0a1 05:58:24 INFO - mozversion platform_buildid: 20160629030209 05:58:24 INFO - mozversion platform_changeset: e45890951ce77c3df05575bd54072b9f300d77b0 05:58:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:58:24 INFO - mozversion platform_version: 50.0a1 05:58:24 INFO - using testdate: 1467205104 05:58:24 INFO - actual date: 1467205104 05:58:24 INFO - starting webserver on 'localhost:49281' 05:58:24 INFO - SUITE-START | Running 2 tests 05:58:24 INFO - TEST-START | dromaeo_css 05:58:24 INFO - operating with platform_type : mac_ 05:58:24 INFO - Initialising browser for dromaeo_css test... 05:58:24 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpzx_vu6/profile http://localhost:49281/getInfo.html) 05:58:26 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 05:58:26 INFO - PROCESS | 1657 | colorDepth:24 05:58:26 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 05:58:26 INFO - PROCESS | 1657 | __metrics 05:58:26 INFO - PROCESS | 1657 | 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" 05:58:26 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:58:27 INFO - TEST-INFO | 1657: exit 0 05:58:27 INFO - Browser initialized. 05:58:27 INFO - Running cycle 1/1 for dromaeo_css test... 05:58:27 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpzx_vu6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:58:39 INFO - PROCESS | 1661 | RSS: Main: 243347456 05:58:39 INFO - PROCESS | 1661 | 05:59:31 INFO - PROCESS | 1661 | 1467205171800 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] 06:01:40 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 06:01:41 INFO - PROCESS | 1661 | RSS: Main: 357498880 06:01:41 INFO - PROCESS | 1661 | 06:04:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 06:04:45 INFO - PROCESS | 1661 | RSS: Main: 376930304 06:04:45 INFO - PROCESS | 1661 | 06:07:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 06:07:48 INFO - PROCESS | 1661 | RSS: Main: 370982912 06:07:48 INFO - PROCESS | 1661 | 06:10:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 06:10:51 INFO - PROCESS | 1661 | RSS: Main: 369025024 06:10:51 INFO - PROCESS | 1661 | 06:13:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 06:13:55 INFO - PROCESS | 1661 | RSS: Main: 336216064 06:13:55 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 06:16:58 INFO - PROCESS | 1661 | RSS: Main: 391131136 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | __start_tp_report 06:16:58 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 06:16:58 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 06:16:58 INFO - PROCESS | 1661 | |i|pagename|runs| 06:16:58 INFO - PROCESS | 1661 | |0;dojo.html;3209.3260415312784;3310.5199746036833;3350.899473015809;3379.2227787608863;3420.2646431017324;4853.466118726941;5588.832335029945;5680.3467601225875;5753.223314852499;5773.9711301443485;5723.198752174699;5768.105943578752;5770.798022069229;5777.104548794943;5778.5377169826415;4383;5190.117679994391;5197.662151960129;5250.976059668347;5322.787088516455;4728.125296820082;5248.475152484759;5279.366476022883;5298.390685071208;5405.729713514309;5260.894782104356;5341.1988201769855;5367.141257398817;5369.167778994262;5376.005438993799;202.75488779590017;5282.286891269668;5294.391145018295;5373.704446255454;5384.596155288369;5232.03207406631;5242.2136679498;5254.65844720092;5266;5291.629585928987;5714.057180565202;5720.885582288331;5754.395788442195;5811.535077193815;5834.154047663065;6126.816195514141;6157.415045570664;6221.004639257697;6264.937350626481;6285;6162.84592885177;6183.907241391383;6227.128202051671;6233.0338797486465;6267.091271765614;5865.325487568942;5915.467607915309;5918.260217472816;5920.644761314335;5922.733476993589;6196.659183744847;6200.038993955944;6225.190725205694;6269.717862696099;6309.589876658002;6176.69116544171;6194.876102478015;6197.752089916353;6199.56603037783;6199.5660303779205;6172.259328880563;6226.377362263737;6249.156363890907;6265.091561723616;6282.4974002079725;6073.392660733891;6124.991279909863;6136.815895523141;6172.598781079216;6178.227721534809;6259.311475737665;6274.623522588659;6274.6548939808745;6278.6860656966965;6289.8742025159245;4936.062148191832;4941.555260026615;4943.2585112233455;4946.9010619787405;4959.652824302264;4401.515833258339;4403.097365040159;4445.066536027406;4446.710963787408;4463.995600989816;5774.393688662714;5774.566907481955;5785.768569257267;5793.1020691792;5848.678322692293;6190.783322583778;6216.813495595139;6237.937620623827;6271.843203919847;6276.843078923063;5462.399136095027;5679.091345384719;5700.25896633435;5799.014167591519;5803.506701930383;5431.619786614978;5442.374126975448;5458.2904222451625;5462.371827239839;5462.617616766788;6176.980798168252;6242.063690446378;6286.088517164985;6297.149884765498;6309.022101574264;6172.382761723971;6191.288001879841;6198.0702894566175;6241.282252540835;6262.467690246198;6244.438000579969;6291.307956124731;6306.211723534559;6310.242770867525;6316.652584107735;6002.189704389852;6057.636541807506;6199.969000155151;6228.5951413158;6240.251169859646;6147.631142131487;6178.907316390348;6208.410201030895;6239.625622462667;6263.4362907338555;6177.413145751147;6179;6187.721552530237;6189.752409903734;6194.5973511721595;5970.611910225822;6051.878962420815;6149.354317796567;6173.660448675366;6178.7219575120125;5925.348211696796;5948.643081415129;5958.612690175125;5975.581709280483;6020.1270815730595;4495.965927836551;6077.11881777131;6187.659678717714;6194.101855231056;6197.101420293942;5819.854503637443;5875.823725288248;5891.3224979127945;5975;6038.426349496791 06:16:58 INFO - PROCESS | 1661 | |1;ext.html;483.1496566043763;494.2586120818772;504.17567277501274;520.1988937036965;537.3739593373715;25632.87183564082;41593.376099358495;42937.35593966093;43025.35461968069;43098.78450607751;43841;44276.33585496212;44365.55634443655;44385.8903527412;44545;8635;9160.541972901361;9278.396904201205;9520.23838092951;9753.609855605768;8657.740267791976;11595.710107247343;11879.406029698524;11889;12010.819837702442;11848.585299514518;11929.284242945409;11942;11945.820812687773;11971.521139154424;8754;11563.884361156364;11796.410179491035;11830.408479576074;11887.583934562248;10331;11679.124065695107;11768.352740599265;11769.058475321954;11841.111916606204;41313;41781.95545111366;41821.79089104535;41878.953026174284;42032;2973.137790040907;3616.6564176403463;3760.3795373763296;3769.2461507698295;3997.420374045776;31716.04851854448;32321.83839080813;32403;32689.019329420156;32899.6710032899;3254.6094468663914;3278.8360581970805;3292.9012129635908;3375.038114137459;3428.9884484077006;521232;523121;523532;524899;525126;61038;61165;61366;61499;61506.38493615007;34955.475667865;35773.284534309685;35878.92363229107;37165.25669486595;37351.813240933625;15019.324130414157;15029.398824046915;15245.466408675644;15252;15290.464833730766;10406.323588966692;10646.520906559223;10646.627368042236;10882.020618144405;11341.659750207342;16888.240029198714;19500.512487187934;19589.200645841574;20125.99370031522;20222.49443763888;6844.760433384808;8003.719869804645;8039.829246103519;8054.476459030261;8077.838443231102;877.9868301975475;967.8403063494585;991.3704797453524;1012.4380968562372;1084.34939036579;29885;29950.55074173889;30170.24574385614;30391.392172156433;30641.540376894365;948.5589201021559;984.8621193032981;1069.395791377869;1086.435053772034;1127.5320741892024;13395.330233488286;15793.921030395004;15875.682486350208;15937.123458209679;16002;707494;710123;711879;712011;715025;59100.40899591035;61064;61177.694111529156;61324;61365.38634613686;1268.796992481217;1308.0581980973684;1412.2232771975578;1418.6737050478241;1461.634591352162;27222.911083556435;30769.384612308077;31677.04968850938;32054.198645033128;32279.35441291114;52080;52438.21342679939;53267.733661333;53282.4671753293;53402.46597534131;560236;574590;574656;575609;577040;36677.449838251916;43995.78002109969;44627;44692;45706.31440528328;48796.75601621969;49008.529744107735;49086.75456622694;49684.503154967984;50169.74915125401;39878.00304992399;40070;40569.59430405658;41239;41272;36525.26949461049;38697.22605547817;38726.61273387343;38891;39373.409398858465;38270.617293826705;38599.42100868433;39062.023449413995;39160;39199.60800392074;32777;34515.654843451244;35385.469217962265;35501.64498355087;35562;22668.413211075065;25840.741592583832;26000.73999260059;26024.73975260299;26706 06:16:58 INFO - PROCESS | 1661 | |2;jquery.html;329.76586623497315;357.86758899207297;380.61367711772556;381.7785684303104;392.6623104130449;19936.302229421974;21236.25673101442;21516.784832151712;21820.563588728255;21824.127034918605;20634;21011.894940525315;21075.789242107574;21613.567728645456;21629.45926351842;5594.13290939905;5641.210230567724;5912.290525136968;6041.335453100155;6047.183630209931;3826.1582451860545;4173.165366926612;4970.527799859007;5982.012967860298;6019.608725432833;5925.200097986782;5968.820935371946;5977.133315669225;5977.133315669225;5987.610805297663;5989.951758442256;6086.543509236825;6646.401823835829;6648.800535983928;6667.366600172976;3742.3825068863603;5472.343318801769;5847.707614619252;5855.326637436707;5884.0585506318785;13088.345582720825;13653.249071301074;13685.452581896812;13688.904887609067;20525;2047.119738512439;2104.158336665331;2189.6606026065983;2352.8235382346393;2602.5705758549816;9970.052844979788;9981.850272245922;10515.894841051568;10531.26281160319;10550.78898422035;1878.990605046966;1935.5064458563118;1945.2899691612602;1951.3560525026708;2028.3205126282712;116495;122105.38947305296;123827.38086309601;124406.37796811048;124517;11550.942245288888;11551.960323570918;11634.592789252336;11792.882071179178;11862.762744745056;14564.053336533092;14624;14643.85356146425;14661.92669036648;14674.41302347894;7966.920330796734;8093.554854482943;8127.715529956423;8197.049062337554;8199.303059239912;7078.292170782984;7361.043064401593;7792.41556883236;7806;7899.131095579482;4560.201964656172;4598.425196850394;4626.653001024936;4633.397658304399;4637.837675681335;1592.8168260650173;1741.9303227870996;1754.1054062427997;1757.9735636621947;1907.6947688369794;870.260278763059;907.9319051071064;944.1974321826392;1010.3483253301579;1042.6816902523003;17228.966262024318;18143.462024905017;20352.796472035385;21117.472063198544;21127.154913803584;876.4522173641474;894.4365050018447;934.131257930118;953.6709835106877;955.7992821507561;11004.944975275232;11820.290782552902;11835.934765871112;12160.635180944586;12212.633620991384;530671;617377;623515;633204;634942.8252858799;117140.41429792797;119223;120094;121633;131480;1003.2575893838507;1018.9898101019044;1035.53400969564;1055.1664185293685;1059.7297689089232;12842.422091006114;16534.917325413295;17521.912390438476;18766.061696914825;19339.806601934364;54491.45508545022;55818.32545023656;55963;56085.43914560802;56439;72702;72812;74526.25473745193;76601;79561.60219199098;7750.224977502205;8051.798705032421;8138.267555919995;8139.674413023413;8336.749897503085;10634.521446534769;13069.869301306866;13262.27057511846;13282.53511127106;13343.266120363473;11220.495077721685;11310.60412885545;11330.490127944428;11433.714157145805;11495.080393568662;9596.616135354509;10492.580296788043;10849.566017359533;10991.780164396507;11170.27393219438;11423.485943132413;11449.484773185393;11583.42082895835;11590.246633968765;11717.179797414099;7093;8063;8131.105578386255;8161.836763264821;8404.663813447394;8273.255407013397;10880.400326960027;11491.367974761133;11670.824937625772;11735.178537502634 06:16:58 INFO - PROCESS | 1661 | |3;mootools.html;1354.1198221156253;1554.0986227987764;1557.2247637693029;1814.6733587954163;1970.2611520679736;7836;8561;8782.429142105759;8796.252318552923;8861.158189971942;8490.10853860345;8728.127187281269;8746.737597872057;8756.474611523314;8779.868301975475;2111.577684463106;2299.2412503873766;2349.8002669773045;2368.0291080656934;2387.761223877607;2051.825594824442;2430.1980346485707;2431.222008957134;2433.8661373624445;2437.792787613055;2366.6331718583647;2383.6901202843605;2399.940001499959;2401.819863510235;2404.1705611563984;2093.487095661565;2250.1786847800436;2339.847909885852;2340.578695834749;2349.3774149850306;2365.3613524348502;2400.879956002193;2403.519296140779;2406.6750988616486;2411.481531470742;8634.093420190853;8701.695440659547;8715.825683486359;8719;8739;5214.191800270964;5230.633855630107;5388.973055134699;5390.245365648812;5395.973020134914;2985.835014884208;3505.3339865425487;3525.5593050868642;3566.6076731559506;3603.927921441583;1056.2877201625815;1068.4871261794306;1079.1798233342638;1080.4381721504851;1090.1769164280997;11285.379304138269;11556.826647600292;11597.188196826226;11607.187496875222;11628.88371116295;4686.133065382915;4697.31888876111;4826.986332870137;4828.082664293773;4828.903421931612;10034.548445319979;10044.748881277881;10046.849297260547;10060.547275372626;10077.344972576906;5380.515753582196;5389.407165211824;5390.434004429557;5401.810936617199;5402.270693456412;4432.867013989586;4573.81704731807;4769.6661233714;4889.706617602884;4893.143699852582;3922.9019274518364;3993.342762753483;4024.2555127301544;4064.532578753422;4102.097538541516;1088.580896354909;1105.7733164701217;1273.6433798536239;1279.6480967733949;1289.6131160651767;775.3603277296253;906.1663269791808;915.2997956563235;935.3172184305495;988.9802203956025;5833.620814647034;5986.46121849027;6107.664078475637;6144.8771024579255;6236.532260080512;425.91268789898;446.89944762428195;449.71892567145534;451.68833504881525;464.6631192385561;4011.13760541485;4053.6959728020515;4059.3910913363234;4105.384192371168;4126.42230087782;10300.96990300964;10568.10171135462;10677.252592318619;10703.732406689895;10707.036366726876;10549.525271362807;10640.840387394195;10643.25497215203;10674.73313167177;10699.946500267604;8089.271965523131;8171.264586187152;8177.836443271101;8193.344532437392;8194.344452443791;5747.655140691572;5863.384344643751;5883;5994.940050599438;6120.418560236771;9545.904540954501;9943.30396872212;9993.450360230258;10042.14641755459;10064.245181611263;9970.800583988424;10109.292349535463;10134.543945522617;10161.695149145537;10185.490725463846;7203.099612548432;7240.7465738701;7870.449068565145;8176;8375.45559538628;8305.792355191168;8367.12145224767;8488.533130677872;8493.235608795238;8498.915010849812;7379.704811807683;7535.359494443033;7601.581912994618;7610.847783044419;7639.809004774925;7424.70301187968;7427.071616047994;7432.665530051053;7443.36731377839;7610.9238907610215;7071.575705457483;7124.786256412316;7166.605836679033;7173.892391614234;7186.6047367395295;4986.828095397651;5034.270030845581;5134.743262836769;5191.402988656202;5255.106631872667;4693.55411235932;5076.314697515788;5152.27868098474;5164.509371609691;5335.33308336458 06:16:58 INFO - PROCESS | 1661 | |4;prototype.html;271.65499815234654;299.2563479752815;301.7194009571099;315.73320544140194;317.6378928022055;5136.998285334361;5336.973315133424;5354.973225133869;5368.32895888014;5369.38252101008;4462.843800466984;4614.3309220163055;4776.97611511942;5141.305923700299;5241.213817927303;3757.567879693829;3903.941440878375;4031.7580945143386;4050.2179041221916;4065.8983525411804;2958.6449626044905;3494.9825250873837;3655.976326628548;4100.446439730628;4120.381942708587;3715.5541335039834;4036.495438070241;4051.7974101294963;4054.4254398456897;4058.3303754880408;3995.8201880915285;4019.336809426441;4045.3325201341745;4061.817218225187;4065.26825171469;3523.894283171509;3534.6818786309354;3537.168765340143;3545.308664810363;3562.928741425183;5016.49835016499;5060.7920559834065;5105.166632518987;5111.488851114896;5142.557285289495;4215.873523794291;4481.005952351205;4640.396748422717;4673.275642275453;4677.789499472532;5469.917951230735;5486.506214440679;5542.806001789958;5545.72271386429;5580.832575022715;3764.2471505698695;3912.256671232457;3915.4126880967797;3916.353801622729;3931.9017024574327;5091.498008087586;5560.443075938703;5700.232927792356;5775.249217601727;5850.47345738877;3402.7788193767324;3998.9200215995515;4000.7999600019884;4002.1595464952684;4008.336540335786;3485.5817301923425;3857.7106716996336;3886.4753258310334;4015.5181378234215;4045.846933623903;4382.627476664456;4551.5676010778925;4569.977150114228;4572.565606267399;4620;5595.18869763882;5606.383297837235;5618.269624948813;5629.380768115504;5676.403977582377;1240.3612139748036;1246.3485881207437;1304.6301383547238;1326.396489597231;1344.7982802579495;901.6348378906422;925.1858364639074;930.4882314726873;930.4882314726873;934.444005816538;4928.039032388721;5127.769250383667;5191.351081114861;5218.930119325529;5246.344206974129;4994.300797888299;5351.86620334487;5698.401667824902;5703.51896924839;5726.885462290814;4640.027988105042;5114.488551144856;5172.026085001139;5184.948150518522;5193.506616871391;2616.6336712860216;2682.390565660588;2853.315204350941;2907.3458471844087;2964.570137330118;5555.888762270311;5846.970765146147;5867.325257595346;5900.1739756434135;5996.790112346047;5051.621128415383;5438.809641662523;5956.076808094752;6003.669798161143;6007.657013764635;5424.918626220609;5670.036093864135;6039.305479869783;6043.156837164619;6061.242344706911;5047.899042019212;5116.437191908962;5263.868403289948;5786.739596718242;5809.593328466966;5706.942930570807;5733.799317024051;5739.62692424991;5749.741261643319;5785.27684039495;5007.674501157413;5102.617303702163;5549.752350298136;5958.314793798595;6001.90997135052;3198.8880389187198;3799.582045974885;3822.2737679840743;3837.788921609338;3859.5561510425537;4861.541537538795;5133.92299115521;5429.158480435539;5511.035568775528;5600.011995741453;5301.284326616012;5596.5242954347705;5598.524125449219;5608.523275521627;5628.352739435018;5266.2363957225225;5526.899778084302;5532.30846144232;5535;5559.805406810742;4844.636652251025;5398.974194902956;5466.988607107698;5469.753861076181;5529.834104976857;5130.076586214517;5423.752536916452;5633.549316054624;5701.605325763272;5743.501576814099;4607.792649330855;4862.222044472867;5116.360454943132;5124.282600436017;5530.446955304437 06:16:58 INFO - PROCESS | 1661 | |5;yui.html;605.9549022050579;719.1478098453333;788.9842203155933;806.5523634382915;839.2027573804885;12760.425780839862;15272.847271527295;15293.464728734496;16557.83442165578;17261.568460788454;14017.649558761035;16086.436974705888;16218;16218.108004059772;16253.431129910456;713.6146480900308;739.6819367671899;888.2183678363032;925.6251218256617;935.2377812083164;930.0652843891913;936.2790651198607;942.3968660057534;942.7077605942145;943.1087622196992;984.3404918704449;986.9013098690109;988.3427520698726;988.4020167798486;991.1624677147798;747.9401647868158;863.9265662418702;934.6261495401826;978.1001478639669;980.8577756225343;722.0360818307565;724.4964749499101;724.7608289264583;725.0755287009052;725.4522835259398;15313;15332.770008449883;15352.462663806826;15383.461578844799;15413.5375938722;13454.260015699132;13590.184588924696;13647.38586763598;13753.693399127067;13851.861481385158;12957.87042129576;13018.674533136653;13304.534341298007;13373.331333433387;13592.524261650893;4450.287953927388;4533.206688829442;4570.2687569988975;4603.332516785081;4779.569838714532;79130.60434697848;80020.59989700072;88142.5592872038;103507;105748.41377379345;10346.896531034594;10462.63380781669;10501.054905058581;10567.207459440573;10872.67381978542;17939.641207175784;20209.696854547194;20436.284730034677;20561.69157462639;20927.68608470874;7618.123915749759;7711.768646940601;7723.65243564041;7841.686332546748;7881.527108373516;4290.892727681833;4335.1329734053315;4391.5827996340295;4424;4561.201789686792;3419.863205471753;3572.392693242155;3573.7855728656364;3574.0171452850154;3580.606133325332;535.3655917737493;565.1663795901095;617.3641149615903;628.1080865171467;643.000134790396;564.3312674480753;691.5850489706136;703.8873780195145;708.1749761527763;708.7094291340526;8770.429922055173;8944.552772361356;9164.95417522908;9186.954065229766;9370.614703135723;656.4682607088274;667.8430568816324;700.0304578159286;707.2821086597108;714.2464699741822;3923.6468717815533;4539.432570928634;4669.439667239885;4681.882952926204;4684.1334353144775;96364;96429.5535566967;97890;97925;102629;88475;140337.29831350778;143492;143912;146387;76573.6171319151;77949.33667033791;78432.60783696159;78763.21236787674;121815;8316.835643010105;8343.874841877498;8896.110388961051;9046.366754327397;9046.864297035418;63039.68480157753;63073.73852522832;63957;65684.01473977798;66426;84048.15951840403;90288;93007;95606.52196738972;99884;11785.110779644714;11797.705057373634;11943;12040.578579749666;12616.49534018629;13709.657258568615;14558.4904528341;14940.701185976437;15255.160966146968;15606.609834754221;13898.722025559635;14030.438782448997;14199.361028753938;14246.6738942543;14635.70728585401;11762.94118529402;14058.437662493387;14097.083689560146;14275.857241427453;14498.275086245434;13288.867111328764;13474.124181928144;13617.931910340385;13693.702621017766;13865.376058077612;11415.771684566429;11434.256773309708;11604.913711642645;11642.41787910618;11885;11219.607313744305;11287.830682539932;11330.830037549607;11386.886131138914;11516 06:16:58 INFO - PROCESS | 1661 | __end_tp_report 06:16:58 INFO - PROCESS | 1661 | __start_cc_report 06:16:58 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1203 06:16:58 INFO - PROCESS | 1661 | __end_cc_report 06:16:58 INFO - PROCESS | 1661 | __startTimestamp1467206218705__endTimestamp 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | ------- Summary: start ------- 06:16:58 INFO - PROCESS | 1661 | Number of tests: 6 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5717.10 Median:5970.61 stddev:779.86 (13.1%) stddev-sans-first:757.04 06:16:58 INFO - PROCESS | 1661 | Values: 3209.3 3310.5 3350.9 3379.2 3420.3 4853.5 5588.8 5680.3 5753.2 5774.0 5723.2 5768.1 5770.8 5777.1 5778.5 4383.0 5190.1 5197.7 5251.0 5322.8 4728.1 5248.5 5279.4 5298.4 5405.7 5260.9 5341.2 5367.1 5369.2 5376.0 202.8 5282.3 5294.4 5373.7 5384.6 5232.0 5242.2 5254.7 5266.0 5291.6 5714.1 5720.9 5754.4 5811.5 5834.2 6126.8 6157.4 6221.0 6264.9 6285.0 6162.8 6183.9 6227.1 6233.0 6267.1 5865.3 5915.5 5918.3 5920.6 5922.7 6196.7 6200.0 6225.2 6269.7 6309.6 6176.7 6194.9 6197.8 6199.6 6199.6 6172.3 6226.4 6249.2 6265.1 6282.5 6073.4 6125.0 6136.8 6172.6 6178.2 6259.3 6274.6 6274.7 6278.7 6289.9 4936.1 4941.6 4943.3 4946.9 4959.7 4401.5 4403.1 4445.1 4446.7 4464.0 5774.4 5774.6 5785.8 5793.1 5848.7 6190.8 6216.8 6237.9 6271.8 6276.8 5462.4 5679.1 5700.3 5799.0 5803.5 5431.6 5442.4 5458.3 5462.4 5462.6 6177.0 6242.1 6286.1 6297.1 6309.0 6172.4 6191.3 6198.1 6241.3 6262.5 6244.4 6291.3 6306.2 6310.2 6316.7 6002.2 6057.6 6200.0 6228.6 6240.3 6147.6 6178.9 6208.4 6239.6 6263.4 6177.4 6179.0 6187.7 6189.8 6194.6 5970.6 6051.9 6149.4 6173.7 6178.7 5925.3 5948.6 5958.6 5975.6 6020.1 4496.0 6077.1 6187.7 6194.1 6197.1 5819.9 5875.8 5891.3 5975.0 6038.4 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:73394.40 Median:38435.02 stddev:162567.71 (423.0%) stddev-sans-first:162931.58 06:16:58 INFO - PROCESS | 1661 | Values: 483.1 494.3 504.2 520.2 537.4 25632.9 41593.4 42937.4 43025.4 43098.8 43841.0 44276.3 44365.6 44385.9 44545.0 8635.0 9160.5 9278.4 9520.2 9753.6 8657.7 11595.7 11879.4 11889.0 12010.8 11848.6 11929.3 11942.0 11945.8 11971.5 8754.0 11563.9 11796.4 11830.4 11887.6 10331.0 11679.1 11768.4 11769.1 11841.1 41313.0 41782.0 41821.8 41879.0 42032.0 2973.1 3616.7 3760.4 3769.2 3997.4 31716.0 32321.8 32403.0 32689.0 32899.7 3254.6 3278.8 3292.9 3375.0 3429.0 521232.0 523121.0 523532.0 524899.0 525126.0 61038.0 61165.0 61366.0 61499.0 61506.4 34955.5 35773.3 35878.9 37165.3 37351.8 15019.3 15029.4 15245.5 15252.0 15290.5 10406.3 10646.5 10646.6 10882.0 11341.7 16888.2 19500.5 19589.2 20126.0 20222.5 6844.8 8003.7 8039.8 8054.5 8077.8 878.0 967.8 991.4 1012.4 1084.3 29885.0 29950.6 30170.2 30391.4 30641.5 948.6 984.9 1069.4 1086.4 1127.5 13395.3 15793.9 15875.7 15937.1 16002.0 707494.0 710123.0 711879.0 712011.0 715025.0 59100.4 61064.0 61177.7 61324.0 61365.4 1268.8 1308.1 1412.2 1418.7 1461.6 27222.9 30769.4 31677.0 32054.2 32279.4 52080.0 52438.2 53267.7 53282.5 53402.5 560236.0 574590.0 574656.0 575609.0 577040.0 36677.4 43995.8 44627.0 44692.0 45706.3 48796.8 49008.5 49086.8 49684.5 50169.7 39878.0 40070.0 40569.6 41239.0 41272.0 36525.3 38697.2 38726.6 38891.0 39373.4 38270.6 38599.4 39062.0 39160.0 39199.6 32777.0 34515.7 35385.5 35501.6 35562.0 22668.4 25840.7 26000.7 26024.7 26706.0 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:34910.66 Median:21565.18 stddev:101579.55 (471.0%) stddev-sans-first:101831.32 06:16:58 INFO - PROCESS | 1661 | Values: 329.8 357.9 380.6 381.8 392.7 19936.3 21236.3 21516.8 21820.6 21824.1 20634.0 21011.9 21075.8 21613.6 21629.5 5594.1 5641.2 5912.3 6041.3 6047.2 3826.2 4173.2 4970.5 5982.0 6019.6 5925.2 5968.8 5977.1 5977.1 5987.6 5990.0 6086.5 6646.4 6648.8 6667.4 3742.4 5472.3 5847.7 5855.3 5884.1 13088.3 13653.2 13685.5 13688.9 20525.0 2047.1 2104.2 2189.7 2352.8 2602.6 9970.1 9981.9 10515.9 10531.3 10550.8 1879.0 1935.5 1945.3 1951.4 2028.3 116495.0 122105.4 123827.4 124406.4 124517.0 11550.9 11552.0 11634.6 11792.9 11862.8 14564.1 14624.0 14643.9 14661.9 14674.4 7966.9 8093.6 8127.7 8197.0 8199.3 7078.3 7361.0 7792.4 7806.0 7899.1 4560.2 4598.4 4626.7 4633.4 4637.8 1592.8 1741.9 1754.1 1758.0 1907.7 870.3 907.9 944.2 1010.3 1042.7 17229.0 18143.5 20352.8 21117.5 21127.2 876.5 894.4 934.1 953.7 955.8 11004.9 11820.3 11835.9 12160.6 12212.6 530671.0 617377.0 623515.0 633204.0 634942.8 117140.4 119223.0 120094.0 121633.0 131480.0 1003.3 1019.0 1035.5 1055.2 1059.7 12842.4 16534.9 17521.9 18766.1 19339.8 54491.5 55818.3 55963.0 56085.4 56439.0 72702.0 72812.0 74526.3 76601.0 79561.6 7750.2 8051.8 8138.3 8139.7 8336.7 10634.5 13069.9 13262.3 13282.5 13343.3 11220.5 11310.6 11330.5 11433.7 11495.1 9596.6 10492.6 10849.6 10991.8 11170.3 11423.5 11449.5 11583.4 11590.2 11717.2 7093.0 8063.0 8131.1 8161.8 8404.7 8273.3 10880.4 11491.4 11670.8 11735.2 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5730.13 Median:4733.49 stddev:3216.96 (68.0%) stddev-sans-first:3209.17 06:16:58 INFO - PROCESS | 1661 | Values: 1354.1 1554.1 1557.2 1814.7 1970.3 7836.0 8561.0 8782.4 8796.3 8861.2 8490.1 8728.1 8746.7 8756.5 8779.9 2111.6 2299.2 2349.8 2368.0 2387.8 2051.8 2430.2 2431.2 2433.9 2437.8 2366.6 2383.7 2399.9 2401.8 2404.2 2093.5 2250.2 2339.8 2340.6 2349.4 2365.4 2400.9 2403.5 2406.7 2411.5 8634.1 8701.7 8715.8 8719.0 8739.0 5214.2 5230.6 5389.0 5390.2 5396.0 2985.8 3505.3 3525.6 3566.6 3603.9 1056.3 1068.5 1079.2 1080.4 1090.2 11285.4 11556.8 11597.2 11607.2 11628.9 4686.1 4697.3 4827.0 4828.1 4828.9 10034.5 10044.7 10046.8 10060.5 10077.3 5380.5 5389.4 5390.4 5401.8 5402.3 4432.9 4573.8 4769.7 4889.7 4893.1 3922.9 3993.3 4024.3 4064.5 4102.1 1088.6 1105.8 1273.6 1279.6 1289.6 775.4 906.2 915.3 935.3 989.0 5833.6 5986.5 6107.7 6144.9 6236.5 425.9 446.9 449.7 451.7 464.7 4011.1 4053.7 4059.4 4105.4 4126.4 10301.0 10568.1 10677.3 10703.7 10707.0 10549.5 10640.8 10643.3 10674.7 10699.9 8089.3 8171.3 8177.8 8193.3 8194.3 5747.7 5863.4 5883.0 5994.9 6120.4 9545.9 9943.3 9993.5 10042.1 10064.2 9970.8 10109.3 10134.5 10161.7 10185.5 7203.1 7240.7 7870.4 8176.0 8375.5 8305.8 8367.1 8488.5 8493.2 8498.9 7379.7 7535.4 7601.6 7610.8 7639.8 7424.7 7427.1 7432.7 7443.4 7610.9 7071.6 7124.8 7166.6 7173.9 7186.6 4986.8 5034.3 5134.7 5191.4 5255.1 4693.6 5076.3 5152.3 5164.5 5335.3 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4525.38 Median:5116.40 stddev:1387.23 (27.1%) stddev-sans-first:1353.89 06:16:58 INFO - PROCESS | 1661 | Values: 271.7 299.3 301.7 315.7 317.6 5137.0 5337.0 5355.0 5368.3 5369.4 4462.8 4614.3 4777.0 5141.3 5241.2 3757.6 3903.9 4031.8 4050.2 4065.9 2958.6 3495.0 3656.0 4100.4 4120.4 3715.6 4036.5 4051.8 4054.4 4058.3 3995.8 4019.3 4045.3 4061.8 4065.3 3523.9 3534.7 3537.2 3545.3 3562.9 5016.5 5060.8 5105.2 5111.5 5142.6 4215.9 4481.0 4640.4 4673.3 4677.8 5469.9 5486.5 5542.8 5545.7 5580.8 3764.2 3912.3 3915.4 3916.4 3931.9 5091.5 5560.4 5700.2 5775.2 5850.5 3402.8 3998.9 4000.8 4002.2 4008.3 3485.6 3857.7 3886.5 4015.5 4045.8 4382.6 4551.6 4570.0 4572.6 4620.0 5595.2 5606.4 5618.3 5629.4 5676.4 1240.4 1246.3 1304.6 1326.4 1344.8 901.6 925.2 930.5 930.5 934.4 4928.0 5127.8 5191.4 5218.9 5246.3 4994.3 5351.9 5698.4 5703.5 5726.9 4640.0 5114.5 5172.0 5184.9 5193.5 2616.6 2682.4 2853.3 2907.3 2964.6 5555.9 5847.0 5867.3 5900.2 5996.8 5051.6 5438.8 5956.1 6003.7 6007.7 5424.9 5670.0 6039.3 6043.2 6061.2 5047.9 5116.4 5263.9 5786.7 5809.6 5706.9 5733.8 5739.6 5749.7 5785.3 5007.7 5102.6 5549.8 5958.3 6001.9 3198.9 3799.6 3822.3 3837.8 3859.6 4861.5 5133.9 5429.2 5511.0 5600.0 5301.3 5596.5 5598.5 5608.5 5628.4 5266.2 5526.9 5532.3 5535.0 5559.8 4844.6 5399.0 5467.0 5469.8 5529.8 5130.1 5423.8 5633.5 5701.6 5743.5 4607.8 4862.2 5116.4 5124.3 5530.4 06:16:58 INFO - PROCESS | 1661 | 06:16:58 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:22523.45 Median:4550.32 stddev:34085.02 (749.1%) stddev-sans-first:34140.91 06:16:58 INFO - PROCESS | 1661 | Values: 606.0 719.1 789.0 806.6 839.2 12760.4 15272.8 15293.5 16557.8 17261.6 14017.6 16086.4 16218.0 16218.1 16253.4 713.6 739.7 888.2 925.6 935.2 930.1 936.3 942.4 942.7 943.1 984.3 986.9 988.3 988.4 991.2 747.9 863.9 934.6 978.1 980.9 722.0 724.5 724.8 725.1 725.5 15313.0 15332.8 15352.5 15383.5 15413.5 13454.3 13590.2 13647.4 13753.7 13851.9 12957.9 13018.7 13304.5 13373.3 13592.5 4450.3 4533.2 4570.3 4603.3 4779.6 79130.6 80020.6 88142.6 103507.0 105748.4 10346.9 10462.6 10501.1 10567.2 10872.7 17939.6 20209.7 20436.3 20561.7 20927.7 7618.1 7711.8 7723.7 7841.7 7881.5 4290.9 4335.1 4391.6 4424.0 4561.2 3419.9 3572.4 3573.8 3574.0 3580.6 535.4 565.2 617.4 628.1 643.0 564.3 691.6 703.9 708.2 708.7 8770.4 8944.6 9165.0 9187.0 9370.6 656.5 667.8 700.0 707.3 714.2 3923.6 4539.4 4669.4 4681.9 4684.1 96364.0 96429.6 97890.0 97925.0 102629.0 88475.0 140337.3 143492.0 143912.0 146387.0 76573.6 77949.3 78432.6 78763.2 121815.0 8316.8 8343.9 8896.1 9046.4 9046.9 63039.7 63073.7 63957.0 65684.0 66426.0 84048.2 90288.0 93007.0 95606.5 99884.0 11785.1 11797.7 11943.0 12040.6 12616.5 13709.7 14558.5 14940.7 15255.2 15606.6 13898.7 14030.4 14199.4 14246.7 14635.7 11762.9 14058.4 14097.1 14275.9 14498.3 13288.9 13474.1 13617.9 13693.7 13865.4 11415.8 11434.3 11604.9 11642.4 11885.0 11219.6 11287.8 11330.8 11386.9 11516.0 06:16:58 INFO - PROCESS | 1661 | -------- Summary: end -------- 06:16:58 INFO - PROCESS | 1661 | 06:16:59 INFO - TEST-INFO | 1661: exit 0 06:16:59 INFO - TEST-OK | dromaeo_css | took 1115043ms 06:16:59 INFO - TEST-START | kraken 06:16:59 INFO - operating with platform_type : mac_ 06:16:59 INFO - Initialising browser for kraken test... 06:16:59 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpd2Zk54/profile http://localhost:49281/getInfo.html) 06:17:01 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 06:17:01 INFO - PROCESS | 1667 | colorDepth:24 06:17:01 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 06:17:01 INFO - PROCESS | 1667 | __metrics 06:17:01 INFO - PROCESS | 1667 | 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" 06:17:01 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:17:02 INFO - TEST-INFO | 1667: exit 0 06:17:02 INFO - Browser initialized. 06:17:02 INFO - Running cycle 1/1 for kraken test... 06:17:02 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpd2Zk54/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:17:14 INFO - PROCESS | 1669 | RSS: Main: 243449856 06:17:14 INFO - PROCESS | 1669 | 06:17:16 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 06:17:17 INFO - PROCESS | 1669 | RSS: Main: 294146048 06:17:17 INFO - PROCESS | 1669 | 06:17:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 06:17:21 INFO - PROCESS | 1669 | RSS: Main: 304320512 06:17:21 INFO - PROCESS | 1669 | 06:17:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 06:17:25 INFO - PROCESS | 1669 | RSS: Main: 388460544 06:17:25 INFO - PROCESS | 1669 | 06:17:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 06:17:27 INFO - PROCESS | 1669 | RSS: Main: 289808384 06:17:27 INFO - PROCESS | 1669 | 06:17:29 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:17:30 INFO - PROCESS | 1669 | RSS: Main: 293449728 06:17:30 INFO - PROCESS | 1669 | 06:17:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 06:17:33 INFO - PROCESS | 1669 | RSS: Main: 329383936 06:17:33 INFO - PROCESS | 1669 | 06:17:36 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 06:17:37 INFO - PROCESS | 1669 | RSS: Main: 332570624 06:17:37 INFO - PROCESS | 1669 | 06:17:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 06:17:40 INFO - PROCESS | 1669 | RSS: Main: 331231232 06:17:40 INFO - PROCESS | 1669 | 06:17:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:17:42 INFO - PROCESS | 1669 | RSS: Main: 310968320 06:17:42 INFO - PROCESS | 1669 | 06:17:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:17:45 INFO - PROCESS | 1669 | RSS: Main: 317796352 06:17:45 INFO - PROCESS | 1669 | 06:17:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:17:48 INFO - PROCESS | 1669 | RSS: Main: 320831488 06:17:48 INFO - PROCESS | 1669 | 06:17:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:17:51 INFO - PROCESS | 1669 | RSS: Main: 323870720 06:17:51 INFO - PROCESS | 1669 | 06:17:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:17:55 INFO - PROCESS | 1669 | RSS: Main: 324694016 06:17:55 INFO - PROCESS | 1669 | 06:17:56 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 06:17:57 INFO - PROCESS | 1669 | RSS: Main: 368418816 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | __start_tp_report 06:17:57 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 06:17:57 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 06:17:57 INFO - PROCESS | 1669 | |i|pagename|runs| 06:17:57 INFO - PROCESS | 1669 | |0;ai-astar;100;112;114;112;111;112;113;112;112;113 06:17:57 INFO - PROCESS | 1669 | |1;audio-beat-detection;153;119;115;169;114;116;138;115;151;115 06:17:57 INFO - PROCESS | 1669 | |2;audio-dft;184;184;186;186;185;182;182;183;182;181 06:17:57 INFO - PROCESS | 1669 | |3;audio-fft;80;73;56;106;59;56;60;59;124;55 06:17:57 INFO - PROCESS | 1669 | |4;audio-oscillator;83;88;93;71;77;69;94;70;67;102 06:17:57 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;88;85;89;85;87;85;89;85;89;84 06:17:57 INFO - PROCESS | 1669 | |6;imaging-darkroom;94;99;144;133;93;94;96;94;94;93 06:17:57 INFO - PROCESS | 1669 | |7;imaging-desaturate;83;85;86;87;87;83;85;86;86;86 06:17:57 INFO - PROCESS | 1669 | |8;json-parse-financial;55;56;54;53;54;53;53;53;53;55 06:17:57 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;65;73;71;76;72;68;68;70;68;70 06:17:57 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;83;85;85;85;83;84;84;86;100;84 06:17:57 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;139;139;137;141;131;136;125;138;163;138 06:17:57 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;166;165;166;166;164;164;165;166;166;162 06:17:57 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;67;67;66;65;67;66;64;65;65 06:17:57 INFO - PROCESS | 1669 | __end_tp_report 06:17:57 INFO - PROCESS | 1669 | __start_cc_report 06:17:57 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2016 06:17:57 INFO - PROCESS | 1669 | __end_cc_report 06:17:57 INFO - PROCESS | 1669 | __startTimestamp1467206277227__endTimestamp 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | ------- Summary: start ------- 06:17:57 INFO - PROCESS | 1669 | Number of tests: 14 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:3.98 (3.6%) stddev-sans-first:0.87 06:17:57 INFO - PROCESS | 1669 | Values: 100.0 112.0 114.0 112.0 111.0 112.0 113.0 112.0 112.0 113.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:128.50 stddev:20.55 (16.0%) stddev-sans-first:20.12 06:17:57 INFO - PROCESS | 1669 | Values: 153.0 119.0 115.0 169.0 114.0 116.0 138.0 115.0 151.0 115.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.78 (1.0%) stddev-sans-first:1.88 06:17:57 INFO - PROCESS | 1669 | Values: 184.0 184.0 186.0 186.0 185.0 182.0 182.0 183.0 182.0 181.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:72.80 Median:59.00 stddev:24.04 (40.7%) stddev-sans-first:25.36 06:17:57 INFO - PROCESS | 1669 | Values: 80.0 73.0 56.0 106.0 59.0 56.0 60.0 59.0 124.0 55.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:80.00 stddev:12.39 (15.5%) stddev-sans-first:13.13 06:17:57 INFO - PROCESS | 1669 | Values: 83.0 88.0 93.0 71.0 77.0 69.0 94.0 70.0 67.0 102.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:2.01 (2.3%) stddev-sans-first:2.07 06:17:57 INFO - PROCESS | 1669 | Values: 88.0 85.0 89.0 85.0 87.0 85.0 89.0 85.0 89.0 84.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:94.00 stddev:18.76 (20.0%) stddev-sans-first:19.59 06:17:57 INFO - PROCESS | 1669 | Values: 94.0 99.0 144.0 133.0 93.0 94.0 96.0 94.0 94.0 93.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.22 06:17:57 INFO - PROCESS | 1669 | Values: 83.0 85.0 86.0 87.0 87.0 83.0 85.0 86.0 86.0 86.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:1.09 06:17:57 INFO - PROCESS | 1669 | Values: 55.0 56.0 54.0 53.0 54.0 53.0 53.0 53.0 53.0 55.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:3.11 (4.4%) stddev-sans-first:2.69 06:17:57 INFO - PROCESS | 1669 | Values: 65.0 73.0 71.0 76.0 72.0 68.0 68.0 70.0 68.0 70.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:84.50 stddev:5.04 (6.0%) stddev-sans-first:5.24 06:17:57 INFO - PROCESS | 1669 | Values: 83.0 85.0 85.0 85.0 83.0 84.0 84.0 86.0 100.0 84.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:138.50 stddev:9.74 (7.0%) stddev-sans-first:10.33 06:17:57 INFO - PROCESS | 1669 | Values: 139.0 139.0 137.0 141.0 131.0 136.0 125.0 138.0 163.0 138.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 06:17:57 INFO - PROCESS | 1669 | Values: 166.0 165.0 166.0 166.0 164.0 164.0 165.0 166.0 166.0 162.0 06:17:57 INFO - PROCESS | 1669 | 06:17:57 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 06:17:57 INFO - PROCESS | 1669 | Values: 66.0 67.0 67.0 66.0 65.0 67.0 66.0 64.0 65.0 65.0 06:17:57 INFO - PROCESS | 1669 | -------- Summary: end -------- 06:17:57 INFO - PROCESS | 1669 | 06:17:58 INFO - TEST-INFO | 1669: exit 0 06:17:58 INFO - TEST-OK | kraken | took 58810ms 06:17:58 INFO - SUITE-END | took 1173s 06:17:58 INFO - Completed test suite (00:19:34) 06:17:58 INFO - javascript benchmark 06:17:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3209.3260415312784, 3310.5199746036833, 3350.899473015809, 3379.2227787608863, 3420.2646431017324, 4853.466118726941, 5588.832335029945, 5680.3467601225875, 5753.223314852499, 5773.9711301443485, 5723.198752174699, 5768.105943578752, 5770.798022069229, 5777.104548794943, 5778.5377169826415, 4383.0, 5190.117679994391, 5197.662151960129, 5250.976059668347, 5322.787088516455, 4728.125296820082, 5248.475152484759, 5279.366476022883, 5298.390685071208, 5405.729713514309, 5260.894782104356, 5341.1988201769855, 5367.141257398817, 5369.167778994262, 5376.005438993799, 202.75488779590017, 5282.286891269668, 5294.391145018295, 5373.704446255454, 5384.596155288369, 5232.03207406631, 5242.2136679498, 5254.65844720092, 5266.0, 5291.629585928987, 5714.057180565202, 5720.885582288331, 5754.395788442195, 5811.535077193815, 5834.154047663065, 6126.816195514141, 6157.415045570664, 6221.004639257697, 6264.937350626481, 6285.0, 6162.84592885177, 6183.907241391383, 6227.128202051671, 6233.0338797486465, 6267.091271765614, 5865.325487568942, 5915.467607915309, 5918.260217472816, 5920.644761314335, 5922.733476993589, 6196.659183744847, 6200.038993955944, 6225.190725205694, 6269.717862696099, 6309.589876658002, 6176.69116544171, 6194.876102478015, 6197.752089916353, 6199.56603037783, 6199.5660303779205, 6172.259328880563, 6226.377362263737, 6249.156363890907, 6265.091561723616, 6282.4974002079725, 6073.392660733891, 6124.991279909863, 6136.815895523141, 6172.598781079216, 6178.227721534809, 6259.311475737665, 6274.623522588659, 6274.6548939808745, 6278.6860656966965, 6289.8742025159245, 4936.062148191832, 4941.555260026615, 4943.2585112233455, 4946.9010619787405, 4959.652824302264, 4401.515833258339, 4403.097365040159, 4445.066536027406, 4446.710963787408, 4463.995600989816, 5774.393688662714, 5774.566907481955, 5785.768569257267, 5793.1020691792, 5848.678322692293, 6190.783322583778, 6216.813495595139, 6237.937620623827, 6271.843203919847, 6276.843078923063, 5462.399136095027, 5679.091345384719, 5700.25896633435, 5799.014167591519, 5803.506701930383, 5431.619786614978, 5442.374126975448, 5458.2904222451625, 5462.371827239839, 5462.617616766788, 6176.980798168252, 6242.063690446378, 6286.088517164985, 6297.149884765498, 6309.022101574264, 6172.382761723971, 6191.288001879841, 6198.0702894566175, 6241.282252540835, 6262.467690246198, 6244.438000579969, 6291.307956124731, 6306.211723534559, 6310.242770867525, 6316.652584107735, 6002.189704389852, 6057.636541807506, 6199.969000155151, 6228.5951413158, 6240.251169859646, 6147.631142131487, 6178.907316390348, 6208.410201030895, 6239.625622462667, 6263.4362907338555, 6177.413145751147, 6179.0, 6187.721552530237, 6189.752409903734, 6194.5973511721595, 5970.611910225822, 6051.878962420815, 6149.354317796567, 6173.660448675366, 6178.7219575120125, 5925.348211696796, 5948.643081415129, 5958.612690175125, 5975.581709280483, 6020.1270815730595, 4495.965927836551, 6077.11881777131, 6187.659678717714, 6194.101855231056, 6197.101420293942, 5819.854503637443, 5875.823725288248, 5891.3224979127945, 5975.0, 6038.426349496791], "value": 5669.928862247129, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.1496566043763, 494.2586120818772, 504.17567277501274, 520.1988937036965, 537.3739593373715, 25632.87183564082, 41593.376099358495, 42937.35593966093, 43025.35461968069, 43098.78450607751, 43841.0, 44276.33585496212, 44365.55634443655, 44385.8903527412, 44545.0, 8635.0, 9160.541972901361, 9278.396904201205, 9520.23838092951, 9753.609855605768, 8657.740267791976, 11595.710107247343, 11879.406029698524, 11889.0, 12010.819837702442, 11848.585299514518, 11929.284242945409, 11942.0, 11945.820812687773, 11971.521139154424, 8754.0, 11563.884361156364, 11796.410179491035, 11830.408479576074, 11887.583934562248, 10331.0, 11679.124065695107, 11768.352740599265, 11769.058475321954, 11841.111916606204, 41313.0, 41781.95545111366, 41821.79089104535, 41878.953026174284, 42032.0, 2973.137790040907, 3616.6564176403463, 3760.3795373763296, 3769.2461507698295, 3997.420374045776, 31716.04851854448, 32321.83839080813, 32403.0, 32689.019329420156, 32899.6710032899, 3254.6094468663914, 3278.8360581970805, 3292.9012129635908, 3375.038114137459, 3428.9884484077006, 521232.0, 523121.0, 523532.0, 524899.0, 525126.0, 61038.0, 61165.0, 61366.0, 61499.0, 61506.38493615007, 34955.475667865, 35773.284534309685, 35878.92363229107, 37165.25669486595, 37351.813240933625, 15019.324130414157, 15029.398824046915, 15245.466408675644, 15252.0, 15290.464833730766, 10406.323588966692, 10646.520906559223, 10646.627368042236, 10882.020618144405, 11341.659750207342, 16888.240029198714, 19500.512487187934, 19589.200645841574, 20125.99370031522, 20222.49443763888, 6844.760433384808, 8003.719869804645, 8039.829246103519, 8054.476459030261, 8077.838443231102, 877.9868301975475, 967.8403063494585, 991.3704797453524, 1012.4380968562372, 1084.34939036579, 29885.0, 29950.55074173889, 30170.24574385614, 30391.392172156433, 30641.540376894365, 948.5589201021559, 984.8621193032981, 1069.395791377869, 1086.435053772034, 1127.5320741892024, 13395.330233488286, 15793.921030395004, 15875.682486350208, 15937.123458209679, 16002.0, 707494.0, 710123.0, 711879.0, 712011.0, 715025.0, 59100.40899591035, 61064.0, 61177.694111529156, 61324.0, 61365.38634613686, 1268.796992481217, 1308.0581980973684, 1412.2232771975578, 1418.6737050478241, 1461.634591352162, 27222.911083556435, 30769.384612308077, 31677.04968850938, 32054.198645033128, 32279.35441291114, 52080.0, 52438.21342679939, 53267.733661333, 53282.4671753293, 53402.46597534131, 560236.0, 574590.0, 574656.0, 575609.0, 577040.0, 36677.449838251916, 43995.78002109969, 44627.0, 44692.0, 45706.31440528328, 48796.75601621969, 49008.529744107735, 49086.75456622694, 49684.503154967984, 50169.74915125401, 39878.00304992399, 40070.0, 40569.59430405658, 41239.0, 41272.0, 36525.26949461049, 38697.22605547817, 38726.61273387343, 38891.0, 39373.409398858465, 38270.617293826705, 38599.42100868433, 39062.023449413995, 39160.0, 39199.60800392074, 32777.0, 34515.654843451244, 35385.469217962265, 35501.64498355087, 35562.0, 22668.413211075065, 25840.741592583832, 26000.73999260059, 26024.73975260299, 26706.0], "value": 20600.058335653936, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.76586623497315, 357.86758899207297, 380.61367711772556, 381.7785684303104, 392.6623104130449, 19936.302229421974, 21236.25673101442, 21516.784832151712, 21820.563588728255, 21824.127034918605, 20634.0, 21011.894940525315, 21075.789242107574, 21613.567728645456, 21629.45926351842, 5594.13290939905, 5641.210230567724, 5912.290525136968, 6041.335453100155, 6047.183630209931, 3826.1582451860545, 4173.165366926612, 4970.527799859007, 5982.012967860298, 6019.608725432833, 5925.200097986782, 5968.820935371946, 5977.133315669225, 5977.133315669225, 5987.610805297663, 5989.951758442256, 6086.543509236825, 6646.401823835829, 6648.800535983928, 6667.366600172976, 3742.3825068863603, 5472.343318801769, 5847.707614619252, 5855.326637436707, 5884.0585506318785, 13088.345582720825, 13653.249071301074, 13685.452581896812, 13688.904887609067, 20525.0, 2047.119738512439, 2104.158336665331, 2189.6606026065983, 2352.8235382346393, 2602.5705758549816, 9970.052844979788, 9981.850272245922, 10515.894841051568, 10531.26281160319, 10550.78898422035, 1878.990605046966, 1935.5064458563118, 1945.2899691612602, 1951.3560525026708, 2028.3205126282712, 116495.0, 122105.38947305296, 123827.38086309601, 124406.37796811048, 124517.0, 11550.942245288888, 11551.960323570918, 11634.592789252336, 11792.882071179178, 11862.762744745056, 14564.053336533092, 14624.0, 14643.85356146425, 14661.92669036648, 14674.41302347894, 7966.920330796734, 8093.554854482943, 8127.715529956423, 8197.049062337554, 8199.303059239912, 7078.292170782984, 7361.043064401593, 7792.41556883236, 7806.0, 7899.131095579482, 4560.201964656172, 4598.425196850394, 4626.653001024936, 4633.397658304399, 4637.837675681335, 1592.8168260650173, 1741.9303227870996, 1754.1054062427997, 1757.9735636621947, 1907.6947688369794, 870.260278763059, 907.9319051071064, 944.1974321826392, 1010.3483253301579, 1042.6816902523003, 17228.966262024318, 18143.462024905017, 20352.796472035385, 21117.472063198544, 21127.154913803584, 876.4522173641474, 894.4365050018447, 934.131257930118, 953.6709835106877, 955.7992821507561, 11004.944975275232, 11820.290782552902, 11835.934765871112, 12160.635180944586, 12212.633620991384, 530671.0, 617377.0, 623515.0, 633204.0, 634942.8252858799, 117140.41429792797, 119223.0, 120094.0, 121633.0, 131480.0, 1003.2575893838507, 1018.9898101019044, 1035.53400969564, 1055.1664185293685, 1059.7297689089232, 12842.422091006114, 16534.917325413295, 17521.912390438476, 18766.061696914825, 19339.806601934364, 54491.45508545022, 55818.32545023656, 55963.0, 56085.43914560802, 56439.0, 72702.0, 72812.0, 74526.25473745193, 76601.0, 79561.60219199098, 7750.224977502205, 8051.798705032421, 8138.267555919995, 8139.674413023413, 8336.749897503085, 10634.521446534769, 13069.869301306866, 13262.27057511846, 13282.53511127106, 13343.266120363473, 11220.495077721685, 11310.60412885545, 11330.490127944428, 11433.714157145805, 11495.080393568662, 9596.616135354509, 10492.580296788043, 10849.566017359533, 10991.780164396507, 11170.27393219438, 11423.485943132413, 11449.484773185393, 11583.42082895835, 11590.246633968765, 11717.179797414099, 7093.0, 8063.0, 8131.105578386255, 8161.836763264821, 8404.663813447394, 8273.255407013397, 10880.400326960027, 11491.367974761133, 11670.824937625772, 11735.178537502634], "value": 9372.281551215969, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.1198221156253, 1554.0986227987764, 1557.2247637693029, 1814.6733587954163, 1970.2611520679736, 7836.0, 8561.0, 8782.429142105759, 8796.252318552923, 8861.158189971942, 8490.10853860345, 8728.127187281269, 8746.737597872057, 8756.474611523314, 8779.868301975475, 2111.577684463106, 2299.2412503873766, 2349.8002669773045, 2368.0291080656934, 2387.761223877607, 2051.825594824442, 2430.1980346485707, 2431.222008957134, 2433.8661373624445, 2437.792787613055, 2366.6331718583647, 2383.6901202843605, 2399.940001499959, 2401.819863510235, 2404.1705611563984, 2093.487095661565, 2250.1786847800436, 2339.847909885852, 2340.578695834749, 2349.3774149850306, 2365.3613524348502, 2400.879956002193, 2403.519296140779, 2406.6750988616486, 2411.481531470742, 8634.093420190853, 8701.695440659547, 8715.825683486359, 8719.0, 8739.0, 5214.191800270964, 5230.633855630107, 5388.973055134699, 5390.245365648812, 5395.973020134914, 2985.835014884208, 3505.3339865425487, 3525.5593050868642, 3566.6076731559506, 3603.927921441583, 1056.2877201625815, 1068.4871261794306, 1079.1798233342638, 1080.4381721504851, 1090.1769164280997, 11285.379304138269, 11556.826647600292, 11597.188196826226, 11607.187496875222, 11628.88371116295, 4686.133065382915, 4697.31888876111, 4826.986332870137, 4828.082664293773, 4828.903421931612, 10034.548445319979, 10044.748881277881, 10046.849297260547, 10060.547275372626, 10077.344972576906, 5380.515753582196, 5389.407165211824, 5390.434004429557, 5401.810936617199, 5402.270693456412, 4432.867013989586, 4573.81704731807, 4769.6661233714, 4889.706617602884, 4893.143699852582, 3922.9019274518364, 3993.342762753483, 4024.2555127301544, 4064.532578753422, 4102.097538541516, 1088.580896354909, 1105.7733164701217, 1273.6433798536239, 1279.6480967733949, 1289.6131160651767, 775.3603277296253, 906.1663269791808, 915.2997956563235, 935.3172184305495, 988.9802203956025, 5833.620814647034, 5986.46121849027, 6107.664078475637, 6144.8771024579255, 6236.532260080512, 425.91268789898, 446.89944762428195, 449.71892567145534, 451.68833504881525, 464.6631192385561, 4011.13760541485, 4053.6959728020515, 4059.3910913363234, 4105.384192371168, 4126.42230087782, 10300.96990300964, 10568.10171135462, 10677.252592318619, 10703.732406689895, 10707.036366726876, 10549.525271362807, 10640.840387394195, 10643.25497215203, 10674.73313167177, 10699.946500267604, 8089.271965523131, 8171.264586187152, 8177.836443271101, 8193.344532437392, 8194.344452443791, 5747.655140691572, 5863.384344643751, 5883.0, 5994.940050599438, 6120.418560236771, 9545.904540954501, 9943.30396872212, 9993.450360230258, 10042.14641755459, 10064.245181611263, 9970.800583988424, 10109.292349535463, 10134.543945522617, 10161.695149145537, 10185.490725463846, 7203.099612548432, 7240.7465738701, 7870.449068565145, 8176.0, 8375.45559538628, 8305.792355191168, 8367.12145224767, 8488.533130677872, 8493.235608795238, 8498.915010849812, 7379.704811807683, 7535.359494443033, 7601.581912994618, 7610.847783044419, 7639.809004774925, 7424.70301187968, 7427.071616047994, 7432.665530051053, 7443.36731377839, 7610.9238907610215, 7071.575705457483, 7124.786256412316, 7166.605836679033, 7173.892391614234, 7186.6047367395295, 4986.828095397651, 5034.270030845581, 5134.743262836769, 5191.402988656202, 5255.106631872667, 4693.55411235932, 5076.314697515788, 5152.27868098474, 5164.509371609691, 5335.33308336458], "value": 4519.748425180645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.65499815234654, 299.2563479752815, 301.7194009571099, 315.73320544140194, 317.6378928022055, 5136.998285334361, 5336.973315133424, 5354.973225133869, 5368.32895888014, 5369.38252101008, 4462.843800466984, 4614.3309220163055, 4776.97611511942, 5141.305923700299, 5241.213817927303, 3757.567879693829, 3903.941440878375, 4031.7580945143386, 4050.2179041221916, 4065.8983525411804, 2958.6449626044905, 3494.9825250873837, 3655.976326628548, 4100.446439730628, 4120.381942708587, 3715.5541335039834, 4036.495438070241, 4051.7974101294963, 4054.4254398456897, 4058.3303754880408, 3995.8201880915285, 4019.336809426441, 4045.3325201341745, 4061.817218225187, 4065.26825171469, 3523.894283171509, 3534.6818786309354, 3537.168765340143, 3545.308664810363, 3562.928741425183, 5016.49835016499, 5060.7920559834065, 5105.166632518987, 5111.488851114896, 5142.557285289495, 4215.873523794291, 4481.005952351205, 4640.396748422717, 4673.275642275453, 4677.789499472532, 5469.917951230735, 5486.506214440679, 5542.806001789958, 5545.72271386429, 5580.832575022715, 3764.2471505698695, 3912.256671232457, 3915.4126880967797, 3916.353801622729, 3931.9017024574327, 5091.498008087586, 5560.443075938703, 5700.232927792356, 5775.249217601727, 5850.47345738877, 3402.7788193767324, 3998.9200215995515, 4000.7999600019884, 4002.1595464952684, 4008.336540335786, 3485.5817301923425, 3857.7106716996336, 3886.4753258310334, 4015.5181378234215, 4045.846933623903, 4382.627476664456, 4551.5676010778925, 4569.977150114228, 4572.565606267399, 4620.0, 5595.18869763882, 5606.383297837235, 5618.269624948813, 5629.380768115504, 5676.403977582377, 1240.3612139748036, 1246.3485881207437, 1304.6301383547238, 1326.396489597231, 1344.7982802579495, 901.6348378906422, 925.1858364639074, 930.4882314726873, 930.4882314726873, 934.444005816538, 4928.039032388721, 5127.769250383667, 5191.351081114861, 5218.930119325529, 5246.344206974129, 4994.300797888299, 5351.86620334487, 5698.401667824902, 5703.51896924839, 5726.885462290814, 4640.027988105042, 5114.488551144856, 5172.026085001139, 5184.948150518522, 5193.506616871391, 2616.6336712860216, 2682.390565660588, 2853.315204350941, 2907.3458471844087, 2964.570137330118, 5555.888762270311, 5846.970765146147, 5867.325257595346, 5900.1739756434135, 5996.790112346047, 5051.621128415383, 5438.809641662523, 5956.076808094752, 6003.669798161143, 6007.657013764635, 5424.918626220609, 5670.036093864135, 6039.305479869783, 6043.156837164619, 6061.242344706911, 5047.899042019212, 5116.437191908962, 5263.868403289948, 5786.739596718242, 5809.593328466966, 5706.942930570807, 5733.799317024051, 5739.62692424991, 5749.741261643319, 5785.27684039495, 5007.674501157413, 5102.617303702163, 5549.752350298136, 5958.314793798595, 6001.90997135052, 3198.8880389187198, 3799.582045974885, 3822.2737679840743, 3837.788921609338, 3859.5561510425537, 4861.541537538795, 5133.92299115521, 5429.158480435539, 5511.035568775528, 5600.011995741453, 5301.284326616012, 5596.5242954347705, 5598.524125449219, 5608.523275521627, 5628.352739435018, 5266.2363957225225, 5526.899778084302, 5532.30846144232, 5535.0, 5559.805406810742, 4844.636652251025, 5398.974194902956, 5466.988607107698, 5469.753861076181, 5529.834104976857, 5130.076586214517, 5423.752536916452, 5633.549316054624, 5701.605325763272, 5743.501576814099, 4607.792649330855, 4862.222044472867, 5116.360454943132, 5124.282600436017, 5530.446955304437], "value": 4080.9692114125955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.9549022050579, 719.1478098453333, 788.9842203155933, 806.5523634382915, 839.2027573804885, 12760.425780839862, 15272.847271527295, 15293.464728734496, 16557.83442165578, 17261.568460788454, 14017.649558761035, 16086.436974705888, 16218.0, 16218.108004059772, 16253.431129910456, 713.6146480900308, 739.6819367671899, 888.2183678363032, 925.6251218256617, 935.2377812083164, 930.0652843891913, 936.2790651198607, 942.3968660057534, 942.7077605942145, 943.1087622196992, 984.3404918704449, 986.9013098690109, 988.3427520698726, 988.4020167798486, 991.1624677147798, 747.9401647868158, 863.9265662418702, 934.6261495401826, 978.1001478639669, 980.8577756225343, 722.0360818307565, 724.4964749499101, 724.7608289264583, 725.0755287009052, 725.4522835259398, 15313.0, 15332.770008449883, 15352.462663806826, 15383.461578844799, 15413.5375938722, 13454.260015699132, 13590.184588924696, 13647.38586763598, 13753.693399127067, 13851.861481385158, 12957.87042129576, 13018.674533136653, 13304.534341298007, 13373.331333433387, 13592.524261650893, 4450.287953927388, 4533.206688829442, 4570.2687569988975, 4603.332516785081, 4779.569838714532, 79130.60434697848, 80020.59989700072, 88142.5592872038, 103507.0, 105748.41377379345, 10346.896531034594, 10462.63380781669, 10501.054905058581, 10567.207459440573, 10872.67381978542, 17939.641207175784, 20209.696854547194, 20436.284730034677, 20561.69157462639, 20927.68608470874, 7618.123915749759, 7711.768646940601, 7723.65243564041, 7841.686332546748, 7881.527108373516, 4290.892727681833, 4335.1329734053315, 4391.5827996340295, 4424.0, 4561.201789686792, 3419.863205471753, 3572.392693242155, 3573.7855728656364, 3574.0171452850154, 3580.606133325332, 535.3655917737493, 565.1663795901095, 617.3641149615903, 628.1080865171467, 643.000134790396, 564.3312674480753, 691.5850489706136, 703.8873780195145, 708.1749761527763, 708.7094291340526, 8770.429922055173, 8944.552772361356, 9164.95417522908, 9186.954065229766, 9370.614703135723, 656.4682607088274, 667.8430568816324, 700.0304578159286, 707.2821086597108, 714.2464699741822, 3923.6468717815533, 4539.432570928634, 4669.439667239885, 4681.882952926204, 4684.1334353144775, 96364.0, 96429.5535566967, 97890.0, 97925.0, 102629.0, 88475.0, 140337.29831350778, 143492.0, 143912.0, 146387.0, 76573.6171319151, 77949.33667033791, 78432.60783696159, 78763.21236787674, 121815.0, 8316.835643010105, 8343.874841877498, 8896.110388961051, 9046.366754327397, 9046.864297035418, 63039.68480157753, 63073.73852522832, 63957.0, 65684.01473977798, 66426.0, 84048.15951840403, 90288.0, 93007.0, 95606.52196738972, 99884.0, 11785.110779644714, 11797.705057373634, 11943.0, 12040.578579749666, 12616.49534018629, 13709.657258568615, 14558.4904528341, 14940.701185976437, 15255.160966146968, 15606.609834754221, 13898.722025559635, 14030.438782448997, 14199.361028753938, 14246.6738942543, 14635.70728585401, 11762.94118529402, 14058.437662493387, 14097.083689560146, 14275.857241427453, 14498.275086245434, 13288.867111328764, 13474.124181928144, 13617.931910340385, 13693.702621017766, 13865.376058077612, 11415.771684566429, 11434.256773309708, 11604.913711642645, 11642.41787910618, 11885.0, 11219.607313744305, 11287.830682539932, 11330.830037549607, 11386.886131138914, 11516.0], "value": 7774.995967844745, "unit": "score"}], "name": "dromaeo_css", "value": 7344.903983102439, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 114.0, 112.0, 111.0, 112.0, 113.0, 112.0, 112.0, 113.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 115.0, 169.0, 114.0, 116.0, 138.0, 115.0, 151.0, 115.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 186.0, 186.0, 185.0, 182.0, 182.0, 183.0, 182.0, 181.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 56.0, 106.0, 59.0, 56.0, 60.0, 59.0, 124.0, 55.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 93.0, 71.0, 77.0, 69.0, 94.0, 70.0, 67.0, 102.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 87.0, 85.0, 89.0, 85.0, 89.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 144.0, 133.0, 93.0, 94.0, 96.0, 94.0, 94.0, 93.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 86.0, 87.0, 87.0, 83.0, 85.0, 86.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 71.0, 76.0, 72.0, 68.0, 68.0, 70.0, 68.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 85.0, 85.0, 83.0, 84.0, 84.0, 86.0, 100.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 137.0, 141.0, 131.0, 136.0, 125.0, 138.0, 163.0, 138.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 166.0, 166.0, 164.0, 164.0, 165.0, 166.0, 166.0, 162.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 65.0, 67.0, 66.0, 64.0, 65.0, 65.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1434.1000000000001, "alertThreshold": 2.0}]} 06:17:58 INFO - Return code: 0 06:17:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:17:58 INFO - # TBPL SUCCESS # 06:17:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:17:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:17:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:17:58 INFO - 2016-06-29 06:17:58.548 screenresolution[1673:10521] starting screenresolution argv=screenresolution get 06:17:58 INFO - 2016-06-29 06:17:58.550 screenresolution[1673:10521] Display 0: 1600x1200x32@60 06:17:58 INFO - 2016-06-29 06:17:58.556 screenresolution[1674:10524] starting screenresolution argv=screenresolution list 06:17:58 INFO - Available Modes on Display 0 06:17:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:17:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:17:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:17:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:17:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:17:58 INFO - Intel Iris: 06:17:58 INFO - Chipset Model: Intel Iris 06:17:58 INFO - Type: GPU 06:17:58 INFO - Bus: Built-In 06:17:58 INFO - VRAM (Dynamic, Max): 1536 MB 06:17:58 INFO - Vendor: Intel (0x8086) 06:17:58 INFO - Device ID: 0x0a2e 06:17:58 INFO - Revision ID: 0x0009 06:17:58 INFO - Displays: 06:17:58 INFO - Display: 06:17:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:17:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:17:58 INFO - Display Serial Number: 1600x1200 60 06:17:58 INFO - Main Display: Yes 06:17:58 INFO - Mirror: Off 06:17:58 INFO - Online: Yes 06:17:58 INFO - Rotation: Supported 06:17:58 INFO - Return code: 0 06:17:58 INFO - Running post-action listener: _resource_record_post_action 06:17:58 INFO - ##### 06:17:58 INFO - ##### Finished run-tests step (success) 06:17:58 INFO - ##### 06:17:58 INFO - Running post-run listener: _resource_record_post_run 06:17:58 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32792064; Write bytes: 708211200; Read time: 310; Write time: 2110 06:17:58 INFO - TinderboxPrint: CPU usage
54.2% 06:17:58 INFO - TinderboxPrint: I/O read bytes / time
32,792,064 / 310 06:17:58 INFO - TinderboxPrint: I/O write bytes / time
708,211,200 / 2,110 06:17:58 INFO - TinderboxPrint: CPU idle
2,216.3 (45.8%) 06:17:58 INFO - TinderboxPrint: CPU system
968.3 (20.0%) 06:17:58 INFO - TinderboxPrint: CPU user
1,653.1 (34.2%) 06:17:58 INFO - TinderboxPrint: Swap in / out
435,499,008 / 0 06:17:58 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392098304; Write bytes: 386176512; Read time: 29087; Write time: 757 06:17:58 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31870464; Write bytes: 298798080; Read time: 297; Write time: 1306 06:17:59 INFO - Running post-run listener: _upload_blobber_files 06:17:59 INFO - Blob upload gear active. 06:17:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:17:59 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 06:17:59 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:17:59 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:17:59 INFO - (blobuploader) - INFO - Open directory for files ... 06:17:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:17:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:17:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:18:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:00 INFO - (blobuploader) - INFO - Done attempting. 06:18:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:18:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:18:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:01 INFO - (blobuploader) - INFO - Done attempting. 06:18:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:18:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:18:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:18:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:18:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:18:03 INFO - (blobuploader) - INFO - Done attempting. 06:18:03 INFO - (blobuploader) - INFO - Iteration through files over. 06:18:03 INFO - Return code: 0 06:18:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:18:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:18:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b318b54c4e302285a10a34efa9317d8990b4a6ea09f5998d72998b1b915eb4097d0c9723dac316b34cfbee345d14409fe96bdea1a01db6cdcc5033f681ab4450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8a5a7f7175d364c8e27f7cca383f002e439dfc635373467efb03bf8b9e799a52386d8cbbcf58902381272ad29cff87fdac6d6f2d88af215751b3e99302bc874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39e1e1ab9e6ab3f76416fb11f8a609458c3cd498184014245032eda804703fbefb46cff8b11d33692fa09b7ba494ef1d0b6e4d9464106d3191d8f1c8b7033f77"} 06:18:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:18:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:18:03 INFO - Contents: 06:18:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b318b54c4e302285a10a34efa9317d8990b4a6ea09f5998d72998b1b915eb4097d0c9723dac316b34cfbee345d14409fe96bdea1a01db6cdcc5033f681ab4450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8a5a7f7175d364c8e27f7cca383f002e439dfc635373467efb03bf8b9e799a52386d8cbbcf58902381272ad29cff87fdac6d6f2d88af215751b3e99302bc874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39e1e1ab9e6ab3f76416fb11f8a609458c3cd498184014245032eda804703fbefb46cff8b11d33692fa09b7ba494ef1d0b6e4d9464106d3191d8f1c8b7033f77"} 06:18:03 INFO - Running post-run listener: copy_logs_to_upload_dir 06:18:03 INFO - Copying logs to upload dir... 06:18:03 INFO - mkdir: /builds/slave/test/build/upload/logs 06:18:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.355282 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-06-29 06:18:03.833869) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 06:18:03.837384) ========= 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.lmhyF38MRb/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.WTNLDD79BM/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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/Firefox/sha512/b318b54c4e302285a10a34efa9317d8990b4a6ea09f5998d72998b1b915eb4097d0c9723dac316b34cfbee345d14409fe96bdea1a01db6cdcc5033f681ab4450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8a5a7f7175d364c8e27f7cca383f002e439dfc635373467efb03bf8b9e799a52386d8cbbcf58902381272ad29cff87fdac6d6f2d88af215751b3e99302bc874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39e1e1ab9e6ab3f76416fb11f8a609458c3cd498184014245032eda804703fbefb46cff8b11d33692fa09b7ba494ef1d0b6e4d9464106d3191d8f1c8b7033f77"} build_url:https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/cefMevC6TbW8gUAcXQxjPw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b318b54c4e302285a10a34efa9317d8990b4a6ea09f5998d72998b1b915eb4097d0c9723dac316b34cfbee345d14409fe96bdea1a01db6cdcc5033f681ab4450", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8a5a7f7175d364c8e27f7cca383f002e439dfc635373467efb03bf8b9e799a52386d8cbbcf58902381272ad29cff87fdac6d6f2d88af215751b3e99302bc874", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39e1e1ab9e6ab3f76416fb11f8a609458c3cd498184014245032eda804703fbefb46cff8b11d33692fa09b7ba494ef1d0b6e4d9464106d3191d8f1c8b7033f77"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 06:18:03.871620) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:18:03.872007) ========= 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.lmhyF38MRb/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.WTNLDD79BM/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.005277 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:18:03.924032) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 06:18:03.924739) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 06:18:03.925585) ========= ========= Total master_lag: 0.18 =========