builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0151 starttime: 1469311642.34 results: success (0) buildid: 20160702021557 builduid: 33ea82e0880b468ead015a673156bad0 revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.340947) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.354280) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.354605) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.382069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.382480) ========= 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.r1L03ESksa/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.s52xjEebv7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-23 15:07:22-- 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% 12.5M=0.001s 2016-07-23 15:07:22 (12.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.505829 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.914782) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.915119) ========= 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.r1L03ESksa/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.s52xjEebv7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.027903 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:22.966171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-23 15:07:22.966521) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39dffbba764210b25bfc1e749b4f16db77fa0d46 --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 39dffbba764210b25bfc1e749b4f16db77fa0d46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r1L03ESksa/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.s52xjEebv7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-23 15:07:23,041 truncating revision to first 12 chars 2016-07-23 15:07:23,041 Setting DEBUG logging. 2016-07-23 15:07:23,041 attempt 1/10 2016-07-23 15:07:23,041 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39dffbba7642?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-23 15:07:24,136 unpacking tar archive at: mozilla-central-39dffbba7642/testing/mozharness/ program finished with exit code 0 elapsedTime=1.363717 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-23 15:07:24.353716) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:24.354045) ========= script_repo_revision: 39dffbba764210b25bfc1e749b4f16db77fa0d46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:24.354450) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:24.354834) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-23 15:07:24.373741) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-23 15:07:24.374143) ========= /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.r1L03ESksa/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.s52xjEebv7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:07:24 INFO - MultiFileLogger online at 20160723 15:07:24 in /builds/slave/test 15:07:24 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 15:07:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:07:24 INFO - {'append_to_log': False, 15:07:24 INFO - 'base_work_dir': '/builds/slave/test', 15:07:24 INFO - 'blob_upload_branch': 'Firefox', 15:07:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:07:24 INFO - 'branch': 'Firefox', 15:07:24 INFO - 'buildbot_json_path': 'buildprops.json', 15:07:24 INFO - 'config_files': ('talos/mac_config.py',), 15:07:24 INFO - 'default_actions': ('clobber', 15:07:24 INFO - 'read-buildbot-config', 15:07:24 INFO - 'download-and-extract', 15:07:24 INFO - 'populate-webroot', 15:07:24 INFO - 'create-virtualenv', 15:07:24 INFO - 'install', 15:07:24 INFO - 'run-tests'), 15:07:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:07:24 INFO - 'download_minidump_stackwalk': True, 15:07:24 INFO - 'download_symbols': 'ondemand', 15:07:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:07:24 INFO - 'tooltool.py': '/tools/tooltool.py', 15:07:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:07:24 INFO - '/tools/misc-python/virtualenv.py')}, 15:07:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:07:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:07:24 INFO - 'installer_path': 'installer.exe', 15:07:24 INFO - 'log_level': 'info', 15:07:24 INFO - 'log_name': 'talos', 15:07:24 INFO - 'log_to_console': True, 15:07:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:07:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:07:24 INFO - 'opt_config_files': (), 15:07:24 INFO - 'pip_index': False, 15:07:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:07:24 INFO - 'cmd': ('bash', 15:07:24 INFO - '-c', 15:07:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:07:24 INFO - 'enabled': True, 15:07:24 INFO - 'halt_on_failure': False, 15:07:24 INFO - 'name': 'check_screen_resolution'},), 15:07:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:07:24 INFO - 'cmd': ('bash', 15:07:24 INFO - '-c', 15:07:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:07:24 INFO - 'enabled': True, 15:07:24 INFO - 'halt_on_failure': False, 15:07:24 INFO - 'name': 'check_screen_resolution'},), 15:07:24 INFO - 'run_cmd_checks_enabled': True, 15:07:24 INFO - 'sps_profile': False, 15:07:24 INFO - 'sps_profile_interval': 0, 15:07:24 INFO - 'suite': 'dromaeojs', 15:07:24 INFO - 'system_bits': '32', 15:07:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:07:24 INFO - 'title': 't-yosemite-r7-0151', 15:07:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:07:24 INFO - 'use_talos_json': True, 15:07:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:07:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:07:24 INFO - 'work_dir': 'build'} 15:07:24 INFO - ##### 15:07:24 INFO - ##### Running clobber step. 15:07:24 INFO - ##### 15:07:24 INFO - Running pre-action listener: _resource_record_pre_action 15:07:24 INFO - Running main action method: clobber 15:07:24 INFO - rmtree: /builds/slave/test/build 15:07:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:07:26 INFO - Running post-action listener: _resource_record_post_action 15:07:26 INFO - ##### 15:07:26 INFO - ##### Finished clobber step (success) 15:07:26 INFO - ##### 15:07:26 INFO - ##### 15:07:26 INFO - ##### Running read-buildbot-config step. 15:07:26 INFO - ##### 15:07:26 INFO - Running pre-action listener: _resource_record_pre_action 15:07:26 INFO - Running main action method: read_buildbot_config 15:07:26 INFO - Using buildbot properties: 15:07:26 INFO - { 15:07:26 INFO - "project": "", 15:07:26 INFO - "product": "firefox", 15:07:26 INFO - "script_repo_revision": "production", 15:07:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:07:26 INFO - "repository": "", 15:07:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:07:26 INFO - "buildid": "20160702021557", 15:07:26 INFO - "pgo_build": "False", 15:07:26 INFO - "basedir": "/builds/slave/test", 15:07:26 INFO - "buildnumber": 17, 15:07:26 INFO - "slavename": "t-yosemite-r7-0151", 15:07:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:07:26 INFO - "platform": "yosemite_r7", 15:07:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:07:26 INFO - "branch": "mozilla-central", 15:07:26 INFO - "repo_path": "mozilla-central", 15:07:26 INFO - "revision": "39dffbba764210b25bfc1e749b4f16db77fa0d46", 15:07:26 INFO - "stage_platform": "macosx64", 15:07:26 INFO - "builduid": "33ea82e0880b468ead015a673156bad0", 15:07:26 INFO - "slavebuilddir": "test" 15:07:26 INFO - } 15:07:26 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg. 15:07:26 INFO - Running post-action listener: _resource_record_post_action 15:07:26 INFO - ##### 15:07:26 INFO - ##### Finished read-buildbot-config step (success) 15:07:26 INFO - ##### 15:07:26 INFO - ##### 15:07:26 INFO - ##### Running download-and-extract step. 15:07:26 INFO - ##### 15:07:26 INFO - Running pre-action listener: _resource_record_pre_action 15:07:26 INFO - Running main action method: download_and_extract 15:07:26 INFO - mkdir: /builds/slave/test/build/tests 15:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:07:26 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.test_packages.json 15:07:26 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/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 15:07:27 INFO - Downloaded 1199 bytes. 15:07:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 15:07:27 INFO - Using the following test package requirements: 15:07:27 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:07:27 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 15:07:27 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 15:07:27 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'jsshell-mac.zip'], 15:07:27 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 15:07:27 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 15:07:27 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 15:07:27 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 15:07:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 15:07:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 15:07:27 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 15:07:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:07: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')]} 15:07:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.common.tests.zip 15:07:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/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 15:07:27 INFO - Downloaded 18791292 bytes. 15:07:27 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'] 15:07:27 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 15:07:30 INFO - Return code: 0 15:07:30 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 15:07: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')]} 15:07:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.common.tests.zip 15:07:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 15:07:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/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 15:07:31 INFO - Downloaded 18791292 bytes. 15:07:31 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'] 15:07:31 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 15:07:34 INFO - Return code: 0 15:07:34 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')]} 15:07:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.talos.tests.zip 15:07:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 15:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/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 15:07:35 INFO - Downloaded 14405327 bytes. 15:07:35 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'] 15:07:35 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 15:07:35 INFO - Return code: 0 15:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:07:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg 15:07:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 15:07:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 15:07:38 INFO - Downloaded 112452250 bytes. 15:07:38 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg 15:07:38 INFO - mkdir: /builds/slave/test/properties 15:07:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:07:38 INFO - Writing to file /builds/slave/test/properties/build_url 15:07:38 INFO - Contents: 15:07:38 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg 15:07:39 INFO - Running post-action listener: _resource_record_post_action 15:07:39 INFO - Running post-action listener: set_extra_try_arguments 15:07:39 INFO - ##### 15:07:39 INFO - ##### Finished download-and-extract step (success) 15:07:39 INFO - ##### 15:07:39 INFO - ##### 15:07:39 INFO - ##### Running populate-webroot step. 15:07:39 INFO - ##### 15:07:39 INFO - Running pre-action listener: _resource_record_pre_action 15:07:39 INFO - Running main action method: populate_webroot 15:07:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:07:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:07:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:07:39 INFO - '--tppagecycles', 15:07:39 INFO - '7'], 15:07:39 INFO - 'tests': ['tsvgm']}}, 15:07:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:07:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:07:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:07:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:07:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['damp', 'tps']}, 15:07:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'tests': ['damp', 'tps']}, 15:07:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['dromaeo_dom']}, 15:07:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:07:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['basic_compositor_video']}, 15:07:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:07:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['a11yr', 15:07:39 INFO - 'ts_paint', 15:07:39 INFO - 'tpaint', 15:07:39 INFO - 'sessionrestore', 15:07:39 INFO - 'sessionrestore_no_auto_restore', 15:07:39 INFO - 'tabpaint']}, 15:07:39 INFO - 'other-e10s': {'tests': ['a11yr', 15:07:39 INFO - 'ts_paint', 15:07:39 INFO - 'tpaint', 15:07:39 INFO - 'sessionrestore', 15:07:39 INFO - 'sessionrestore_no_auto_restore', 15:07:39 INFO - 'tabpaint']}, 15:07:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:07:39 INFO - 'ts_paint', 15:07:39 INFO - 'tpaint', 15:07:39 INFO - 'sessionrestore', 15:07:39 INFO - 'sessionrestore_no_auto_restore', 15:07:39 INFO - 'tabpaint']}, 15:07:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:07:39 INFO - 'ts_paint', 15:07:39 INFO - 'tpaint', 15:07:39 INFO - 'sessionrestore', 15:07:39 INFO - 'sessionrestore_no_auto_restore', 15:07:39 INFO - 'tabpaint']}, 15:07:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['a11yr', 15:07:39 INFO - 'ts_paint', 15:07:39 INFO - 'tpaint', 15:07:39 INFO - 'sessionrestore', 15:07:39 INFO - 'sessionrestore_no_auto_restore', 15:07:39 INFO - 'tabpaint']}, 15:07:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['a11yr', 15:07:39 INFO - 'ts_paint', 15:07:39 INFO - 'tpaint', 15:07:39 INFO - 'sessionrestore', 15:07:39 INFO - 'sessionrestore_no_auto_restore', 15:07:39 INFO - 'tabpaint']}, 15:07:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['tsvgx', 15:07:39 INFO - 'tsvgr_opacity', 15:07:39 INFO - 'tart', 15:07:39 INFO - 'tscrollx', 15:07:39 INFO - 'cart']}, 15:07:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:07:39 INFO - 'tsvgr_opacity', 15:07:39 INFO - 'tart', 15:07:39 INFO - 'tscrollx', 15:07:39 INFO - 'cart']}, 15:07:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'talos_options': ['--disable-e10s'], 15:07:39 INFO - 'tests': ['tp5o']}, 15:07:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'tests': ['tp5o']}, 15:07:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'talos_options': ['--disable-e10s', 15:07:39 INFO - '--xperf_path', 15:07:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:39 INFO - 'tests': ['tp5n']}, 15:07:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:39 INFO - 'talos_options': ['--xperf_path', 15:07:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:39 INFO - 'tests': ['tp5n']}}, 15:07:39 INFO - 'talos.zip': {'path': '', 15:07:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:07:39 INFO - Running post-action listener: _resource_record_post_action 15:07:39 INFO - ##### 15:07:39 INFO - ##### Finished populate-webroot step (success) 15:07:39 INFO - ##### 15:07:39 INFO - ##### 15:07:39 INFO - ##### Running create-virtualenv step. 15:07:39 INFO - ##### 15:07:39 INFO - Running pre-action listener: _resource_record_pre_action 15:07:39 INFO - Running main action method: create_virtualenv 15:07:39 INFO - Creating virtualenv /builds/slave/test/build/venv 15:07:39 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 15:07:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:07:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:07:39 INFO - Using real prefix '/tools/python27' 15:07:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:07:40 INFO - Installing distribute.............................................................................................................................................................................................done. 15:07:43 INFO - Installing pip.................done. 15:07:43 INFO - Return code: 0 15:07:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:07:43 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 15:07:43 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 15:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:43 INFO - 'HOME': '/Users/cltbld', 15:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:43 INFO - 'LOGNAME': 'cltbld', 15:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:43 INFO - 'MOZ_NO_REMOTE': '1', 15:07:43 INFO - 'NO_EM_RESTART': '1', 15:07:43 INFO - 'PAGER': '/bin/cat', 15:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:43 INFO - 'PWD': '/builds/slave/test', 15:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:43 INFO - 'SHELL': '/bin/bash', 15:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:07:43 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:43 INFO - 'USER': 'cltbld', 15:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:43 INFO - 'XPC_FLAGS': '0x0', 15:07:43 INFO - 'XPC_SERVICE_NAME': '0', 15:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:43 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 15:07:43 INFO - Cleaning up... 15:07:43 INFO - Return code: 0 15:07:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:43 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:07:43 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 15:07:43 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 15:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:43 INFO - 'HOME': '/Users/cltbld', 15:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:43 INFO - 'LOGNAME': 'cltbld', 15:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:43 INFO - 'MOZ_NO_REMOTE': '1', 15:07:43 INFO - 'NO_EM_RESTART': '1', 15:07:43 INFO - 'PAGER': '/bin/cat', 15:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:43 INFO - 'PWD': '/builds/slave/test', 15:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:43 INFO - 'SHELL': '/bin/bash', 15:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:07:43 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:43 INFO - 'USER': 'cltbld', 15:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:43 INFO - 'XPC_FLAGS': '0x0', 15:07:43 INFO - 'XPC_SERVICE_NAME': '0', 15:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:43 INFO - Downloading/unpacking psutil>=3.1.1 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:07:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:47 INFO - Installing collected packages: psutil 15:07:47 INFO - Running setup.py install for psutil 15:07:47 INFO - building 'psutil._psutil_osx' extension 15:07:47 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 15:07:47 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 15:07:48 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 15:07:48 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 15:07:48 INFO - building 'psutil._psutil_posix' extension 15:07:48 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 15:07:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:07:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:07:48 INFO - ^ 15:07:48 INFO - 1 warning generated. 15:07:48 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 15:07:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:48 INFO - Successfully installed psutil 15:07:48 INFO - Cleaning up... 15:07:48 INFO - Return code: 0 15:07:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:07: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:07: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 mozsystemmonitor==0.3 15:07:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:07:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:48 INFO - 'HOME': '/Users/cltbld', 15:07:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:48 INFO - 'LOGNAME': 'cltbld', 15:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:48 INFO - 'MOZ_NO_REMOTE': '1', 15:07:48 INFO - 'NO_EM_RESTART': '1', 15:07:48 INFO - 'PAGER': '/bin/cat', 15:07:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:48 INFO - 'PWD': '/builds/slave/test', 15:07:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:48 INFO - 'SHELL': '/bin/bash', 15:07:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:07:48 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:07:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:48 INFO - 'USER': 'cltbld', 15:07:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:48 INFO - 'XPC_FLAGS': '0x0', 15:07:48 INFO - 'XPC_SERVICE_NAME': '0', 15:07:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:07:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:07:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:07:51 INFO - Installing collected packages: mozsystemmonitor 15:07:51 INFO - Running setup.py install for mozsystemmonitor 15:07:51 INFO - Successfully installed mozsystemmonitor 15:07:51 INFO - Cleaning up... 15:07:51 INFO - Return code: 0 15:07:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:07:51 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')]} 15:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:51 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')]} 15:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:51 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:07:51 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 15:07:51 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 15:07:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:07:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:51 INFO - 'HOME': '/Users/cltbld', 15:07:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:51 INFO - 'LOGNAME': 'cltbld', 15:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:51 INFO - 'MOZ_NO_REMOTE': '1', 15:07:51 INFO - 'NO_EM_RESTART': '1', 15:07:51 INFO - 'PAGER': '/bin/cat', 15:07:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:51 INFO - 'PWD': '/builds/slave/test', 15:07:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:51 INFO - 'SHELL': '/bin/bash', 15:07:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:07:51 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:07:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:51 INFO - 'USER': 'cltbld', 15:07:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:51 INFO - 'XPC_FLAGS': '0x0', 15:07:51 INFO - 'XPC_SERVICE_NAME': '0', 15:07:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:51 INFO - Downloading/unpacking jsonschema==2.5.1 15:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:07:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:54 INFO - Downloading functools32-3.2.3-2.tar.gz 15:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:07:54 INFO - warning: no files found matching '*.txt' 15:07:54 INFO - no previously-included directories found matching 'build' 15:07:54 INFO - no previously-included directories found matching 'dist' 15:07:54 INFO - no previously-included directories found matching '.git*' 15:07:54 INFO - Installing collected packages: jsonschema, functools32 15:07:55 INFO - Running setup.py install for functools32 15:07:55 INFO - warning: no files found matching '*.txt' 15:07:55 INFO - no previously-included directories found matching 'build' 15:07:55 INFO - no previously-included directories found matching 'dist' 15:07:55 INFO - no previously-included directories found matching '.git*' 15:07:55 INFO - Successfully installed jsonschema functools32 15:07:55 INFO - Cleaning up... 15:07:55 INFO - Return code: 0 15:07:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:55 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:07:55 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 15:07:55 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 15:07:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:07:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:07:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:07:55 INFO - 'HOME': '/Users/cltbld', 15:07:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:07:55 INFO - 'LOGNAME': 'cltbld', 15:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:55 INFO - 'MOZ_NO_REMOTE': '1', 15:07:55 INFO - 'NO_EM_RESTART': '1', 15:07:55 INFO - 'PAGER': '/bin/cat', 15:07:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:55 INFO - 'PWD': '/builds/slave/test', 15:07:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:07:55 INFO - 'SHELL': '/bin/bash', 15:07:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:07:55 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:07:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:07:55 INFO - 'USER': 'cltbld', 15:07:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:07:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:55 INFO - 'XPC_FLAGS': '0x0', 15:07:55 INFO - 'XPC_SERVICE_NAME': '0', 15:07:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:55 INFO - Downloading/unpacking blobuploader==1.2.4 15:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:57 INFO - Downloading blobuploader-1.2.4.tar.gz 15:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:07:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:07:57 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 15:07:57 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 15:07:57 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 15:07:57 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 15:07:57 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 15:07:57 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 15:07:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:07:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:59 INFO - Downloading docopt-0.6.1.tar.gz 15:07:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:07:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:07:59 INFO - Running setup.py install for blobuploader 15:07:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:07:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:07:59 INFO - Running setup.py install for requests 15:07:59 INFO - Running setup.py install for docopt 15:07:59 INFO - Successfully installed blobuploader requests docopt 15:07:59 INFO - Cleaning up... 15:08:00 INFO - Return code: 0 15:08:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:08:00 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')]} 15:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:00 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')]} 15:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:00 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:08:00 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 15:08:00 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 15:08:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:08:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:08:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:08:00 INFO - 'HOME': '/Users/cltbld', 15:08:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:08:00 INFO - 'LOGNAME': 'cltbld', 15:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:00 INFO - 'MOZ_NO_REMOTE': '1', 15:08:00 INFO - 'NO_EM_RESTART': '1', 15:08:00 INFO - 'PAGER': '/bin/cat', 15:08:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:00 INFO - 'PWD': '/builds/slave/test', 15:08:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:08:00 INFO - 'SHELL': '/bin/bash', 15:08:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:08:00 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:08:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:08:00 INFO - 'USER': 'cltbld', 15:08:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:08:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:00 INFO - 'XPC_FLAGS': '0x0', 15:08:00 INFO - 'XPC_SERVICE_NAME': '0', 15:08:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:08:00 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-vKHw_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:08:00 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-xKlhKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:08:00 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-fOvCwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:08:00 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ATqbjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:08:00 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-FrD9kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:08:00 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-wzVECv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:08:00 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-w2S7tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Mg2q3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Jj0qS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-yGE7qW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Rr4XC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-g9umx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-E8FyEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-C1H5Gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-VtN8zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:08:01 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-gqBVDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:08:02 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-0l4iO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:08:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:08:02 INFO - Running setup.py install for manifestparser 15:08:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:08:02 INFO - Running setup.py install for mozcrash 15:08:02 INFO - Running setup.py install for mozdebug 15:08:02 INFO - Running setup.py install for mozdevice 15:08:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:08:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:08:02 INFO - Running setup.py install for mozfile 15:08:02 INFO - Running setup.py install for mozhttpd 15:08:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Running setup.py install for mozinfo 15:08:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Running setup.py install for mozInstall 15:08:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Running setup.py install for mozleak 15:08:03 INFO - Running setup.py install for mozlog 15:08:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Running setup.py install for moznetwork 15:08:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:08:03 INFO - Running setup.py install for mozprocess 15:08:03 INFO - Running setup.py install for mozprofile 15:08:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:08:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:08:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:08:04 INFO - Running setup.py install for mozrunner 15:08:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:08:04 INFO - Running setup.py install for mozscreenshot 15:08:04 INFO - Running setup.py install for moztest 15:08:04 INFO - Running setup.py install for mozversion 15:08:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:08:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:08:04 INFO - Cleaning up... 15:08:04 INFO - Return code: 0 15:08:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:04 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:08:04 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 15:08:04 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 15:08:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:08:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:08:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:08:04 INFO - 'HOME': '/Users/cltbld', 15:08:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:08:04 INFO - 'LOGNAME': 'cltbld', 15:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:04 INFO - 'MOZ_NO_REMOTE': '1', 15:08:04 INFO - 'NO_EM_RESTART': '1', 15:08:04 INFO - 'PAGER': '/bin/cat', 15:08:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:04 INFO - 'PWD': '/builds/slave/test', 15:08:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:08:04 INFO - 'SHELL': '/bin/bash', 15:08:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:08:04 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:08:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:08:04 INFO - 'USER': 'cltbld', 15:08:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:08:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:04 INFO - 'XPC_FLAGS': '0x0', 15:08:04 INFO - 'XPC_SERVICE_NAME': '0', 15:08:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-R6ttlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-26qmNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-mkw2YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-uia0EH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-unwbAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-ZMzt37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-s_9w0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-Vf4EOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-GxfbPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:08:05 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)) 15:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:08:05 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-NNrZo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:08:06 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)) 15:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:08:06 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-g_YOVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:08:06 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)) 15:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:08:06 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-TC_DUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:08:06 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)) 15:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:08:06 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-tvyX9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:08:06 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)) 15:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:08:06 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-5vFI8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:08:06 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)) 15:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:06 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-sMbOKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:08:06 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)) 15:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:08:06 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-lefVbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:08:06 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)) 15:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:08:06 INFO - Running setup.py (path:/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/pip-3jeCw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:08:06 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)) 15:08:06 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)) 15:08:06 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)) 15:08:06 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)) 15:08:06 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)) 15:08:06 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)) 15:08:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:08:06 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 15:08:06 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 15:08:06 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 15:08:06 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 15:08:06 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 15:08:06 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 15:08:09 INFO - Downloading blessings-1.6.tar.gz 15:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:08:09 INFO - Installing collected packages: blessings 15:08:09 INFO - Running setup.py install for blessings 15:08:09 INFO - Successfully installed blessings 15:08:09 INFO - Cleaning up... 15:08:09 INFO - Return code: 0 15:08:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:08:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:08:09 INFO - Reading from file tmpfile_stdout 15:08:09 INFO - Current package versions: 15:08:09 INFO - blessings == 1.6 15:08:09 INFO - blobuploader == 1.2.4 15:08:09 INFO - docopt == 0.6.1 15:08:09 INFO - functools32 == 3.2.3-2 15:08:09 INFO - jsonschema == 2.5.1 15:08:09 INFO - manifestparser == 1.1 15:08:09 INFO - mozInstall == 1.12 15:08:09 INFO - mozcrash == 0.17 15:08:09 INFO - mozdebug == 0.1 15:08:09 INFO - mozdevice == 0.48 15:08:09 INFO - mozfile == 1.2 15:08:09 INFO - mozhttpd == 0.7 15:08:09 INFO - mozinfo == 0.9 15:08:09 INFO - mozleak == 0.1 15:08:09 INFO - mozlog == 3.2 15:08:09 INFO - moznetwork == 0.27 15:08:09 INFO - mozprocess == 0.22 15:08:09 INFO - mozprofile == 0.28 15:08:09 INFO - mozrunner == 6.11 15:08:09 INFO - mozscreenshot == 0.1 15:08:09 INFO - mozsystemmonitor == 0.3 15:08:09 INFO - moztest == 0.7 15:08:09 INFO - mozversion == 1.4 15:08:09 INFO - psutil == 3.1.1 15:08:09 INFO - requests == 1.2.3 15:08:09 INFO - wsgiref == 0.1.2 15:08:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:09 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:08:09 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 15:08:09 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 15:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:08:09 INFO - 'HOME': '/Users/cltbld', 15:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:08:09 INFO - 'LOGNAME': 'cltbld', 15:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:09 INFO - 'MOZ_NO_REMOTE': '1', 15:08:09 INFO - 'NO_EM_RESTART': '1', 15:08:09 INFO - 'PAGER': '/bin/cat', 15:08:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:09 INFO - 'PWD': '/builds/slave/test', 15:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:08:09 INFO - 'SHELL': '/bin/bash', 15:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:08:09 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:08:09 INFO - 'USER': 'cltbld', 15:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:09 INFO - 'XPC_FLAGS': '0x0', 15:08:09 INFO - 'XPC_SERVICE_NAME': '0', 15:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 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)) 15:08:10 INFO - Cleaning up... 15:08:10 INFO - Return code: 0 15:08:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:08:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:08:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:08:10 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 0x10e494a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e529618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae78c97d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, '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.r1L03ESksa/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.s52xjEebv7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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 15:08:10 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 15:08:10 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 15:08:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:08:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:08:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:08:10 INFO - 'HOME': '/Users/cltbld', 15:08:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:08:10 INFO - 'LOGNAME': 'cltbld', 15:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:10 INFO - 'MOZ_NO_REMOTE': '1', 15:08:10 INFO - 'NO_EM_RESTART': '1', 15:08:10 INFO - 'PAGER': '/bin/cat', 15:08:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:10 INFO - 'PWD': '/builds/slave/test', 15:08:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:08:10 INFO - 'SHELL': '/bin/bash', 15:08:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:08:10 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:08:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:08:10 INFO - 'USER': 'cltbld', 15:08:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:08:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:10 INFO - 'XPC_FLAGS': '0x0', 15:08:10 INFO - 'XPC_SERVICE_NAME': '0', 15:08:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:08:10 INFO - Cleaning up... 15:08:10 INFO - Return code: 0 15:08:10 INFO - Running post-action listener: _resource_record_post_action 15:08:10 INFO - Running post-action listener: _start_resource_monitoring 15:08:10 INFO - Starting resource monitoring. 15:08:10 INFO - ##### 15:08:10 INFO - ##### Finished create-virtualenv step (success) 15:08:10 INFO - ##### 15:08:10 INFO - ##### 15:08:10 INFO - ##### Running install step. 15:08:10 INFO - ##### 15:08:10 INFO - Running pre-action listener: _resource_record_pre_action 15:08:10 INFO - Running main action method: install 15:08:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:08:10 INFO - Reading from file tmpfile_stdout 15:08:10 INFO - Detecting whether we're running mozinstall >=1.0... 15:08:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:08:10 INFO - Reading from file tmpfile_stdout 15:08:10 INFO - Output received: 15:08:10 INFO - Usage: mozinstall [options] installer 15:08:10 INFO - Options: 15:08:10 INFO - -h, --help show this help message and exit 15:08:10 INFO - -d DEST, --destination=DEST 15:08:10 INFO - Directory to install application into. [default: 15:08:10 INFO - "/builds/slave/test"] 15:08:10 INFO - --app=APP Application being installed. [default: firefox] 15:08:10 INFO - mkdir: /builds/slave/test/build/application 15:08:10 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'] 15:08:10 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 15:08:44 INFO - Reading from file tmpfile_stdout 15:08:44 INFO - Output received: 15:08:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:08:44 INFO - Running post-action listener: _resource_record_post_action 15:08:44 INFO - ##### 15:08:44 INFO - ##### Finished install step (success) 15:08:44 INFO - ##### 15:08:44 INFO - ##### 15:08:44 INFO - ##### Running run-tests step. 15:08:44 INFO - ##### 15:08:44 INFO - Running pre-action listener: _resource_record_pre_action 15:08:44 INFO - Running main action method: run_tests 15:08:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:08:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:08:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:08:44 INFO - 2016-07-23 15:08:44.786 screenresolution[1654:11380] starting screenresolution argv=screenresolution get 15:08:44 INFO - 2016-07-23 15:08:44.789 screenresolution[1654:11380] Display 0: 1600x1200x32@60 15:08:44 INFO - 2016-07-23 15:08:44.795 screenresolution[1655:11383] starting screenresolution argv=screenresolution list 15:08:44 INFO - Available Modes on Display 0 15:08:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:08:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:08:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:08:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:08:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:08:45 INFO - Intel Iris: 15:08:45 INFO - Chipset Model: Intel Iris 15:08:45 INFO - Type: GPU 15:08:45 INFO - Bus: Built-In 15:08:45 INFO - VRAM (Dynamic, Max): 1536 MB 15:08:45 INFO - Vendor: Intel (0x8086) 15:08:45 INFO - Device ID: 0x0a2e 15:08:45 INFO - Revision ID: 0x0009 15:08:45 INFO - Displays: 15:08:45 INFO - Display: 15:08:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:08:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:08:45 INFO - Display Serial Number: 1600x1200 60 15:08:45 INFO - Main Display: Yes 15:08:45 INFO - Mirror: Off 15:08:45 INFO - Online: Yes 15:08:45 INFO - Rotation: Supported 15:08:45 INFO - Return code: 0 15:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:08:45 INFO - Python 2.7.3 15:08:45 INFO - Return code: 0 15:08:45 INFO - grabbing minidump binary from tooltool 15:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:08:45 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 0x10e3f8d30>, '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 0x10e15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e3f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:08:45 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 15:08:45 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 15:08:45 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 15:08:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:08:45 INFO - Return code: 0 15:08:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:08:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:08:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:08:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:08:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:08:45 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 15:08:45 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/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0151 --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 15:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r1L03ESksa/Render', 15:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:08:45 INFO - 'HOME': '/Users/cltbld', 15:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:08:45 INFO - 'LOGNAME': 'cltbld', 15:08:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:08:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:08:45 INFO - 'MOZ_NO_REMOTE': '1', 15:08:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:08:45 INFO - 'NO_EM_RESTART': '1', 15:08:45 INFO - 'PAGER': '/bin/cat', 15:08:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:08:45 INFO - 'PWD': '/builds/slave/test', 15:08:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:08:45 INFO - 'SHELL': '/bin/bash', 15:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s52xjEebv7/Listeners', 15:08:45 INFO - 'TMPDIR': '/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/', 15:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:08:45 INFO - 'USER': 'cltbld', 15:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:08:45 INFO - 'XPC_FLAGS': '0x0', 15:08:45 INFO - 'XPC_SERVICE_NAME': '0', 15:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:08:45 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/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0151', '--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 15:08:45 INFO - mozversion application_buildid: 20160702021557 15:08:45 INFO - mozversion application_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 15:08:45 INFO - mozversion application_display_name: Nightly 15:08:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:08:45 INFO - mozversion application_name: Firefox 15:08:45 INFO - mozversion application_remotingname: firefox 15:08:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:08:45 INFO - mozversion application_vendor: Mozilla 15:08:45 INFO - mozversion application_version: 50.0a1 15:08:45 INFO - mozversion platform_buildid: 20160702021557 15:08:45 INFO - mozversion platform_changeset: 39dffbba764210b25bfc1e749b4f16db77fa0d46 15:08:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:08:45 INFO - mozversion platform_version: 50.0a1 15:08:45 INFO - using testdate: 1469311725 15:08:45 INFO - actual date: 1469311725 15:08:45 INFO - starting webserver on 'localhost:49280' 15:08:45 INFO - SUITE-START | Running 2 tests 15:08:45 INFO - TEST-START | dromaeo_css 15:08:45 INFO - operating with platform_type : mac_ 15:08:45 INFO - Initialising browser for dromaeo_css test... 15:08:45 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpuX9jif/profile http://localhost:49280/getInfo.html) 15:08:47 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 15:08:47 INFO - PROCESS | 1665 | colorDepth:24 15:08:47 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 15:08:47 INFO - PROCESS | 1665 | __metrics 15:08:48 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:08:48 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:08:48 INFO - TEST-INFO | 1665: exit 0 15:08:48 INFO - Browser initialized. 15:08:48 INFO - Running cycle 1/1 for dromaeo_css test... 15:08:48 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpuX9jif/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:09:00 INFO - PROCESS | 1668 | RSS: Main: 244891648 15:09:00 INFO - PROCESS | 1668 | 15:09:51 INFO - PROCESS | 1668 | 1469311791865 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] 15:12:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 15:12:05 INFO - PROCESS | 1668 | RSS: Main: 438710272 15:12:05 INFO - PROCESS | 1668 | 15:15:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 15:15:08 INFO - PROCESS | 1668 | RSS: Main: 379351040 15:15:08 INFO - PROCESS | 1668 | 15:18:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 15:18:11 INFO - PROCESS | 1668 | RSS: Main: 373383168 15:18:11 INFO - PROCESS | 1668 | 15:21:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 15:21:14 INFO - PROCESS | 1668 | RSS: Main: 374439936 15:21:14 INFO - PROCESS | 1668 | 15:24:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 15:24:18 INFO - PROCESS | 1668 | RSS: Main: 349151232 15:24:18 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 15:27:22 INFO - PROCESS | 1668 | RSS: Main: 435429376 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | __start_tp_report 15:27:22 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 15:27:22 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 15:27:22 INFO - PROCESS | 1668 | |i|pagename|runs| 15:27:22 INFO - PROCESS | 1668 | |0;dojo.html;3316.9336613267737;3336.683015113563;3370.713489353405;3375.5611770469836;3416.145963509123;5554.750036248369;5621.77512899484;5658.519025882805;5716.1711551824965;5729.3124825021005;5641.8589535261635;5698.173764804101;5707.6290041147295;5736.512396446296;5737.168110623957;5116.258142569325;5154.6134039946965;5171.405288391846;5178.6892786432745;5226.503482169207;5232.032074066291;5240.187770895498;5242.056429842646;5246.7638956247065;5251.133562962106;5123.462036486171;5132.153194722866;5133.897322053556;5157.200633901752;5227.980543793961;5212.1660534314515;5235.712035838027;5236.581073514109;5262.05283049051;5299.125644268691;5035.194368900995;5050.267711181895;5068.594512438996;5165;5172.405173405069;5599.356074051496;5643.266375371176;5661.037623603997;5682.17608446777;5715.057015592422;6230.190075290183;6235.9376406235815;6237.812865613993;6245.531585131088;6248.50011999039;5605.523530499912;6185.35053819347;6187.041008643668;6261.5929964552615;6296.622202667854;5735.713214339266;5781.971090144607;5799.710014499216;5830.883382332329;5833;6221.9377806221355;6235.625862448268;6236.6258024518675;6244.843878902973;6248.28144763349;5974.402559744078;6047.637141771508;6154.5384096192965;6180.876382472416;6195.504359651217;6108.949222962717;6209.316975132731;6239.469645080128;6264.655443950626;6284.968575157095;6157.876842463215;6202.255729312511;6220.782272620437;6232.501399887998;6253.093301471352;6090.056041313604;6179.196704428396;6257.530685198628;6276.246850377984;6281.748730050746;4833.130036593447;4854.296127061556;4865.635077369211;4890.24201248807;4911.312416261726;4427.845025424094;4431.958489754905;4432.423784907941;4438.511763705989;4473.150101480708;5619.466150715675;5691.231683722644;5780.826575202751;5785.305763308345;5822.505087067647;6170.753169873245;6201.10084037812;6268;6278.6860656966965;6298.937010629927;4853.4418541867435;5774.855628609318;5776.475096166769;5785.71071446426;5788.0739081746715;5301.708406037628;5337.199420087018;5414.242006119146;5415.268938693304;5421.810236641778;6176.722047507873;6182.072689096672;6219.253689557282;6237.5009999200865;6237.625742455467;6284.183056202664;6293.244810622755;6294.8111556653375;6298.08677741734;6314.9264625014775;6192.885280649346;6236.594621349598;6245.594036387621;6253.124562561154;6264.373562643699;6077.24034495688;6104.519788040188;6131.693415329127;6154.753809847735;6208.379162083756;5523.254360661259;6190.41191086846;6203.348648392034;6215.254169499689;6239.688015599293;6101.19492530469;6121.632702037892;6131.96934015327;6146.170267013896;6148.907766383597;6106.3089915548935;6108.159949115366;6109.829807539466;6110.40687992437;6130.092410195535;5771.8557036074435;5820.592558520862;5827.621204621686;5844.94155058461;6008.669523176267;6034.2155519782145;6049.939500604937;6074.179985701874;6074.936886045013;6103.664298463627;5901.29184497863;5934.43622855828;5956.374580668968;5973.1637570741;6029.909551356821 15:27:22 INFO - PROCESS | 1668 | |1;ext.html;465.9743714095724;479.2786855782048;484.4453101199125;538.9407165211826;546.7534022428862;30674.693253067464;32490.18774530635;32511.837440812764;32576.348473030583;32593;33243.168920776996;33854.66145338546;42682.14635707284;43399.56600434003;43623.12753744923;9740.123388895016;9888.950555247213;9907.405555666683;9920.255980801432;9921.95039024804;9749.561269742879;9844.458554779485;9845.40927544346;9861.161801246903;9861.852072218886;8759.95620021899;8924.821503569925;8941.95529022354;9008.54957252138;9049.773755656128;8894.688685895995;11740.471678774476;12000.21998570099;12007.639770806802;12190.329531875743;11870.109741769402;11955.222910510789;11957.282563046245;11967.042636589053;11995.340256285901;39766;40077;40123.59876401228;40134.79932600318;40210.994725131815;2965.555166724987;3488.7383446241633;3499.6325385834375;3643.1984963308296;3777.5089238399105;31490.055298340856;32073.6792632073;32448.351032979208;32957.17607059819;33086.6691333086;3251.057193413907;3277.2298509850166;3279.6720327967014;3306.57014588102;3416.9145771355916;424388;507530;508333;508865;508871.45564272313;33374.332513350084;33585.496217556756;56965.29725567825;57214.42785572091;57340.426595734345;34669.30661386809;34933.1266718329;34959;35034;35081.1229719254;14374.568762937128;14692.26538673062;14714.705705885823;14722.926385368219;14745;12428.502859885684;12489.75020499585;12499;12515.56195533152;12518.186317889307;7760;7779.7666070017995;7784.416168787364;7843.882341764764;7889.329407000355;3030.409070231328;3053.0993356959752;3055.801372910754;3202.8718851246153;3223.113643747988;719.1154879498247;953.9427634341962;986.4475893499663;1060.771934034171;1083.534080345459;24406.1457848978;29483.262918426783;29696;30310.242243944078;30586.929457469327;1080.8216644253766;1092.224520590374;1115.2472081345059;1120.3334016260312;1137.2494153858413;15073.849261507465;15839.604009899846;15989.440369587008;16072.678546429006;16160.27278772458;695752;697446.5383019259;701685;705680;706390;57616.71191644099;58154.8369032617;58166;58388;58401;1223.816427535859;1272.9872701273055;1296.494367196794;1370.369629970185;1402.3268830961197;30462.695373045986;31565.52651710272;31746.902893293296;32047.198820029687;32279;48808.51191488039;48872;49285.76785580389;49286.99999999857;49294.50705492899;541239;541504;542593;543364;543711;23497.76502234956;24768.628470572596;26482.808273628118;26686.599701004114;26743.732562674126;47941.5205847937;48167.5183248163;48175.759121204166;49382.012359753324;49470;40175;40400.59599404086;40685.81974410743;41369.79315103405;41597.37603936004;40389.59610403858;40663.98340041523;40731.796341018104;41281;41643.7917810409;38790.4181437273;39381;39512.802435987636;39818;40055.1988960225;31161.688383115878;32544.83727581347;32765;34702.30595388129;34886.30227395488;22962.31113066611;23979.640305395085;24642.876785616674;25096.62355064712;25920.740792592587 15:27:22 INFO - PROCESS | 1668 | |2;jquery.html;325.7752151015799;351.3692921206434;375.8289978059983;385.9556151042629;402.9677625789937;15750.291236894338;17289.65420691587;20420.693689594667;21044.053017614224;21068.89465552674;20104.49738756532;21104.57790844182;21316;21717.45706357342;21779.782202177972;4922.581580565657;5375.892482150354;5564.221009058735;5564.749586268607;6165.106059621352;6092.51259899207;6096.35988221237;6130.478909292716;6130.540209484284;6142.754289828402;5829.8834023319505;6092.969535152318;6110.297315808694;6167.321594624587;6168.537359698041;5933.139694744238;6111.663858487781;6155.72299246535;6161.260648722137;6163.106349579286;5993.160957465958;6005.60963537373;6016.278046634432;6018.548608854309;6030.849228769272;13588.048836581444;13835.239061851595;21972;26286;26316.605250921253;2042.1320938601036;2080.4278823323707;2152.655575107975;2363.172889488665;2853.069891240404;9681.564329605184;10128.797424051552;10172.338797978182;10591.894081059245;10677.30597511167;1848.5655870870335;1909.9590723055967;1928.845692344609;1944.6013567218686;2127.4362293992103;102509;128447.35776321152;131608;132272.33863830715;136982.31508842492;20865.89567052155;20870.156356220283;21427.67858482124;21447.63809047738;21729.782702173092;35742;35768.64231357653;35833;35921.8203908984;36004.819975899954;10740.83888741654;11210.271332363533;11247.493862776195;11279;11370.20408571392;9197.356185067116;9257.305702072372;9266.907330926604;9338.859917101248;9406.905930940604;9025.142611451902;9358.298127640453;9788.951055244679;9888.20894328452;9922.057404546556;3438.5529881115294;3542.1852973816167;3659.5059666945153;3759.684110561336;3763.379042457964;836.2599099796602;841.0832192909636;888.8977767556685;904.7514430086438;977.5503268496429;20901.79098209029;21455.67816482754;21489.78510214909;21516.031778570003;21521.031553580127;795.9005124359455;943.3679434778714;976.1848856205006;986.2800155886243;1055.714956961616;11142.220044596963;11370.658880233606;11421.600243991421;11441.713957151138;11499.597514086967;585669;594900.2549872489;601490;602176;608227.9588602028;117279.48161555167;125567;134116;134580;137879;831.7629475599546;951.4338968313842;973.2846357926918;980.74010387246;984.9901500984898;17374.565635859206;17799.110044497465;18730.15714292833;18952.95758733228;19129.33047343336;54576.72711636575;55256.894862103334;55379.89240215254;55917.44082559122;55981.720091399286;75748.62125689337;76045;76913.84629230679;77854.61072694827;78156.82764758647;6874.828129296808;8158.551279679437;8210.83578328442;8567.443116197677;8607.526586037577;12488.937555312164;13159.868401315864;13243.801342980056;13359;13504.054716170167;11328.94335528317;11361.204715669825;11378.658640240807;11386.032187263843;11559.479823407799;11127.165462590176;11205.66383008511;11215.214934954474;11225.66323010311;11352.772944540899;11595.246308989888;11743.823842642538;11790.292582445081;11841.763164736829;11947.641570752892;7293.9635301823155;7479.476436649382;7497.512661677191;7793.10379306387;8603.397762156588;10656.573737050741;11259.76142624327;11568.07455403549;11623.6512904613;11700.824487632863 15:27:22 INFO - PROCESS | 1668 | |3;mootools.html;1368.0286996232674;1470.8161479815026;1532.4176812811131;1706.1725063344284;1861.1903821837502;7732.690692372305;8542.70100546481;8776.64893404263;8778.824423511534;8897.065808090154;7244.456665750064;8712.564371781402;8722.65109395625;8723.08407617199;8731.476111433318;2373.5846226910307;2408.6989126359204;2409.6987876515436;2411.0958390603523;2411.746766589509;2346.9765302347014;2377.560151371993;2390.008146619151;2396.149366974725;2396.7483414241515;2364.586197415454;2381.6189409694452;2391.9355886630456;2393.9162129325478;2394.5091256292503;2335.2643917166206;2365.73976862545;2366.3137690069857;2368.04094341791;2382.392489915078;2273.7385200701965;2354.5173239486035;2356.8232382571205;2359.3393849722106;2369.4313364792324;7782.883256751154;8035.397345199075;8792.42849214799;8807.603657835349;8887.289016878698;4816.301636262758;4963.156263435225;4969.527894849984;4983.526564976358;5154.587632989352;3565.2691198304287;3578.6063533011607;3662.047867554428;3667.1015601177883;3667.4865518827387;1056.4928834159646;1060.9522571484301;1062.8990245926702;1065.9360438373644;1077.175960390302;11571.479283432265;11701.063914886789;11712.414379281086;11718.648440546796;11720.179587428798;3682;4609.746463944515;4751.1447939370555;4932.1861892788;4943.802247910044;8578.699745508877;9992.950035249778;10015.69952901414;10017.499125043865;10037.598496060076;5389.461053894579;5394.136938089887;5397.217403476552;5397.568194544427;5419.864503387368;4355.303151495746;4396.802143903532;4559.749213793207;4893.657443978958;4937.530934561211;3919.8432062717743;3952.446657467957;4015.99600099975;4062.7156099073372;4066.471358723347;1273.2360583649736;1273.2742336868082;1277.4379273119798;1280.7310464802497;1284.8393950756156;806.5644551942003;900.8603666431713;924.117467818232;938.2447130060367;953.3183773601908;5699.544036477072;5907.881842363128;5939.762409503658;5966.791162309299;6052.878942421127;437.8073647595048;442.6436718441621;455.14205722213865;461.39557180094187;462.06431975249717;3912.902177445587;3959.920801583952;3988.4615576897327;4037.2934736421007;4076.490438695163;10036.548355324028;10123.088921997056;10432.791344173074;10795.352278863293;10883.619073332395;10784.406857622906;10795.892041079645;10801.78396432067;10805;10835.729106772236;6916.827079323057;7851.882221766678;8167.264946154874;8193;8262.958685206655;5869.4717475427415;5956.851078723066;5999.370066143167;6076.665783381956;6091.086337049478;9888.555015024198;10123.088921996761;10202.234832387452;10208.948955255177;10211.029952154533;8326.62530186131;8674.696385626472;10297.845532316871;10345.431001295;10379.584816607252;7059.18819335783;7143.9285607143265;7243.420526357984;7248.057752492142;7251.854962900607;7761.068671759425;7834.255745704149;7838.608069596611;7932.6430310637315;8238;7563.394928405824;7575.924240757522;7619.314261716472;7718.382529397747;7742.38060955111;7224.530405523624;7416.961625372344;7469.14104877946;7555.9244407555225;7582.772516824504;6743.325667433218;7008.439324854102;7071.7878463646175;7079.362857342864;7107.111611048619;5015;5016.172331565251;5023.522765337433;5051.924221136759;5126.128558145198;5061.291419201241;5085.46602606721;5109.875827317984;5110.8466745997675;5234.979179060047 15:27:22 INFO - PROCESS | 1668 | |4;prototype.html;265.22289691204764;303.7979743470592;307.38676340700295;307.486497549093;310.86843888246773;5069.797208111671;5165.405978312495;5236.502532259429;5237.790488380465;5241.292425522558;4584.587387135157;4899.41207055153;5135.8202462913805;5199.986002729469;5200.193969934657;3326.4677651575753;3326.5509156263956;3492.9476057859024;3657.3965295726175;3783.8675646352385;3889.436031775391;3916.5887581803927;3953.169834334779;3984.565556399694;4012.4382586437923;3045.299581096338;3338.21551935295;3426.605940316858;3773.9622603773887;3860.517435320585;3087.7838551301306;3682.318771027342;3823.388257878726;3835.884923452301;3960.960390396088;3047.9847600762077;3243.6594157613345;3406.386850366933;3429.1427143214196;3439.3465241604017;4966.602671786249;4996.925046124311;5017.498250174954;5024.648274620778;5030.849074527733;3676.9264614707554;4249.2563801334645;4519.909601807978;4530.343100250445;4534.569215924482;4431.512533621299;5376.193570964387;5377.489138531833;5395.3525576931015;5397.892042159135;3781.5651200112065;3798.5755341746844;3806;3807.067268519203;3824.4893267159596;4981.825636102755;5282.630215884889;5503.871706300199;5679.488846003838;5702.600817942745;3188.6811318867926;3744;3774.263838634204;3785.6782173515007;3795.7153213508545;3820.8089595520114;3831.5210598675167;3833.5208098987628;3840.5007349045004;3851.9229615408094;3912.471816304819;4030.4760381150263;4258.786245919898;4454.420925279757;4456.821727130943;5240.397354304304;5421.891562168735;5463.9180412293845;5467.835964921059;5491.807786727445;1092.6721983404946;1142.5886680794906;1255.4413286087786;1278.5461161287794;1282.8332316798942;716.5628966330535;769.7921561178563;777.782769965003;786.631261604815;807.4549678966674;3759.6240375962734;4843.467218605951;4910.355031064562;4958.405156261753;4981.476944920803;5096.9490305097215;5142.588592912558;5525.933107125504;5590.720463976785;5602.551795856321;4615.746133962664;4658.46270730658;4791.837387324349;4999.500049995044;5001.42483614389;2708.91873243803;2811.67665718445;2889.292123429731;2912;2935.9266018349713;4822.590079843182;5509;5662.433756624305;5749.712514374264;5759.136129580513;5352.57179425645;5671.489565939085;5674.035413979634;5678.716064196774;5729.5702822288495;5738.483536481736;5763;5764.423557644286;5772.249607550992;5786.309366144763;5565.638233514808;5571.498565129158;5575.135853942646;5593.105103183471;5598.272224610774;5123.743812809419;5274.784590472088;5426.891462170814;5492.423295553909;5535.557155427637;5718.771249149988;5721.942780572141;5723.451460094768;5737.340205876378;5767.769289228384;3402.1834759658;3421.367047096295;3673.687736542424;3681.5582130144553;3702.278055779097;5158.638895277445;5573.7213139343685;5637.295338082739;5648.830535083954;5658.9151162733415;5425.647332923348;5464.590155738256;5471.398146203994;5500.614956952975;5502.614816962774;4673.556012178838;5092.159793634009;5460.740622314601;5492.972535137299;5510.8071217507195;4335.978320108379;5343.492368225013;5389.326334208224;5392.5416339611575;5404.5406140476925;4881.584340541345;5039.319691841554;5129.307543481731;5266.368035835724;5318.255444237887;5345.625806193529;5369.758360873692;5377.946220537745;5392;5396.595255355785 15:27:22 INFO - PROCESS | 1668 | |5;yui.html;749.1646813802612;759.2521366454046;771.8815983426955;790.371654534645;824.2005254902745;14779.187144707037;16155.676886462294;16164.35342586298;16164.676706465878;16168.11075390853;14224.431022759103;14513.27433628317;14682.486112986047;14704.044237124579;14728.85271147291;727.2364017781335;874.6195404998814;879.6085741844881;882;882.7969566999594;684.9280825513324;715.1168307140703;716.183550752143;716.9749058782918;718.7951433841356;721.6391804097951;906.4923642760042;912.7353067610385;921.6912334367995;922.7693076730817;905.6060613633058;906.9047749986222;907.1019690506384;908.8682141089505;909.7771046093683;525.9868503287411;652.9804105876831;674.7537148940651;675.8479342148026;690.9965450172767;15002.474913378088;15081.396744130221;15087.094774313466;15102.697946041128;15104.169270690109;13448.193108413527;13917.373718182705;13940.093893896865;14011.439542418291;14111;11394.54421823126;11818.881811181864;11821.231619944676;12689.936550317281;13064.150830196008;3859.7105217108547;4498.865034048984;4515.887102822423;4849.781759820817;4864.927026094611;114531.42734286358;115602;116049.41975290069;116406;117185.48443546622;10691.35851848876;10797.784044319069;10799.514021869034;10823.350598964087;10924.781504369868;20505;20510.487237818874;20524.07641656129;20547.280845170648;20558.897205513877;7796.220377962159;7798.649060792278;7908.4859484134495;8123.18768123183;8128;4066.0038290618395;4313.698041137153;4552.339910712994;4631.421072365954;4824.276358546246;3552.786832790041;3692.7415080944074;3696.353138200804;3696.7042636589545;3699.3711069117776;681.5637991685326;686.9244383117853;689.9793006209821;705.4708968273794;707.4906067631297;553.8809156031473;652.1391762872962;658.4534836086037;679.5616827146562;703.1843062048;9035.864462033074;9315.86026209594;9318.390850561718;9484;9689.224862011022;648.1971706935647;671.3856821008723;674.2886255000923;691.5712258399724;711.4521818199958;4585.977070114628;4609.193391156534;4614.446266448048;4647.606482661365;4802.807887684523;95677;97767;136596;137174;137564;121805;121970;122258;124177;124643;68556;108079.99999999843;109681;109772;110399.44800275947;8962.955185224033;8986.55067246622;9072.138146876036;9121.361504694607;9374.484403357881;42753.5724642762;62940.37059629345;64469;64912;65002.674986626655;77780;84064.15935840564;85043.5747821257;85854;87099.56450217709;11636;11682.415879206175;11717.612088184294;11836.349000805038;12384.75230495367;13108.934455327662;13436;13612.86387136116;13767.586972390844;13805.51680691171;11923.224990375598;12213.572524961584;12222.449989750661;12587.496500139892;12610.084485177733;12335.753284934071;12457.937710311753;12637.17858339205;12729.936350318188;12763.170393924365;12007.75984480323;12071.517139314328;12290.016798656265;12414.565490207799;12603.432845521791;10246.282760206714;10508.789824203626;10517.263791534519;10523.579056837947;10948.835767463335;9201.401908875901;9250.907490925005;9637.759056023655;9717.319787614799;10381.234877053948 15:27:22 INFO - PROCESS | 1668 | __end_tp_report 15:27:22 INFO - PROCESS | 1668 | __start_cc_report 15:27:22 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1992 15:27:22 INFO - PROCESS | 1668 | __end_cc_report 15:27:22 INFO - PROCESS | 1668 | __startTimestamp1469312842305__endTimestamp 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | ------- Summary: start ------- 15:27:22 INFO - PROCESS | 1668 | Number of tests: 6 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5720.90 Median:5934.44 stddev:638.43 (10.8%) stddev-sans-first:611.90 15:27:22 INFO - PROCESS | 1668 | Values: 3316.9 3336.7 3370.7 3375.6 3416.1 5554.8 5621.8 5658.5 5716.2 5729.3 5641.9 5698.2 5707.6 5736.5 5737.2 5116.3 5154.6 5171.4 5178.7 5226.5 5232.0 5240.2 5242.1 5246.8 5251.1 5123.5 5132.2 5133.9 5157.2 5228.0 5212.2 5235.7 5236.6 5262.1 5299.1 5035.2 5050.3 5068.6 5165.0 5172.4 5599.4 5643.3 5661.0 5682.2 5715.1 6230.2 6235.9 6237.8 6245.5 6248.5 5605.5 6185.4 6187.0 6261.6 6296.6 5735.7 5782.0 5799.7 5830.9 5833.0 6221.9 6235.6 6236.6 6244.8 6248.3 5974.4 6047.6 6154.5 6180.9 6195.5 6108.9 6209.3 6239.5 6264.7 6285.0 6157.9 6202.3 6220.8 6232.5 6253.1 6090.1 6179.2 6257.5 6276.2 6281.7 4833.1 4854.3 4865.6 4890.2 4911.3 4427.8 4432.0 4432.4 4438.5 4473.2 5619.5 5691.2 5780.8 5785.3 5822.5 6170.8 6201.1 6268.0 6278.7 6298.9 4853.4 5774.9 5776.5 5785.7 5788.1 5301.7 5337.2 5414.2 5415.3 5421.8 6176.7 6182.1 6219.3 6237.5 6237.6 6284.2 6293.2 6294.8 6298.1 6314.9 6192.9 6236.6 6245.6 6253.1 6264.4 6077.2 6104.5 6131.7 6154.8 6208.4 5523.3 6190.4 6203.3 6215.3 6239.7 6101.2 6121.6 6132.0 6146.2 6148.9 6106.3 6108.2 6109.8 6110.4 6130.1 5771.9 5820.6 5827.6 5844.9 6008.7 6034.2 6049.9 6074.2 6074.9 6103.7 5901.3 5934.4 5956.4 5973.2 6029.9 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:69435.89 Median:19266.82 stddev:157042.80 (815.1%) stddev-sans-first:157397.97 15:27:22 INFO - PROCESS | 1668 | Values: 466.0 479.3 484.4 538.9 546.8 30674.7 32490.2 32511.8 32576.3 32593.0 33243.2 33854.7 42682.1 43399.6 43623.1 9740.1 9889.0 9907.4 9920.3 9922.0 9749.6 9844.5 9845.4 9861.2 9861.9 8760.0 8924.8 8942.0 9008.5 9049.8 8894.7 11740.5 12000.2 12007.6 12190.3 11870.1 11955.2 11957.3 11967.0 11995.3 39766.0 40077.0 40123.6 40134.8 40211.0 2965.6 3488.7 3499.6 3643.2 3777.5 31490.1 32073.7 32448.4 32957.2 33086.7 3251.1 3277.2 3279.7 3306.6 3416.9 424388.0 507530.0 508333.0 508865.0 508871.5 33374.3 33585.5 56965.3 57214.4 57340.4 34669.3 34933.1 34959.0 35034.0 35081.1 14374.6 14692.3 14714.7 14722.9 14745.0 12428.5 12489.8 12499.0 12515.6 12518.2 7760.0 7779.8 7784.4 7843.9 7889.3 3030.4 3053.1 3055.8 3202.9 3223.1 719.1 953.9 986.4 1060.8 1083.5 24406.1 29483.3 29696.0 30310.2 30586.9 1080.8 1092.2 1115.2 1120.3 1137.2 15073.8 15839.6 15989.4 16072.7 16160.3 695752.0 697446.5 701685.0 705680.0 706390.0 57616.7 58154.8 58166.0 58388.0 58401.0 1223.8 1273.0 1296.5 1370.4 1402.3 30462.7 31565.5 31746.9 32047.2 32279.0 48808.5 48872.0 49285.8 49287.0 49294.5 541239.0 541504.0 542593.0 543364.0 543711.0 23497.8 24768.6 26482.8 26686.6 26743.7 47941.5 48167.5 48175.8 49382.0 49470.0 40175.0 40400.6 40685.8 41369.8 41597.4 40389.6 40664.0 40731.8 41281.0 41643.8 38790.4 39381.0 39512.8 39818.0 40055.2 31161.7 32544.8 32765.0 34702.3 34886.3 22962.3 23979.6 24642.9 25096.6 25920.7 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:36444.86 Median:1882.16 stddev:99977.67 (5311.8%) stddev-sans-first:100221.35 15:27:22 INFO - PROCESS | 1668 | Values: 325.8 351.4 375.8 386.0 403.0 15750.3 17289.7 20420.7 21044.1 21068.9 20104.5 21104.6 21316.0 21717.5 21779.8 4922.6 5375.9 5564.2 5564.7 6165.1 6092.5 6096.4 6130.5 6130.5 6142.8 5829.9 6093.0 6110.3 6167.3 6168.5 5933.1 6111.7 6155.7 6161.3 6163.1 5993.2 6005.6 6016.3 6018.5 6030.8 13588.0 13835.2 21972.0 26286.0 26316.6 2042.1 2080.4 2152.7 2363.2 2853.1 9681.6 10128.8 10172.3 10591.9 10677.3 1848.6 1910.0 1928.8 1944.6 2127.4 102509.0 128447.4 131608.0 132272.3 136982.3 20865.9 20870.2 21427.7 21447.6 21729.8 35742.0 35768.6 35833.0 35921.8 36004.8 10740.8 11210.3 11247.5 11279.0 11370.2 9197.4 9257.3 9266.9 9338.9 9406.9 9025.1 9358.3 9789.0 9888.2 9922.1 3438.6 3542.2 3659.5 3759.7 3763.4 836.3 841.1 888.9 904.8 977.6 20901.8 21455.7 21489.8 21516.0 21521.0 795.9 943.4 976.2 986.3 1055.7 11142.2 11370.7 11421.6 11441.7 11499.6 585669.0 594900.3 601490.0 602176.0 608228.0 117279.5 125567.0 134116.0 134580.0 137879.0 831.8 951.4 973.3 980.7 985.0 17374.6 17799.1 18730.2 18953.0 19129.3 54576.7 55256.9 55379.9 55917.4 55981.7 75748.6 76045.0 76913.8 77854.6 78156.8 6874.8 8158.6 8210.8 8567.4 8607.5 12488.9 13159.9 13243.8 13359.0 13504.1 11328.9 11361.2 11378.7 11386.0 11559.5 11127.2 11205.7 11215.2 11225.7 11352.8 11595.2 11743.8 11790.3 11841.8 11947.6 7294.0 7479.5 7497.5 7793.1 8603.4 10656.6 11259.8 11568.1 11623.7 11700.8 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5649.43 Median:4783.72 stddev:3168.93 (66.2%) stddev-sans-first:3161.48 15:27:22 INFO - PROCESS | 1668 | Values: 1368.0 1470.8 1532.4 1706.2 1861.2 7732.7 8542.7 8776.6 8778.8 8897.1 7244.5 8712.6 8722.7 8723.1 8731.5 2373.6 2408.7 2409.7 2411.1 2411.7 2347.0 2377.6 2390.0 2396.1 2396.7 2364.6 2381.6 2391.9 2393.9 2394.5 2335.3 2365.7 2366.3 2368.0 2382.4 2273.7 2354.5 2356.8 2359.3 2369.4 7782.9 8035.4 8792.4 8807.6 8887.3 4816.3 4963.2 4969.5 4983.5 5154.6 3565.3 3578.6 3662.0 3667.1 3667.5 1056.5 1061.0 1062.9 1065.9 1077.2 11571.5 11701.1 11712.4 11718.6 11720.2 3682.0 4609.7 4751.1 4932.2 4943.8 8578.7 9993.0 10015.7 10017.5 10037.6 5389.5 5394.1 5397.2 5397.6 5419.9 4355.3 4396.8 4559.7 4893.7 4937.5 3919.8 3952.4 4016.0 4062.7 4066.5 1273.2 1273.3 1277.4 1280.7 1284.8 806.6 900.9 924.1 938.2 953.3 5699.5 5907.9 5939.8 5966.8 6052.9 437.8 442.6 455.1 461.4 462.1 3912.9 3959.9 3988.5 4037.3 4076.5 10036.5 10123.1 10432.8 10795.4 10883.6 10784.4 10795.9 10801.8 10805.0 10835.7 6916.8 7851.9 8167.3 8193.0 8263.0 5869.5 5956.9 5999.4 6076.7 6091.1 9888.6 10123.1 10202.2 10208.9 10211.0 8326.6 8674.7 10297.8 10345.4 10379.6 7059.2 7143.9 7243.4 7248.1 7251.9 7761.1 7834.3 7838.6 7932.6 8238.0 7563.4 7575.9 7619.3 7718.4 7742.4 7224.5 7417.0 7469.1 7555.9 7582.8 6743.3 7008.4 7071.8 7079.4 7107.1 5015.0 5016.2 5023.5 5051.9 5126.1 5061.3 5085.5 5109.9 5110.8 5235.0 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4392.30 Median:5021.07 stddev:1386.62 (27.6%) stddev-sans-first:1355.47 15:27:22 INFO - PROCESS | 1668 | Values: 265.2 303.8 307.4 307.5 310.9 5069.8 5165.4 5236.5 5237.8 5241.3 4584.6 4899.4 5135.8 5200.0 5200.2 3326.5 3326.6 3492.9 3657.4 3783.9 3889.4 3916.6 3953.2 3984.6 4012.4 3045.3 3338.2 3426.6 3774.0 3860.5 3087.8 3682.3 3823.4 3835.9 3961.0 3048.0 3243.7 3406.4 3429.1 3439.3 4966.6 4996.9 5017.5 5024.6 5030.8 3676.9 4249.3 4519.9 4530.3 4534.6 4431.5 5376.2 5377.5 5395.4 5397.9 3781.6 3798.6 3806.0 3807.1 3824.5 4981.8 5282.6 5503.9 5679.5 5702.6 3188.7 3744.0 3774.3 3785.7 3795.7 3820.8 3831.5 3833.5 3840.5 3851.9 3912.5 4030.5 4258.8 4454.4 4456.8 5240.4 5421.9 5463.9 5467.8 5491.8 1092.7 1142.6 1255.4 1278.5 1282.8 716.6 769.8 777.8 786.6 807.5 3759.6 4843.5 4910.4 4958.4 4981.5 5096.9 5142.6 5525.9 5590.7 5602.6 4615.7 4658.5 4791.8 4999.5 5001.4 2708.9 2811.7 2889.3 2912.0 2935.9 4822.6 5509.0 5662.4 5749.7 5759.1 5352.6 5671.5 5674.0 5678.7 5729.6 5738.5 5763.0 5764.4 5772.2 5786.3 5565.6 5571.5 5575.1 5593.1 5598.3 5123.7 5274.8 5426.9 5492.4 5535.6 5718.8 5721.9 5723.5 5737.3 5767.8 3402.2 3421.4 3673.7 3681.6 3702.3 5158.6 5573.7 5637.3 5648.8 5658.9 5425.6 5464.6 5471.4 5500.6 5502.6 4673.6 5092.2 5460.7 5493.0 5510.8 4336.0 5343.5 5389.3 5392.5 5404.5 4881.6 5039.3 5129.3 5266.4 5318.3 5345.6 5369.8 5377.9 5392.0 5396.6 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:23371.15 Median:23533.64 stddev:36644.15 (155.7%) stddev-sans-first:36707.58 15:27:22 INFO - PROCESS | 1668 | Values: 749.2 759.3 771.9 790.4 824.2 14779.2 16155.7 16164.4 16164.7 16168.1 14224.4 14513.3 14682.5 14704.0 14728.9 727.2 874.6 879.6 882.0 882.8 684.9 715.1 716.2 717.0 718.8 721.6 906.5 912.7 921.7 922.8 905.6 906.9 907.1 908.9 909.8 526.0 653.0 674.8 675.8 691.0 15002.5 15081.4 15087.1 15102.7 15104.2 13448.2 13917.4 13940.1 14011.4 14111.0 11394.5 11818.9 11821.2 12689.9 13064.2 3859.7 4498.9 4515.9 4849.8 4864.9 114531.4 115602.0 116049.4 116406.0 117185.5 10691.4 10797.8 10799.5 10823.4 10924.8 20505.0 20510.5 20524.1 20547.3 20558.9 7796.2 7798.6 7908.5 8123.2 8128.0 4066.0 4313.7 4552.3 4631.4 4824.3 3552.8 3692.7 3696.4 3696.7 3699.4 681.6 686.9 690.0 705.5 707.5 553.9 652.1 658.5 679.6 703.2 9035.9 9315.9 9318.4 9484.0 9689.2 648.2 671.4 674.3 691.6 711.5 4586.0 4609.2 4614.4 4647.6 4802.8 95677.0 97767.0 136596.0 137174.0 137564.0 121805.0 121970.0 122258.0 124177.0 124643.0 68556.0 108080.0 109681.0 109772.0 110399.4 8963.0 8986.6 9072.1 9121.4 9374.5 42753.6 62940.4 64469.0 64912.0 65002.7 77780.0 84064.2 85043.6 85854.0 87099.6 11636.0 11682.4 11717.6 11836.3 12384.8 13108.9 13436.0 13612.9 13767.6 13805.5 11923.2 12213.6 12222.4 12587.5 12610.1 12335.8 12457.9 12637.2 12729.9 12763.2 12007.8 12071.5 12290.0 12414.6 12603.4 10246.3 10508.8 10517.3 10523.6 10948.8 9201.4 9250.9 9637.8 9717.3 10381.2 15:27:22 INFO - PROCESS | 1668 | -------- Summary: end -------- 15:27:22 INFO - PROCESS | 1668 | 15:27:22 INFO - TEST-INFO | 1668: exit 0 15:27:22 INFO - TEST-OK | dromaeo_css | took 1117534ms 15:27:22 INFO - TEST-START | kraken 15:27:22 INFO - operating with platform_type : mac_ 15:27:22 INFO - Initialising browser for kraken test... 15:27:22 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpJ3r9YZ/profile http://localhost:49280/getInfo.html) 15:27:25 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 15:27:25 INFO - PROCESS | 1674 | colorDepth:24 15:27:25 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 15:27:25 INFO - PROCESS | 1674 | __metrics 15:27:25 INFO - PROCESS | 1674 | 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" 15:27:25 INFO - PROCESS | 1674 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:27:25 INFO - TEST-INFO | 1674: exit 0 15:27:25 INFO - Browser initialized. 15:27:25 INFO - Running cycle 1/1 for kraken test... 15:27:25 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/T/tmpJ3r9YZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:27:37 INFO - PROCESS | 1675 | RSS: Main: 244842496 15:27:37 INFO - PROCESS | 1675 | 15:27:40 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 15:27:41 INFO - PROCESS | 1675 | RSS: Main: 294305792 15:27:41 INFO - PROCESS | 1675 | 15:27:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 15:27:44 INFO - PROCESS | 1675 | RSS: Main: 304267264 15:27:44 INFO - PROCESS | 1675 | 15:27:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 15:27:49 INFO - PROCESS | 1675 | RSS: Main: 390025216 15:27:49 INFO - PROCESS | 1675 | 15:27:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 15:27:51 INFO - PROCESS | 1675 | RSS: Main: 292102144 15:27:51 INFO - PROCESS | 1675 | 15:27:53 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:27:54 INFO - PROCESS | 1675 | RSS: Main: 295927808 15:27:54 INFO - PROCESS | 1675 | 15:27:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 15:27:57 INFO - PROCESS | 1675 | RSS: Main: 335040512 15:27:57 INFO - PROCESS | 1675 | 15:28:00 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 15:28:01 INFO - PROCESS | 1675 | RSS: Main: 335917056 15:28:01 INFO - PROCESS | 1675 | 15:28:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 15:28:04 INFO - PROCESS | 1675 | RSS: Main: 335548416 15:28:04 INFO - PROCESS | 1675 | 15:28:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:28:07 INFO - PROCESS | 1675 | RSS: Main: 313159680 15:28:07 INFO - PROCESS | 1675 | 15:28:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:28:09 INFO - PROCESS | 1675 | RSS: Main: 319852544 15:28:09 INFO - PROCESS | 1675 | 15:28:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:28:12 INFO - PROCESS | 1675 | RSS: Main: 325128192 15:28:12 INFO - PROCESS | 1675 | 15:28:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:28:16 INFO - PROCESS | 1675 | RSS: Main: 337379328 15:28:16 INFO - PROCESS | 1675 | 15:28:19 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:28:19 INFO - PROCESS | 1675 | RSS: Main: 333996032 15:28:19 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 15:28:21 INFO - PROCESS | 1675 | RSS: Main: 377303040 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | __start_tp_report 15:28:21 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 15:28:21 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 15:28:21 INFO - PROCESS | 1675 | |i|pagename|runs| 15:28:21 INFO - PROCESS | 1675 | |0;ai-astar;101;109;112;109;113;111;111;112;110;111 15:28:21 INFO - PROCESS | 1675 | |1;audio-beat-detection;142;106;106;156;106;106;127;107;138;106 15:28:21 INFO - PROCESS | 1675 | |2;audio-dft;183;184;184;184;185;187;180;185;184;182 15:28:21 INFO - PROCESS | 1675 | |3;audio-fft;83;75;58;108;59;57;59;68;94;57 15:28:21 INFO - PROCESS | 1675 | |4;audio-oscillator;83;95;92;71;71;69;103;97;98;86 15:28:21 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;86;83;87;83;86;83;86;84;86;82 15:28:21 INFO - PROCESS | 1675 | |6;imaging-darkroom;93;93;93;93;91;92;93;93;93;92 15:28:21 INFO - PROCESS | 1675 | |7;imaging-desaturate;96;136;84;85;82;84;85;98;134;68 15:28:21 INFO - PROCESS | 1675 | |8;json-parse-financial;56;56;55;55;55;55;54;55;54;55 15:28:21 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;65;66;71;78;76;72;71;69;68;68 15:28:21 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;79;82;83;85;85;83;86;85;86;180 15:28:21 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;141;143;146;147;146;148;137;133;137;135 15:28:21 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;166;163;165;163;181;230;189;165;164;166 15:28:21 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;67;67;66;68;66;67;65;66;66 15:28:21 INFO - PROCESS | 1675 | __end_tp_report 15:28:21 INFO - PROCESS | 1675 | __start_cc_report 15:28:21 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2194 15:28:21 INFO - PROCESS | 1675 | __end_cc_report 15:28:21 INFO - PROCESS | 1675 | __startTimestamp1469312901675__endTimestamp 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | ------- Summary: start ------- 15:28:21 INFO - PROCESS | 1675 | Number of tests: 14 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.38 (3.0%) stddev-sans-first:1.36 15:28:21 INFO - PROCESS | 1675 | Values: 101.0 109.0 112.0 109.0 113.0 111.0 111.0 112.0 110.0 111.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:120.00 Median:117.00 stddev:19.15 (16.4%) stddev-sans-first:18.59 15:28:21 INFO - PROCESS | 1675 | Values: 142.0 106.0 106.0 156.0 106.0 106.0 127.0 107.0 138.0 106.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.87 (1.0%) stddev-sans-first:1.96 15:28:21 INFO - PROCESS | 1675 | Values: 183.0 184.0 184.0 184.0 185.0 187.0 180.0 185.0 184.0 182.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:71.80 Median:63.50 stddev:17.98 (28.3%) stddev-sans-first:18.61 15:28:21 INFO - PROCESS | 1675 | Values: 83.0 75.0 58.0 108.0 59.0 57.0 59.0 68.0 94.0 57.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:89.00 stddev:12.55 (14.1%) stddev-sans-first:13.24 15:28:21 INFO - PROCESS | 1675 | Values: 83.0 95.0 92.0 71.0 71.0 69.0 103.0 97.0 98.0 86.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:84.60 Median:86.00 stddev:1.78 (2.1%) stddev-sans-first:1.81 15:28:21 INFO - PROCESS | 1675 | Values: 86.0 83.0 87.0 83.0 86.0 83.0 86.0 84.0 86.0 82.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 15:28:21 INFO - PROCESS | 1675 | Values: 93.0 93.0 93.0 93.0 91.0 92.0 93.0 93.0 93.0 92.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:84.50 stddev:22.49 (26.6%) stddev-sans-first:23.85 15:28:21 INFO - PROCESS | 1675 | Values: 96.0 136.0 84.0 85.0 82.0 84.0 85.0 98.0 134.0 68.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.60 15:28:21 INFO - PROCESS | 1675 | Values: 56.0 56.0 55.0 55.0 55.0 55.0 54.0 55.0 54.0 55.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:4.14 (5.8%) stddev-sans-first:3.91 15:28:21 INFO - PROCESS | 1675 | Values: 65.0 66.0 71.0 78.0 76.0 72.0 71.0 69.0 68.0 68.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:93.40 Median:85.00 stddev:30.50 (35.9%) stddev-sans-first:31.91 15:28:21 INFO - PROCESS | 1675 | Values: 79.0 82.0 83.0 85.0 85.0 83.0 86.0 85.0 86.0 180.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:144.50 stddev:5.48 (3.8%) stddev-sans-first:5.81 15:28:21 INFO - PROCESS | 1675 | Values: 141.0 143.0 146.0 147.0 146.0 148.0 137.0 133.0 137.0 135.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.20 Median:166.00 stddev:21.15 (12.7%) stddev-sans-first:22.17 15:28:21 INFO - PROCESS | 1675 | Values: 166.0 163.0 165.0 163.0 181.0 230.0 189.0 165.0 164.0 166.0 15:28:21 INFO - PROCESS | 1675 | 15:28:21 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 15:28:21 INFO - PROCESS | 1675 | Values: 67.0 67.0 67.0 66.0 68.0 66.0 67.0 65.0 66.0 66.0 15:28:21 INFO - PROCESS | 1675 | -------- Summary: end -------- 15:28:21 INFO - PROCESS | 1675 | 15:28:22 INFO - TEST-INFO | 1675: exit 0 15:28:22 INFO - TEST-OK | kraken | took 59600ms 15:28:22 INFO - SUITE-END | took 1177s 15:28:22 INFO - Completed test suite (00:19:37) 15:28:22 INFO - javascript benchmark 15:28:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3316.9336613267737, 3336.683015113563, 3370.713489353405, 3375.5611770469836, 3416.145963509123, 5554.750036248369, 5621.77512899484, 5658.519025882805, 5716.1711551824965, 5729.3124825021005, 5641.8589535261635, 5698.173764804101, 5707.6290041147295, 5736.512396446296, 5737.168110623957, 5116.258142569325, 5154.6134039946965, 5171.405288391846, 5178.6892786432745, 5226.503482169207, 5232.032074066291, 5240.187770895498, 5242.056429842646, 5246.7638956247065, 5251.133562962106, 5123.462036486171, 5132.153194722866, 5133.897322053556, 5157.200633901752, 5227.980543793961, 5212.1660534314515, 5235.712035838027, 5236.581073514109, 5262.05283049051, 5299.125644268691, 5035.194368900995, 5050.267711181895, 5068.594512438996, 5165.0, 5172.405173405069, 5599.356074051496, 5643.266375371176, 5661.037623603997, 5682.17608446777, 5715.057015592422, 6230.190075290183, 6235.9376406235815, 6237.812865613993, 6245.531585131088, 6248.50011999039, 5605.523530499912, 6185.35053819347, 6187.041008643668, 6261.5929964552615, 6296.622202667854, 5735.713214339266, 5781.971090144607, 5799.710014499216, 5830.883382332329, 5833.0, 6221.9377806221355, 6235.625862448268, 6236.6258024518675, 6244.843878902973, 6248.28144763349, 5974.402559744078, 6047.637141771508, 6154.5384096192965, 6180.876382472416, 6195.504359651217, 6108.949222962717, 6209.316975132731, 6239.469645080128, 6264.655443950626, 6284.968575157095, 6157.876842463215, 6202.255729312511, 6220.782272620437, 6232.501399887998, 6253.093301471352, 6090.056041313604, 6179.196704428396, 6257.530685198628, 6276.246850377984, 6281.748730050746, 4833.130036593447, 4854.296127061556, 4865.635077369211, 4890.24201248807, 4911.312416261726, 4427.845025424094, 4431.958489754905, 4432.423784907941, 4438.511763705989, 4473.150101480708, 5619.466150715675, 5691.231683722644, 5780.826575202751, 5785.305763308345, 5822.505087067647, 6170.753169873245, 6201.10084037812, 6268.0, 6278.6860656966965, 6298.937010629927, 4853.4418541867435, 5774.855628609318, 5776.475096166769, 5785.71071446426, 5788.0739081746715, 5301.708406037628, 5337.199420087018, 5414.242006119146, 5415.268938693304, 5421.810236641778, 6176.722047507873, 6182.072689096672, 6219.253689557282, 6237.5009999200865, 6237.625742455467, 6284.183056202664, 6293.244810622755, 6294.8111556653375, 6298.08677741734, 6314.9264625014775, 6192.885280649346, 6236.594621349598, 6245.594036387621, 6253.124562561154, 6264.373562643699, 6077.24034495688, 6104.519788040188, 6131.693415329127, 6154.753809847735, 6208.379162083756, 5523.254360661259, 6190.41191086846, 6203.348648392034, 6215.254169499689, 6239.688015599293, 6101.19492530469, 6121.632702037892, 6131.96934015327, 6146.170267013896, 6148.907766383597, 6106.3089915548935, 6108.159949115366, 6109.829807539466, 6110.40687992437, 6130.092410195535, 5771.8557036074435, 5820.592558520862, 5827.621204621686, 5844.94155058461, 6008.669523176267, 6034.2155519782145, 6049.939500604937, 6074.179985701874, 6074.936886045013, 6103.664298463627, 5901.29184497863, 5934.43622855828, 5956.374580668968, 5973.1637570741, 6029.909551356821], "value": 5679.820294982733, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.9743714095724, 479.2786855782048, 484.4453101199125, 538.9407165211826, 546.7534022428862, 30674.693253067464, 32490.18774530635, 32511.837440812764, 32576.348473030583, 32593.0, 33243.168920776996, 33854.66145338546, 42682.14635707284, 43399.56600434003, 43623.12753744923, 9740.123388895016, 9888.950555247213, 9907.405555666683, 9920.255980801432, 9921.95039024804, 9749.561269742879, 9844.458554779485, 9845.40927544346, 9861.161801246903, 9861.852072218886, 8759.95620021899, 8924.821503569925, 8941.95529022354, 9008.54957252138, 9049.773755656128, 8894.688685895995, 11740.471678774476, 12000.21998570099, 12007.639770806802, 12190.329531875743, 11870.109741769402, 11955.222910510789, 11957.282563046245, 11967.042636589053, 11995.340256285901, 39766.0, 40077.0, 40123.59876401228, 40134.79932600318, 40210.994725131815, 2965.555166724987, 3488.7383446241633, 3499.6325385834375, 3643.1984963308296, 3777.5089238399105, 31490.055298340856, 32073.6792632073, 32448.351032979208, 32957.17607059819, 33086.6691333086, 3251.057193413907, 3277.2298509850166, 3279.6720327967014, 3306.57014588102, 3416.9145771355916, 424388.0, 507530.0, 508333.0, 508865.0, 508871.45564272313, 33374.332513350084, 33585.496217556756, 56965.29725567825, 57214.42785572091, 57340.426595734345, 34669.30661386809, 34933.1266718329, 34959.0, 35034.0, 35081.1229719254, 14374.568762937128, 14692.26538673062, 14714.705705885823, 14722.926385368219, 14745.0, 12428.502859885684, 12489.75020499585, 12499.0, 12515.56195533152, 12518.186317889307, 7760.0, 7779.7666070017995, 7784.416168787364, 7843.882341764764, 7889.329407000355, 3030.409070231328, 3053.0993356959752, 3055.801372910754, 3202.8718851246153, 3223.113643747988, 719.1154879498247, 953.9427634341962, 986.4475893499663, 1060.771934034171, 1083.534080345459, 24406.1457848978, 29483.262918426783, 29696.0, 30310.242243944078, 30586.929457469327, 1080.8216644253766, 1092.224520590374, 1115.2472081345059, 1120.3334016260312, 1137.2494153858413, 15073.849261507465, 15839.604009899846, 15989.440369587008, 16072.678546429006, 16160.27278772458, 695752.0, 697446.5383019259, 701685.0, 705680.0, 706390.0, 57616.71191644099, 58154.8369032617, 58166.0, 58388.0, 58401.0, 1223.816427535859, 1272.9872701273055, 1296.494367196794, 1370.369629970185, 1402.3268830961197, 30462.695373045986, 31565.52651710272, 31746.902893293296, 32047.198820029687, 32279.0, 48808.51191488039, 48872.0, 49285.76785580389, 49286.99999999857, 49294.50705492899, 541239.0, 541504.0, 542593.0, 543364.0, 543711.0, 23497.76502234956, 24768.628470572596, 26482.808273628118, 26686.599701004114, 26743.732562674126, 47941.5205847937, 48167.5183248163, 48175.759121204166, 49382.012359753324, 49470.0, 40175.0, 40400.59599404086, 40685.81974410743, 41369.79315103405, 41597.37603936004, 40389.59610403858, 40663.98340041523, 40731.796341018104, 41281.0, 41643.7917810409, 38790.4181437273, 39381.0, 39512.802435987636, 39818.0, 40055.1988960225, 31161.688383115878, 32544.83727581347, 32765.0, 34702.30595388129, 34886.30227395488, 22962.31113066611, 23979.640305395085, 24642.876785616674, 25096.62355064712, 25920.740792592587], "value": 18694.701736852712, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.7752151015799, 351.3692921206434, 375.8289978059983, 385.9556151042629, 402.9677625789937, 15750.291236894338, 17289.65420691587, 20420.693689594667, 21044.053017614224, 21068.89465552674, 20104.49738756532, 21104.57790844182, 21316.0, 21717.45706357342, 21779.782202177972, 4922.581580565657, 5375.892482150354, 5564.221009058735, 5564.749586268607, 6165.106059621352, 6092.51259899207, 6096.35988221237, 6130.478909292716, 6130.540209484284, 6142.754289828402, 5829.8834023319505, 6092.969535152318, 6110.297315808694, 6167.321594624587, 6168.537359698041, 5933.139694744238, 6111.663858487781, 6155.72299246535, 6161.260648722137, 6163.106349579286, 5993.160957465958, 6005.60963537373, 6016.278046634432, 6018.548608854309, 6030.849228769272, 13588.048836581444, 13835.239061851595, 21972.0, 26286.0, 26316.605250921253, 2042.1320938601036, 2080.4278823323707, 2152.655575107975, 2363.172889488665, 2853.069891240404, 9681.564329605184, 10128.797424051552, 10172.338797978182, 10591.894081059245, 10677.30597511167, 1848.5655870870335, 1909.9590723055967, 1928.845692344609, 1944.6013567218686, 2127.4362293992103, 102509.0, 128447.35776321152, 131608.0, 132272.33863830715, 136982.31508842492, 20865.89567052155, 20870.156356220283, 21427.67858482124, 21447.63809047738, 21729.782702173092, 35742.0, 35768.64231357653, 35833.0, 35921.8203908984, 36004.819975899954, 10740.83888741654, 11210.271332363533, 11247.493862776195, 11279.0, 11370.20408571392, 9197.356185067116, 9257.305702072372, 9266.907330926604, 9338.859917101248, 9406.905930940604, 9025.142611451902, 9358.298127640453, 9788.951055244679, 9888.20894328452, 9922.057404546556, 3438.5529881115294, 3542.1852973816167, 3659.5059666945153, 3759.684110561336, 3763.379042457964, 836.2599099796602, 841.0832192909636, 888.8977767556685, 904.7514430086438, 977.5503268496429, 20901.79098209029, 21455.67816482754, 21489.78510214909, 21516.031778570003, 21521.031553580127, 795.9005124359455, 943.3679434778714, 976.1848856205006, 986.2800155886243, 1055.714956961616, 11142.220044596963, 11370.658880233606, 11421.600243991421, 11441.713957151138, 11499.597514086967, 585669.0, 594900.2549872489, 601490.0, 602176.0, 608227.9588602028, 117279.48161555167, 125567.0, 134116.0, 134580.0, 137879.0, 831.7629475599546, 951.4338968313842, 973.2846357926918, 980.74010387246, 984.9901500984898, 17374.565635859206, 17799.110044497465, 18730.15714292833, 18952.95758733228, 19129.33047343336, 54576.72711636575, 55256.894862103334, 55379.89240215254, 55917.44082559122, 55981.720091399286, 75748.62125689337, 76045.0, 76913.84629230679, 77854.61072694827, 78156.82764758647, 6874.828129296808, 8158.551279679437, 8210.83578328442, 8567.443116197677, 8607.526586037577, 12488.937555312164, 13159.868401315864, 13243.801342980056, 13359.0, 13504.054716170167, 11328.94335528317, 11361.204715669825, 11378.658640240807, 11386.032187263843, 11559.479823407799, 11127.165462590176, 11205.66383008511, 11215.214934954474, 11225.66323010311, 11352.772944540899, 11595.246308989888, 11743.823842642538, 11790.292582445081, 11841.763164736829, 11947.641570752892, 7293.9635301823155, 7479.476436649382, 7497.512661677191, 7793.10379306387, 8603.397762156588, 10656.573737050741, 11259.76142624327, 11568.07455403549, 11623.6512904613, 11700.824487632863], "value": 10518.922434657961, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.0286996232674, 1470.8161479815026, 1532.4176812811131, 1706.1725063344284, 1861.1903821837502, 7732.690692372305, 8542.70100546481, 8776.64893404263, 8778.824423511534, 8897.065808090154, 7244.456665750064, 8712.564371781402, 8722.65109395625, 8723.08407617199, 8731.476111433318, 2373.5846226910307, 2408.6989126359204, 2409.6987876515436, 2411.0958390603523, 2411.746766589509, 2346.9765302347014, 2377.560151371993, 2390.008146619151, 2396.149366974725, 2396.7483414241515, 2364.586197415454, 2381.6189409694452, 2391.9355886630456, 2393.9162129325478, 2394.5091256292503, 2335.2643917166206, 2365.73976862545, 2366.3137690069857, 2368.04094341791, 2382.392489915078, 2273.7385200701965, 2354.5173239486035, 2356.8232382571205, 2359.3393849722106, 2369.4313364792324, 7782.883256751154, 8035.397345199075, 8792.42849214799, 8807.603657835349, 8887.289016878698, 4816.301636262758, 4963.156263435225, 4969.527894849984, 4983.526564976358, 5154.587632989352, 3565.2691198304287, 3578.6063533011607, 3662.047867554428, 3667.1015601177883, 3667.4865518827387, 1056.4928834159646, 1060.9522571484301, 1062.8990245926702, 1065.9360438373644, 1077.175960390302, 11571.479283432265, 11701.063914886789, 11712.414379281086, 11718.648440546796, 11720.179587428798, 3682.0, 4609.746463944515, 4751.1447939370555, 4932.1861892788, 4943.802247910044, 8578.699745508877, 9992.950035249778, 10015.69952901414, 10017.499125043865, 10037.598496060076, 5389.461053894579, 5394.136938089887, 5397.217403476552, 5397.568194544427, 5419.864503387368, 4355.303151495746, 4396.802143903532, 4559.749213793207, 4893.657443978958, 4937.530934561211, 3919.8432062717743, 3952.446657467957, 4015.99600099975, 4062.7156099073372, 4066.471358723347, 1273.2360583649736, 1273.2742336868082, 1277.4379273119798, 1280.7310464802497, 1284.8393950756156, 806.5644551942003, 900.8603666431713, 924.117467818232, 938.2447130060367, 953.3183773601908, 5699.544036477072, 5907.881842363128, 5939.762409503658, 5966.791162309299, 6052.878942421127, 437.8073647595048, 442.6436718441621, 455.14205722213865, 461.39557180094187, 462.06431975249717, 3912.902177445587, 3959.920801583952, 3988.4615576897327, 4037.2934736421007, 4076.490438695163, 10036.548355324028, 10123.088921997056, 10432.791344173074, 10795.352278863293, 10883.619073332395, 10784.406857622906, 10795.892041079645, 10801.78396432067, 10805.0, 10835.729106772236, 6916.827079323057, 7851.882221766678, 8167.264946154874, 8193.0, 8262.958685206655, 5869.4717475427415, 5956.851078723066, 5999.370066143167, 6076.665783381956, 6091.086337049478, 9888.555015024198, 10123.088921996761, 10202.234832387452, 10208.948955255177, 10211.029952154533, 8326.62530186131, 8674.696385626472, 10297.845532316871, 10345.431001295, 10379.584816607252, 7059.18819335783, 7143.9285607143265, 7243.420526357984, 7248.057752492142, 7251.854962900607, 7761.068671759425, 7834.255745704149, 7838.608069596611, 7932.6430310637315, 8238.0, 7563.394928405824, 7575.924240757522, 7619.314261716472, 7718.382529397747, 7742.38060955111, 7224.530405523624, 7416.961625372344, 7469.14104877946, 7555.9244407555225, 7582.772516824504, 6743.325667433218, 7008.439324854102, 7071.7878463646175, 7079.362857342864, 7107.111611048619, 5015.0, 5016.172331565251, 5023.522765337433, 5051.924221136759, 5126.128558145198, 5061.291419201241, 5085.46602606721, 5109.875827317984, 5110.8466745997675, 5234.979179060047], "value": 4478.802046283094, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.22289691204764, 303.7979743470592, 307.38676340700295, 307.486497549093, 310.86843888246773, 5069.797208111671, 5165.405978312495, 5236.502532259429, 5237.790488380465, 5241.292425522558, 4584.587387135157, 4899.41207055153, 5135.8202462913805, 5199.986002729469, 5200.193969934657, 3326.4677651575753, 3326.5509156263956, 3492.9476057859024, 3657.3965295726175, 3783.8675646352385, 3889.436031775391, 3916.5887581803927, 3953.169834334779, 3984.565556399694, 4012.4382586437923, 3045.299581096338, 3338.21551935295, 3426.605940316858, 3773.9622603773887, 3860.517435320585, 3087.7838551301306, 3682.318771027342, 3823.388257878726, 3835.884923452301, 3960.960390396088, 3047.9847600762077, 3243.6594157613345, 3406.386850366933, 3429.1427143214196, 3439.3465241604017, 4966.602671786249, 4996.925046124311, 5017.498250174954, 5024.648274620778, 5030.849074527733, 3676.9264614707554, 4249.2563801334645, 4519.909601807978, 4530.343100250445, 4534.569215924482, 4431.512533621299, 5376.193570964387, 5377.489138531833, 5395.3525576931015, 5397.892042159135, 3781.5651200112065, 3798.5755341746844, 3806.0, 3807.067268519203, 3824.4893267159596, 4981.825636102755, 5282.630215884889, 5503.871706300199, 5679.488846003838, 5702.600817942745, 3188.6811318867926, 3744.0, 3774.263838634204, 3785.6782173515007, 3795.7153213508545, 3820.8089595520114, 3831.5210598675167, 3833.5208098987628, 3840.5007349045004, 3851.9229615408094, 3912.471816304819, 4030.4760381150263, 4258.786245919898, 4454.420925279757, 4456.821727130943, 5240.397354304304, 5421.891562168735, 5463.9180412293845, 5467.835964921059, 5491.807786727445, 1092.6721983404946, 1142.5886680794906, 1255.4413286087786, 1278.5461161287794, 1282.8332316798942, 716.5628966330535, 769.7921561178563, 777.782769965003, 786.631261604815, 807.4549678966674, 3759.6240375962734, 4843.467218605951, 4910.355031064562, 4958.405156261753, 4981.476944920803, 5096.9490305097215, 5142.588592912558, 5525.933107125504, 5590.720463976785, 5602.551795856321, 4615.746133962664, 4658.46270730658, 4791.837387324349, 4999.500049995044, 5001.42483614389, 2708.91873243803, 2811.67665718445, 2889.292123429731, 2912.0, 2935.9266018349713, 4822.590079843182, 5509.0, 5662.433756624305, 5749.712514374264, 5759.136129580513, 5352.57179425645, 5671.489565939085, 5674.035413979634, 5678.716064196774, 5729.5702822288495, 5738.483536481736, 5763.0, 5764.423557644286, 5772.249607550992, 5786.309366144763, 5565.638233514808, 5571.498565129158, 5575.135853942646, 5593.105103183471, 5598.272224610774, 5123.743812809419, 5274.784590472088, 5426.891462170814, 5492.423295553909, 5535.557155427637, 5718.771249149988, 5721.942780572141, 5723.451460094768, 5737.340205876378, 5767.769289228384, 3402.1834759658, 3421.367047096295, 3673.687736542424, 3681.5582130144553, 3702.278055779097, 5158.638895277445, 5573.7213139343685, 5637.295338082739, 5648.830535083954, 5658.9151162733415, 5425.647332923348, 5464.590155738256, 5471.398146203994, 5500.614956952975, 5502.614816962774, 4673.556012178838, 5092.159793634009, 5460.740622314601, 5492.972535137299, 5510.8071217507195, 4335.978320108379, 5343.492368225013, 5389.326334208224, 5392.5416339611575, 5404.5406140476925, 4881.584340541345, 5039.319691841554, 5129.307543481731, 5266.368035835724, 5318.255444237887, 5345.625806193529, 5369.758360873692, 5377.946220537745, 5392.0, 5396.595255355785], "value": 3940.3644305033167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.1646813802612, 759.2521366454046, 771.8815983426955, 790.371654534645, 824.2005254902745, 14779.187144707037, 16155.676886462294, 16164.35342586298, 16164.676706465878, 16168.11075390853, 14224.431022759103, 14513.27433628317, 14682.486112986047, 14704.044237124579, 14728.85271147291, 727.2364017781335, 874.6195404998814, 879.6085741844881, 882.0, 882.7969566999594, 684.9280825513324, 715.1168307140703, 716.183550752143, 716.9749058782918, 718.7951433841356, 721.6391804097951, 906.4923642760042, 912.7353067610385, 921.6912334367995, 922.7693076730817, 905.6060613633058, 906.9047749986222, 907.1019690506384, 908.8682141089505, 909.7771046093683, 525.9868503287411, 652.9804105876831, 674.7537148940651, 675.8479342148026, 690.9965450172767, 15002.474913378088, 15081.396744130221, 15087.094774313466, 15102.697946041128, 15104.169270690109, 13448.193108413527, 13917.373718182705, 13940.093893896865, 14011.439542418291, 14111.0, 11394.54421823126, 11818.881811181864, 11821.231619944676, 12689.936550317281, 13064.150830196008, 3859.7105217108547, 4498.865034048984, 4515.887102822423, 4849.781759820817, 4864.927026094611, 114531.42734286358, 115602.0, 116049.41975290069, 116406.0, 117185.48443546622, 10691.35851848876, 10797.784044319069, 10799.514021869034, 10823.350598964087, 10924.781504369868, 20505.0, 20510.487237818874, 20524.07641656129, 20547.280845170648, 20558.897205513877, 7796.220377962159, 7798.649060792278, 7908.4859484134495, 8123.18768123183, 8128.0, 4066.0038290618395, 4313.698041137153, 4552.339910712994, 4631.421072365954, 4824.276358546246, 3552.786832790041, 3692.7415080944074, 3696.353138200804, 3696.7042636589545, 3699.3711069117776, 681.5637991685326, 686.9244383117853, 689.9793006209821, 705.4708968273794, 707.4906067631297, 553.8809156031473, 652.1391762872962, 658.4534836086037, 679.5616827146562, 703.1843062048, 9035.864462033074, 9315.86026209594, 9318.390850561718, 9484.0, 9689.224862011022, 648.1971706935647, 671.3856821008723, 674.2886255000923, 691.5712258399724, 711.4521818199958, 4585.977070114628, 4609.193391156534, 4614.446266448048, 4647.606482661365, 4802.807887684523, 95677.0, 97767.0, 136596.0, 137174.0, 137564.0, 121805.0, 121970.0, 122258.0, 124177.0, 124643.0, 68556.0, 108079.99999999843, 109681.0, 109772.0, 110399.44800275947, 8962.955185224033, 8986.55067246622, 9072.138146876036, 9121.361504694607, 9374.484403357881, 42753.5724642762, 62940.37059629345, 64469.0, 64912.0, 65002.674986626655, 77780.0, 84064.15935840564, 85043.5747821257, 85854.0, 87099.56450217709, 11636.0, 11682.415879206175, 11717.612088184294, 11836.349000805038, 12384.75230495367, 13108.934455327662, 13436.0, 13612.86387136116, 13767.586972390844, 13805.51680691171, 11923.224990375598, 12213.572524961584, 12222.449989750661, 12587.496500139892, 12610.084485177733, 12335.753284934071, 12457.937710311753, 12637.17858339205, 12729.936350318188, 12763.170393924365, 12007.75984480323, 12071.517139314328, 12290.016798656265, 12414.565490207799, 12603.432845521791, 10246.282760206714, 10508.789824203626, 10517.263791534519, 10523.579056837947, 10948.835767463335, 9201.401908875901, 9250.907490925005, 9637.759056023655, 9717.319787614799, 10381.234877053948], "value": 7641.652823861933, "unit": "score"}], "name": "dromaeo_css", "value": 7294.456708737305, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 109.0, 112.0, 109.0, 113.0, 111.0, 111.0, 112.0, 110.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 106.0, 106.0, 156.0, 106.0, 106.0, 127.0, 107.0, 138.0, 106.0], "value": 120.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 184.0, 184.0, 185.0, 187.0, 180.0, 185.0, 184.0, 182.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 58.0, 108.0, 59.0, 57.0, 59.0, 68.0, 94.0, 57.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 95.0, 92.0, 71.0, 71.0, 69.0, 103.0, 97.0, 98.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 83.0, 86.0, 83.0, 86.0, 84.0, 86.0, 82.0], "value": 84.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 91.0, 92.0, 93.0, 93.0, 93.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 136.0, 84.0, 85.0, 82.0, 84.0, 85.0, 98.0, 134.0, 68.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 71.0, 78.0, 76.0, 72.0, 71.0, 69.0, 68.0, 68.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 83.0, 85.0, 85.0, 83.0, 86.0, 85.0, 86.0, 180.0], "value": 93.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 146.0, 147.0, 146.0, 148.0, 137.0, 133.0, 137.0, 135.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 165.0, 163.0, 181.0, 230.0, 189.0, 165.0, 164.0, 166.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 68.0, 66.0, 67.0, 65.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1446.2, "alertThreshold": 2.0}]} 15:28:22 INFO - Return code: 0 15:28:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:28:22 INFO - # TBPL SUCCESS # 15:28:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:28:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:28:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:28:22 INFO - 2016-07-23 15:28:22.998 screenresolution[1679:13541] starting screenresolution argv=screenresolution get 15:28:23 INFO - 2016-07-23 15:28:23.001 screenresolution[1679:13541] Display 0: 1600x1200x32@60 15:28:23 INFO - 2016-07-23 15:28:23.007 screenresolution[1680:13544] starting screenresolution argv=screenresolution list 15:28:23 INFO - Available Modes on Display 0 15:28:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:28:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:28:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:28:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:28:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:28:23 INFO - Intel Iris: 15:28:23 INFO - Chipset Model: Intel Iris 15:28:23 INFO - Type: GPU 15:28:23 INFO - Bus: Built-In 15:28:23 INFO - VRAM (Dynamic, Max): 1536 MB 15:28:23 INFO - Vendor: Intel (0x8086) 15:28:23 INFO - Device ID: 0x0a2e 15:28:23 INFO - Revision ID: 0x0009 15:28:23 INFO - Displays: 15:28:23 INFO - Display: 15:28:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:28:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:28:23 INFO - Display Serial Number: 1600x1200 60 15:28:23 INFO - Main Display: Yes 15:28:23 INFO - Mirror: Off 15:28:23 INFO - Online: Yes 15:28:23 INFO - Rotation: Supported 15:28:23 INFO - Return code: 0 15:28:23 INFO - Running post-action listener: _resource_record_post_action 15:28:23 INFO - ##### 15:28:23 INFO - ##### Finished run-tests step (success) 15:28:23 INFO - ##### 15:28:23 INFO - Running post-run listener: _resource_record_post_run 15:28:23 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 32640512; Write bytes: 706098688; Read time: 317; Write time: 2213 15:28:23 INFO - TinderboxPrint: CPU usage
53.5% 15:28:23 INFO - TinderboxPrint: I/O read bytes / time
32,640,512 / 317 15:28:23 INFO - TinderboxPrint: I/O write bytes / time
706,098,688 / 2,213 15:28:23 INFO - TinderboxPrint: CPU idle
2,256.3 (46.5%) 15:28:23 INFO - TinderboxPrint: CPU system
968.4 (20.0%) 15:28:23 INFO - TinderboxPrint: CPU user
1,625.0 (33.5%) 15:28:23 INFO - TinderboxPrint: Swap in / out
435,421,184 / 0 15:28:23 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404830720; Write bytes: 399386624; Read time: 29748; Write time: 706 15:28:23 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29175296; Write bytes: 294850048; Read time: 288; Write time: 1473 15:28:23 INFO - Running post-run listener: _upload_blobber_files 15:28:23 INFO - Blob upload gear active. 15:28:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:28:23 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 15:28:23 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'] 15:28:23 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 15:28:24 INFO - (blobuploader) - INFO - Open directory for files ... 15:28:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:28:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:28:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:25 INFO - (blobuploader) - INFO - Done attempting. 15:28:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:28:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:28:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:26 INFO - (blobuploader) - INFO - Done attempting. 15:28:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:28:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:28:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:28:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:28:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:28:29 INFO - (blobuploader) - INFO - Done attempting. 15:28:29 INFO - (blobuploader) - INFO - Iteration through files over. 15:28:29 INFO - Return code: 0 15:28:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:28:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:28:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611162463579bffc4df420a806bb5e8e6eabe639b2b5f9135aa31bfe3ab5ec282f591baab0f7ce7f5877ddf871f4d4b8054060623ced679e1ca7eb5f5143abef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d1780eedd42495d29e3acb85c814da77d6ff5b65174feb88c0342d839359a83e93c046034c5e72aedbd39018dfc05aa4536e01881c8edfbdb0c30c50f1dfc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac0dc391037e873f78248b4cbf7678e593a512ffd8fbd48a3d479d9fdc74fbad5f2a3092bd3083b47744dde01d61683a45646ae3157ec4bb6767cdf2c3ab6a54"} 15:28:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:28:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:28:29 INFO - Contents: 15:28:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611162463579bffc4df420a806bb5e8e6eabe639b2b5f9135aa31bfe3ab5ec282f591baab0f7ce7f5877ddf871f4d4b8054060623ced679e1ca7eb5f5143abef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d1780eedd42495d29e3acb85c814da77d6ff5b65174feb88c0342d839359a83e93c046034c5e72aedbd39018dfc05aa4536e01881c8edfbdb0c30c50f1dfc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac0dc391037e873f78248b4cbf7678e593a512ffd8fbd48a3d479d9fdc74fbad5f2a3092bd3083b47744dde01d61683a45646ae3157ec4bb6767cdf2c3ab6a54"} 15:28:29 INFO - Running post-run listener: copy_logs_to_upload_dir 15:28:29 INFO - Copying logs to upload dir... 15:28:29 INFO - mkdir: /builds/slave/test/build/upload/logs 15:28:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.211996 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-23 15:28:29.623009) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-23 15:28:29.627019) ========= 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.r1L03ESksa/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.s52xjEebv7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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/611162463579bffc4df420a806bb5e8e6eabe639b2b5f9135aa31bfe3ab5ec282f591baab0f7ce7f5877ddf871f4d4b8054060623ced679e1ca7eb5f5143abef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d1780eedd42495d29e3acb85c814da77d6ff5b65174feb88c0342d839359a83e93c046034c5e72aedbd39018dfc05aa4536e01881c8edfbdb0c30c50f1dfc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac0dc391037e873f78248b4cbf7678e593a512ffd8fbd48a3d479d9fdc74fbad5f2a3092bd3083b47744dde01d61683a45646ae3157ec4bb6767cdf2c3ab6a54"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011239 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467450957/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/611162463579bffc4df420a806bb5e8e6eabe639b2b5f9135aa31bfe3ab5ec282f591baab0f7ce7f5877ddf871f4d4b8054060623ced679e1ca7eb5f5143abef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56d1780eedd42495d29e3acb85c814da77d6ff5b65174feb88c0342d839359a83e93c046034c5e72aedbd39018dfc05aa4536e01881c8edfbdb0c30c50f1dfc5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac0dc391037e873f78248b4cbf7678e593a512ffd8fbd48a3d479d9fdc74fbad5f2a3092bd3083b47744dde01d61683a45646ae3157ec4bb6767cdf2c3ab6a54"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-23 15:28:29.660202) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 15:28:29.660575) ========= 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.r1L03ESksa/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.s52xjEebv7/Listeners TMPDIR=/var/folders/4s/k3nh3fqd501g1sztrgh4qx9000000w/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.004970 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 15:28:29.709319) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-23 15:28:29.709676) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-23 15:28:29.710032) ========= ========= Total master_lag: 0.18 =========