builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0085 starttime: 1469429359.58 results: success (0) buildid: 20160704040157 builduid: 98039680a2564fa8930079b9dde77b17 revision: 99bab2d812fc9619c122fcdd84c6f085ced8c1e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:19.578558) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:19.578969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:19.579249) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:19.608699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:19.609103) ========= 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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-24 23:49:19-- 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% 141M=0s 2016-07-24 23:49:20 (141 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.390161 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:20.024808) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:20.025125) ========= 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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.028838 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:20.074194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-24 23:49:20.074549) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 99bab2d812fc9619c122fcdd84c6f085ced8c1e8 --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 99bab2d812fc9619c122fcdd84c6f085ced8c1e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.29uZLmL1Y6/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.SEtd8XlrIO/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-24 23:49:20,146 truncating revision to first 12 chars 2016-07-24 23:49:20,146 Setting DEBUG logging. 2016-07-24 23:49:20,147 attempt 1/10 2016-07-24 23:49:20,147 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/99bab2d812fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-24 23:49:21,284 unpacking tar archive at: mozilla-central-99bab2d812fc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.425246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-24 23:49:21.521703) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:21.522020) ========= script_repo_revision: 99bab2d812fc9619c122fcdd84c6f085ced8c1e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:21.522469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:21.522755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-24 23:49:21.545514) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-24 23:49:21.545932) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 23:49:21 INFO - MultiFileLogger online at 20160724 23:49:21 in /builds/slave/test 23:49:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 23:49:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:49:21 INFO - {'append_to_log': False, 23:49:21 INFO - 'base_work_dir': '/builds/slave/test', 23:49:21 INFO - 'blob_upload_branch': 'Firefox', 23:49:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:49:21 INFO - 'branch': 'Firefox', 23:49:21 INFO - 'buildbot_json_path': 'buildprops.json', 23:49:21 INFO - 'config_files': ('talos/mac_config.py',), 23:49:21 INFO - 'default_actions': ('clobber', 23:49:21 INFO - 'read-buildbot-config', 23:49:21 INFO - 'download-and-extract', 23:49:21 INFO - 'populate-webroot', 23:49:21 INFO - 'create-virtualenv', 23:49:21 INFO - 'install', 23:49:21 INFO - 'run-tests'), 23:49:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:49:21 INFO - 'download_minidump_stackwalk': True, 23:49:21 INFO - 'download_symbols': 'ondemand', 23:49:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:49:21 INFO - 'tooltool.py': '/tools/tooltool.py', 23:49:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:49:21 INFO - '/tools/misc-python/virtualenv.py')}, 23:49:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:49:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:49:21 INFO - 'installer_path': 'installer.exe', 23:49:21 INFO - 'log_level': 'info', 23:49:21 INFO - 'log_name': 'talos', 23:49:21 INFO - 'log_to_console': True, 23:49:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:49:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:49:21 INFO - 'opt_config_files': (), 23:49:21 INFO - 'pip_index': False, 23:49:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:49:21 INFO - 'cmd': ('bash', 23:49:21 INFO - '-c', 23:49:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:49:21 INFO - 'enabled': True, 23:49:21 INFO - 'halt_on_failure': False, 23:49:21 INFO - 'name': 'check_screen_resolution'},), 23:49:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:49:21 INFO - 'cmd': ('bash', 23:49:21 INFO - '-c', 23:49:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:49:21 INFO - 'enabled': True, 23:49:21 INFO - 'halt_on_failure': False, 23:49:21 INFO - 'name': 'check_screen_resolution'},), 23:49:21 INFO - 'run_cmd_checks_enabled': True, 23:49:21 INFO - 'sps_profile': False, 23:49:21 INFO - 'sps_profile_interval': 0, 23:49:21 INFO - 'suite': 'dromaeojs-e10s', 23:49:21 INFO - 'system_bits': '32', 23:49:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:49:21 INFO - 'title': 't-yosemite-r7-0085', 23:49:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:49:21 INFO - 'use_talos_json': True, 23:49:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:49:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:49:21 INFO - 'work_dir': 'build'} 23:49:21 INFO - ##### 23:49:21 INFO - ##### Running clobber step. 23:49:21 INFO - ##### 23:49:21 INFO - Running pre-action listener: _resource_record_pre_action 23:49:21 INFO - Running main action method: clobber 23:49:21 INFO - rmtree: /builds/slave/test/build 23:49:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:49:23 INFO - Running post-action listener: _resource_record_post_action 23:49:23 INFO - ##### 23:49:23 INFO - ##### Finished clobber step (success) 23:49:23 INFO - ##### 23:49:23 INFO - ##### 23:49:23 INFO - ##### Running read-buildbot-config step. 23:49:23 INFO - ##### 23:49:23 INFO - Running pre-action listener: _resource_record_pre_action 23:49:23 INFO - Running main action method: read_buildbot_config 23:49:23 INFO - Using buildbot properties: 23:49:23 INFO - { 23:49:23 INFO - "project": "", 23:49:23 INFO - "product": "firefox", 23:49:23 INFO - "script_repo_revision": "production", 23:49:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:49:23 INFO - "repository": "", 23:49:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:49:23 INFO - "buildid": "20160704040157", 23:49:23 INFO - "pgo_build": "False", 23:49:23 INFO - "basedir": "/builds/slave/test", 23:49:23 INFO - "buildnumber": 75, 23:49:23 INFO - "slavename": "t-yosemite-r7-0085", 23:49:23 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 23:49:23 INFO - "platform": "yosemite_r7", 23:49:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:49:23 INFO - "branch": "mozilla-central", 23:49:23 INFO - "repo_path": "mozilla-central", 23:49:23 INFO - "revision": "99bab2d812fc9619c122fcdd84c6f085ced8c1e8", 23:49:23 INFO - "stage_platform": "macosx64", 23:49:23 INFO - "builduid": "98039680a2564fa8930079b9dde77b17", 23:49:23 INFO - "slavebuilddir": "test" 23:49:23 INFO - } 23:49:23 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg. 23:49:23 INFO - Running post-action listener: _resource_record_post_action 23:49:23 INFO - ##### 23:49:23 INFO - ##### Finished read-buildbot-config step (success) 23:49:23 INFO - ##### 23:49:23 INFO - ##### 23:49:23 INFO - ##### Running download-and-extract step. 23:49:23 INFO - ##### 23:49:23 INFO - Running pre-action listener: _resource_record_pre_action 23:49:23 INFO - Running main action method: download_and_extract 23:49:23 INFO - mkdir: /builds/slave/test/build/tests 23:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.test_packages.json 23:49:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/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 23:49:24 INFO - Downloaded 1199 bytes. 23:49:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 23:49:24 INFO - Using the following test package requirements: 23:49:24 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:49:24 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 23:49:24 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 23:49:24 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'jsshell-mac.zip'], 23:49:24 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 23:49:24 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 23:49:24 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 23:49:24 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 23:49:24 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 23:49:24 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 23:49:24 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 23:49:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.common.tests.zip 23:49:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:49:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/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 23:49:24 INFO - Downloaded 18791388 bytes. 23:49:24 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'] 23:49:24 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 23:49:27 INFO - Return code: 0 23:49:27 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 23:49: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')]} 23:49:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.common.tests.zip 23:49:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 23:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/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 23:49:28 INFO - Downloaded 18791388 bytes. 23:49:28 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'] 23:49:28 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 23:49:31 INFO - Return code: 0 23:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.talos.tests.zip 23:49:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 23:49:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/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 23:49:35 INFO - Downloaded 14405327 bytes. 23:49: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'] 23:49: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 23:49:36 INFO - Return code: 0 23:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg 23:49:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 23:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 23:49:39 INFO - Downloaded 112452261 bytes. 23:49:39 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg 23:49:39 INFO - mkdir: /builds/slave/test/properties 23:49:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:49:39 INFO - Writing to file /builds/slave/test/properties/build_url 23:49:39 INFO - Contents: 23:49:39 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg 23:49:39 INFO - Running post-action listener: _resource_record_post_action 23:49:39 INFO - Running post-action listener: set_extra_try_arguments 23:49:39 INFO - ##### 23:49:39 INFO - ##### Finished download-and-extract step (success) 23:49:39 INFO - ##### 23:49:39 INFO - ##### 23:49:39 INFO - ##### Running populate-webroot step. 23:49:39 INFO - ##### 23:49:39 INFO - Running pre-action listener: _resource_record_pre_action 23:49:39 INFO - Running main action method: populate_webroot 23:49:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:49:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:49:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:49:39 INFO - '--tppagecycles', 23:49:39 INFO - '7'], 23:49:39 INFO - 'tests': ['tsvgm']}}, 23:49:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:49:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:49:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:49:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:49:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:49:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:49:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['damp', 'tps']}, 23:49:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'tests': ['damp', 'tps']}, 23:49:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['dromaeo_dom']}, 23:49:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:49:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['basic_compositor_video']}, 23:49:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:49:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['a11yr', 23:49:39 INFO - 'ts_paint', 23:49:39 INFO - 'tpaint', 23:49:39 INFO - 'sessionrestore', 23:49:39 INFO - 'sessionrestore_no_auto_restore', 23:49:39 INFO - 'tabpaint']}, 23:49:39 INFO - 'other-e10s': {'tests': ['a11yr', 23:49:39 INFO - 'ts_paint', 23:49:39 INFO - 'tpaint', 23:49:39 INFO - 'sessionrestore', 23:49:39 INFO - 'sessionrestore_no_auto_restore', 23:49:39 INFO - 'tabpaint']}, 23:49:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:49:39 INFO - 'ts_paint', 23:49:39 INFO - 'tpaint', 23:49:39 INFO - 'sessionrestore', 23:49:39 INFO - 'sessionrestore_no_auto_restore', 23:49:39 INFO - 'tabpaint']}, 23:49:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:49:39 INFO - 'ts_paint', 23:49:39 INFO - 'tpaint', 23:49:39 INFO - 'sessionrestore', 23:49:39 INFO - 'sessionrestore_no_auto_restore', 23:49:39 INFO - 'tabpaint']}, 23:49:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['a11yr', 23:49:39 INFO - 'ts_paint', 23:49:39 INFO - 'tpaint', 23:49:39 INFO - 'sessionrestore', 23:49:39 INFO - 'sessionrestore_no_auto_restore', 23:49:39 INFO - 'tabpaint']}, 23:49:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['a11yr', 23:49:39 INFO - 'ts_paint', 23:49:39 INFO - 'tpaint', 23:49:39 INFO - 'sessionrestore', 23:49:39 INFO - 'sessionrestore_no_auto_restore', 23:49:39 INFO - 'tabpaint']}, 23:49:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['tsvgx', 23:49:39 INFO - 'tsvgr_opacity', 23:49:39 INFO - 'tart', 23:49:39 INFO - 'tscrollx', 23:49:39 INFO - 'cart']}, 23:49:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:49:39 INFO - 'tsvgr_opacity', 23:49:39 INFO - 'tart', 23:49:39 INFO - 'tscrollx', 23:49:39 INFO - 'cart']}, 23:49:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'talos_options': ['--disable-e10s'], 23:49:39 INFO - 'tests': ['tp5o']}, 23:49:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'tests': ['tp5o']}, 23:49:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'talos_options': ['--disable-e10s', 23:49:39 INFO - '--xperf_path', 23:49:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:49:39 INFO - 'tests': ['tp5n']}, 23:49:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:39 INFO - 'talos_options': ['--xperf_path', 23:49:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:49:39 INFO - 'tests': ['tp5n']}}, 23:49:39 INFO - 'talos.zip': {'path': '', 23:49:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:49:39 INFO - Running post-action listener: _resource_record_post_action 23:49:39 INFO - ##### 23:49:39 INFO - ##### Finished populate-webroot step (success) 23:49:39 INFO - ##### 23:49:39 INFO - ##### 23:49:39 INFO - ##### Running create-virtualenv step. 23:49:39 INFO - ##### 23:49:39 INFO - Running pre-action listener: _resource_record_pre_action 23:49:39 INFO - Running main action method: create_virtualenv 23:49:39 INFO - Creating virtualenv /builds/slave/test/build/venv 23:49: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 23:49:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:49:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:49:39 INFO - Using real prefix '/tools/python27' 23:49:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:49:40 INFO - Installing distribute.............................................................................................................................................................................................done. 23:49:43 INFO - Installing pip.................done. 23:49:43 INFO - Return code: 0 23:49:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:49: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')]} 23:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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')]} 23:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:49: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 23:49: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 23:49:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:49:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:43 INFO - 'HOME': '/Users/cltbld', 23:49:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:43 INFO - 'LOGNAME': 'cltbld', 23:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:43 INFO - 'MOZ_NO_REMOTE': '1', 23:49:43 INFO - 'NO_EM_RESTART': '1', 23:49:43 INFO - 'PAGER': '/bin/cat', 23:49:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:43 INFO - 'PWD': '/builds/slave/test', 23:49:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:43 INFO - 'SHELL': '/bin/bash', 23:49:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:49:43 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:49:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:43 INFO - 'USER': 'cltbld', 23:49:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:43 INFO - 'XPC_FLAGS': '0x0', 23:49:43 INFO - 'XPC_SERVICE_NAME': '0', 23:49:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49: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 23:49:43 INFO - Cleaning up... 23:49:43 INFO - Return code: 0 23:49:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:49: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')]} 23:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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')]} 23:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:49: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 23:49: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 23:49:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:49:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:43 INFO - 'HOME': '/Users/cltbld', 23:49:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:43 INFO - 'LOGNAME': 'cltbld', 23:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:43 INFO - 'MOZ_NO_REMOTE': '1', 23:49:43 INFO - 'NO_EM_RESTART': '1', 23:49:43 INFO - 'PAGER': '/bin/cat', 23:49:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:43 INFO - 'PWD': '/builds/slave/test', 23:49:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:43 INFO - 'SHELL': '/bin/bash', 23:49:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:49:43 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:49:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:43 INFO - 'USER': 'cltbld', 23:49:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:43 INFO - 'XPC_FLAGS': '0x0', 23:49:43 INFO - 'XPC_SERVICE_NAME': '0', 23:49:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:43 INFO - Downloading/unpacking psutil>=3.1.1 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:49:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:49:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:49:46 INFO - Installing collected packages: psutil 23:49:46 INFO - Running setup.py install for psutil 23:49:46 INFO - building 'psutil._psutil_osx' extension 23:49:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:49: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 23:49: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 23:49:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 23:49:47 INFO - building 'psutil._psutil_posix' extension 23:49:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 23:49:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:49:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:49:47 INFO - ^ 23:49:47 INFO - 1 warning generated. 23:49:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 23:49:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:49:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:49:47 INFO - Successfully installed psutil 23:49:47 INFO - Cleaning up... 23:49:48 INFO - Return code: 0 23:49:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:49: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')]} 23:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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')]} 23:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:49: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 23:49: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 23:49:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:49:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:48 INFO - 'HOME': '/Users/cltbld', 23:49:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:48 INFO - 'LOGNAME': 'cltbld', 23:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:48 INFO - 'MOZ_NO_REMOTE': '1', 23:49:48 INFO - 'NO_EM_RESTART': '1', 23:49:48 INFO - 'PAGER': '/bin/cat', 23:49:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:48 INFO - 'PWD': '/builds/slave/test', 23:49:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:48 INFO - 'SHELL': '/bin/bash', 23:49:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:49:48 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:49:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:48 INFO - 'USER': 'cltbld', 23:49:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:48 INFO - 'XPC_FLAGS': '0x0', 23:49:48 INFO - 'XPC_SERVICE_NAME': '0', 23:49:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:49:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:49:50 INFO - Installing collected packages: mozsystemmonitor 23:49:50 INFO - Running setup.py install for mozsystemmonitor 23:49:51 INFO - Successfully installed mozsystemmonitor 23:49:51 INFO - Cleaning up... 23:49:51 INFO - Return code: 0 23:49:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:49: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')]} 23:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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')]} 23:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49: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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:49: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 23:49: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 23:49:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:49:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:51 INFO - 'HOME': '/Users/cltbld', 23:49:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:51 INFO - 'LOGNAME': 'cltbld', 23:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:51 INFO - 'MOZ_NO_REMOTE': '1', 23:49:51 INFO - 'NO_EM_RESTART': '1', 23:49:51 INFO - 'PAGER': '/bin/cat', 23:49:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:51 INFO - 'PWD': '/builds/slave/test', 23:49:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:51 INFO - 'SHELL': '/bin/bash', 23:49:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:49:51 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:49:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:51 INFO - 'USER': 'cltbld', 23:49:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:51 INFO - 'XPC_FLAGS': '0x0', 23:49:51 INFO - 'XPC_SERVICE_NAME': '0', 23:49:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:51 INFO - Downloading/unpacking jsonschema==2.5.1 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:49:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:54 INFO - Downloading functools32-3.2.3-2.tar.gz 23:49:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:49:54 INFO - warning: no files found matching '*.txt' 23:49:54 INFO - no previously-included directories found matching 'build' 23:49:54 INFO - no previously-included directories found matching 'dist' 23:49:54 INFO - no previously-included directories found matching '.git*' 23:49:54 INFO - Installing collected packages: jsonschema, functools32 23:49:54 INFO - Running setup.py install for functools32 23:49:54 INFO - warning: no files found matching '*.txt' 23:49:54 INFO - no previously-included directories found matching 'build' 23:49:54 INFO - no previously-included directories found matching 'dist' 23:49:54 INFO - no previously-included directories found matching '.git*' 23:49:54 INFO - Successfully installed jsonschema functools32 23:49:54 INFO - Cleaning up... 23:49:54 INFO - Return code: 0 23:49:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:54 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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:49:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:49:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:49:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:54 INFO - 'HOME': '/Users/cltbld', 23:49:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:54 INFO - 'LOGNAME': 'cltbld', 23:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:54 INFO - 'MOZ_NO_REMOTE': '1', 23:49:54 INFO - 'NO_EM_RESTART': '1', 23:49:54 INFO - 'PAGER': '/bin/cat', 23:49:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:54 INFO - 'PWD': '/builds/slave/test', 23:49:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:54 INFO - 'SHELL': '/bin/bash', 23:49:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:49:54 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:49:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:54 INFO - 'USER': 'cltbld', 23:49:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:54 INFO - 'XPC_FLAGS': '0x0', 23:49:54 INFO - 'XPC_SERVICE_NAME': '0', 23:49:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:54 INFO - Downloading/unpacking blobuploader==1.2.4 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49:57 INFO - Downloading blobuploader-1.2.4.tar.gz 23:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:49:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:49:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49:58 INFO - Downloading docopt-0.6.1.tar.gz 23:49:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:49:58 INFO - Installing collected packages: blobuploader, requests, docopt 23:49:58 INFO - Running setup.py install for blobuploader 23:49:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:49:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:49:58 INFO - Running setup.py install for requests 23:49:59 INFO - Running setup.py install for docopt 23:49:59 INFO - Successfully installed blobuploader requests docopt 23:49:59 INFO - Cleaning up... 23:49:59 INFO - Return code: 0 23:49:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:59 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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:49:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:49:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:49:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:49:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:59 INFO - 'HOME': '/Users/cltbld', 23:49:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:59 INFO - 'LOGNAME': 'cltbld', 23:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:59 INFO - 'MOZ_NO_REMOTE': '1', 23:49:59 INFO - 'NO_EM_RESTART': '1', 23:49:59 INFO - 'PAGER': '/bin/cat', 23:49:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:59 INFO - 'PWD': '/builds/slave/test', 23:49:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:59 INFO - 'SHELL': '/bin/bash', 23:49:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:49:59 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:49:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:59 INFO - 'USER': 'cltbld', 23:49:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:59 INFO - 'XPC_FLAGS': '0x0', 23:49:59 INFO - 'XPC_SERVICE_NAME': '0', 23:49:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:49:59 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-X2YCqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:49:59 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-JMHKEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-OwDdVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-l_qJIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-4Uhcb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-_VfDSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ui_SQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-06zL0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-QofrcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-HF5vY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:50:00 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-7x6_XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:50:01 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-kL_FAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:50:01 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-nu4FM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:50:01 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Bg9oKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:50:01 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-jH6OJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:50:01 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-I6Abun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:50:01 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-NAU_il-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:50:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:50:01 INFO - Running setup.py install for manifestparser 23:50:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:50:01 INFO - Running setup.py install for mozcrash 23:50:01 INFO - Running setup.py install for mozdebug 23:50:02 INFO - Running setup.py install for mozdevice 23:50:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Running setup.py install for mozfile 23:50:02 INFO - Running setup.py install for mozhttpd 23:50:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Running setup.py install for mozinfo 23:50:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Running setup.py install for mozInstall 23:50:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:50:02 INFO - Running setup.py install for mozleak 23:50:02 INFO - Running setup.py install for mozlog 23:50:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:50:03 INFO - Running setup.py install for moznetwork 23:50:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:50:03 INFO - Running setup.py install for mozprocess 23:50:03 INFO - Running setup.py install for mozprofile 23:50:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:50:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:50:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:50:03 INFO - Running setup.py install for mozrunner 23:50:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:50:03 INFO - Running setup.py install for mozscreenshot 23:50:03 INFO - Running setup.py install for moztest 23:50:04 INFO - Running setup.py install for mozversion 23:50:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:50:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:50:04 INFO - Cleaning up... 23:50:04 INFO - Return code: 0 23:50:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:50: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')]} 23:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:50: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')]} 23:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:50: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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:50: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 23:50: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 23:50:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:50:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:50:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:50:04 INFO - 'HOME': '/Users/cltbld', 23:50:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:50:04 INFO - 'LOGNAME': 'cltbld', 23:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:50:04 INFO - 'MOZ_NO_REMOTE': '1', 23:50:04 INFO - 'NO_EM_RESTART': '1', 23:50:04 INFO - 'PAGER': '/bin/cat', 23:50:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:50:04 INFO - 'PWD': '/builds/slave/test', 23:50:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:50:04 INFO - 'SHELL': '/bin/bash', 23:50:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:50:04 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:50:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:50:04 INFO - 'USER': 'cltbld', 23:50:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:50:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:50:04 INFO - 'XPC_FLAGS': '0x0', 23:50:04 INFO - 'XPC_SERVICE_NAME': '0', 23:50:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:50:04 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-oArQC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:50:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:50:04 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-VwmeLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:50:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:50:04 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-8lTnyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:50:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:50:04 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Fsdc3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:50:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:50:04 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-YSVtXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:50: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)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-fyA2Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:50: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)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-IIK4Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:50: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)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-DTQ1V7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:50: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)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-qQ8ale-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:50: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)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Wb0vSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:50:05 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)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-ZvRf7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Al_71G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-mKdOpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:50:05 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Xrgcca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:50: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)) 23:50:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:50:06 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-JPpDXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:50: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)) 23:50:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:50:06 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-fFoMMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:50: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)) 23:50:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:50:06 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-o0OJ0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:50: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 23:50: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 23:50: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 23:50: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 23:50: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 23:50: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 23:50:08 INFO - Downloading blessings-1.6.tar.gz 23:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:50:08 INFO - Installing collected packages: blessings 23:50:08 INFO - Running setup.py install for blessings 23:50:09 INFO - Successfully installed blessings 23:50:09 INFO - Cleaning up... 23:50:09 INFO - Return code: 0 23:50:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:50:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:50:09 INFO - Reading from file tmpfile_stdout 23:50:09 INFO - Current package versions: 23:50:09 INFO - blessings == 1.6 23:50:09 INFO - blobuploader == 1.2.4 23:50:09 INFO - docopt == 0.6.1 23:50:09 INFO - functools32 == 3.2.3-2 23:50:09 INFO - jsonschema == 2.5.1 23:50:09 INFO - manifestparser == 1.1 23:50:09 INFO - mozInstall == 1.12 23:50:09 INFO - mozcrash == 0.17 23:50:09 INFO - mozdebug == 0.1 23:50:09 INFO - mozdevice == 0.48 23:50:09 INFO - mozfile == 1.2 23:50:09 INFO - mozhttpd == 0.7 23:50:09 INFO - mozinfo == 0.9 23:50:09 INFO - mozleak == 0.1 23:50:09 INFO - mozlog == 3.2 23:50:09 INFO - moznetwork == 0.27 23:50:09 INFO - mozprocess == 0.22 23:50:09 INFO - mozprofile == 0.28 23:50:09 INFO - mozrunner == 6.11 23:50:09 INFO - mozscreenshot == 0.1 23:50:09 INFO - mozsystemmonitor == 0.3 23:50:09 INFO - moztest == 0.7 23:50:09 INFO - mozversion == 1.4 23:50:09 INFO - psutil == 3.1.1 23:50:09 INFO - requests == 1.2.3 23:50:09 INFO - wsgiref == 0.1.2 23:50:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:50: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')]} 23:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:50:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:50: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')]} 23:50:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:50:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:50: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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:50: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 23:50: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 23:50:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:50:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:50:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:50:09 INFO - 'HOME': '/Users/cltbld', 23:50:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:50:09 INFO - 'LOGNAME': 'cltbld', 23:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:50:09 INFO - 'MOZ_NO_REMOTE': '1', 23:50:09 INFO - 'NO_EM_RESTART': '1', 23:50:09 INFO - 'PAGER': '/bin/cat', 23:50:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:50:09 INFO - 'PWD': '/builds/slave/test', 23:50:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:50:09 INFO - 'SHELL': '/bin/bash', 23:50:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:50:09 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:50:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:50:09 INFO - 'USER': 'cltbld', 23:50:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:50:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:50:09 INFO - 'XPC_FLAGS': '0x0', 23:50:09 INFO - 'XPC_SERVICE_NAME': '0', 23:50:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50: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)) 23:50:10 INFO - Cleaning up... 23:50:10 INFO - Return code: 0 23:50:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:50:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:50:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:50: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 0x103e91a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f26618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee02daefe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, '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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 23:50: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 23:50: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 23:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:50:10 INFO - 'HOME': '/Users/cltbld', 23:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:50:10 INFO - 'LOGNAME': 'cltbld', 23:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:50:10 INFO - 'MOZ_NO_REMOTE': '1', 23:50:10 INFO - 'NO_EM_RESTART': '1', 23:50:10 INFO - 'PAGER': '/bin/cat', 23:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:50:10 INFO - 'PWD': '/builds/slave/test', 23:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:50:10 INFO - 'SHELL': '/bin/bash', 23:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:50:10 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:50:10 INFO - 'USER': 'cltbld', 23:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:50:10 INFO - 'XPC_FLAGS': '0x0', 23:50:10 INFO - 'XPC_SERVICE_NAME': '0', 23:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:50:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:50:10 INFO - Cleaning up... 23:50:10 INFO - Return code: 0 23:50:10 INFO - Running post-action listener: _resource_record_post_action 23:50:10 INFO - Running post-action listener: _start_resource_monitoring 23:50:10 INFO - Starting resource monitoring. 23:50:10 INFO - ##### 23:50:10 INFO - ##### Finished create-virtualenv step (success) 23:50:10 INFO - ##### 23:50:10 INFO - ##### 23:50:10 INFO - ##### Running install step. 23:50:10 INFO - ##### 23:50:10 INFO - Running pre-action listener: _resource_record_pre_action 23:50:10 INFO - Running main action method: install 23:50:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:50:10 INFO - Reading from file tmpfile_stdout 23:50:10 INFO - Detecting whether we're running mozinstall >=1.0... 23:50:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:50:10 INFO - Reading from file tmpfile_stdout 23:50:10 INFO - Output received: 23:50:10 INFO - Usage: mozinstall [options] installer 23:50:10 INFO - Options: 23:50:10 INFO - -h, --help show this help message and exit 23:50:10 INFO - -d DEST, --destination=DEST 23:50:10 INFO - Directory to install application into. [default: 23:50:10 INFO - "/builds/slave/test"] 23:50:10 INFO - --app=APP Application being installed. [default: firefox] 23:50:10 INFO - mkdir: /builds/slave/test/build/application 23:50: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'] 23:50: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 23:50:45 INFO - Reading from file tmpfile_stdout 23:50:45 INFO - Output received: 23:50:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:50:45 INFO - Running post-action listener: _resource_record_post_action 23:50:45 INFO - ##### 23:50:45 INFO - ##### Finished install step (success) 23:50:45 INFO - ##### 23:50:45 INFO - ##### 23:50:45 INFO - ##### Running run-tests step. 23:50:45 INFO - ##### 23:50:45 INFO - Running pre-action listener: _resource_record_pre_action 23:50:45 INFO - Running main action method: run_tests 23:50:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:50:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:50:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:50:45 INFO - 2016-07-24 23:50:45.367 screenresolution[1639:7559] starting screenresolution argv=screenresolution get 23:50:45 INFO - 2016-07-24 23:50:45.370 screenresolution[1639:7559] Display 0: 1600x1200x32@60 23:50:45 INFO - 2016-07-24 23:50:45.376 screenresolution[1640:7561] starting screenresolution argv=screenresolution list 23:50:45 INFO - Available Modes on Display 0 23:50:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:50:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:50:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:50:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:50:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:50:45 INFO - Intel Iris: 23:50:45 INFO - Chipset Model: Intel Iris 23:50:45 INFO - Type: GPU 23:50:45 INFO - Bus: Built-In 23:50:45 INFO - VRAM (Dynamic, Max): 1536 MB 23:50:45 INFO - Vendor: Intel (0x8086) 23:50:45 INFO - Device ID: 0x0a2e 23:50:45 INFO - Revision ID: 0x0009 23:50:45 INFO - Displays: 23:50:45 INFO - Display: 23:50:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:50:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:50:45 INFO - Display Serial Number: 1600x1200 60 23:50:45 INFO - Main Display: Yes 23:50:45 INFO - Mirror: Off 23:50:45 INFO - Online: Yes 23:50:45 INFO - Rotation: Supported 23:50:45 INFO - Return code: 0 23:50:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:50:45 INFO - Python 2.7.3 23:50:45 INFO - Return code: 0 23:50:45 INFO - grabbing minidump binary from tooltool 23:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:50: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 0x103df5d30>, '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 0x103b5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103dedb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:50: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 23:50: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 23:50: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 23:50:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:50:45 INFO - Return code: 0 23:50:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:50:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:50:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:50:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:50:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:50: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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:50: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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0085 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:50:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.29uZLmL1Y6/Render', 23:50:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:50:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:50:45 INFO - 'HOME': '/Users/cltbld', 23:50:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:50:45 INFO - 'LOGNAME': 'cltbld', 23:50:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:50:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:50:45 INFO - 'MOZ_NO_REMOTE': '1', 23:50:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:50:45 INFO - 'NO_EM_RESTART': '1', 23:50:45 INFO - 'PAGER': '/bin/cat', 23:50:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:50:45 INFO - 'PWD': '/builds/slave/test', 23:50:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:50:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:50:45 INFO - 'SHELL': '/bin/bash', 23:50:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SEtd8XlrIO/Listeners', 23:50:45 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:50:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:50:45 INFO - 'USER': 'cltbld', 23:50:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:50:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:50:45 INFO - 'XPC_FLAGS': '0x0', 23:50:45 INFO - 'XPC_SERVICE_NAME': '0', 23:50:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:50:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0085', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:50:45 INFO - mozversion application_buildid: 20160704040157 23:50:45 INFO - mozversion application_changeset: 99bab2d812fc9619c122fcdd84c6f085ced8c1e8 23:50:45 INFO - mozversion application_display_name: Nightly 23:50:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:50:45 INFO - mozversion application_name: Firefox 23:50:45 INFO - mozversion application_remotingname: firefox 23:50:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:50:45 INFO - mozversion application_vendor: Mozilla 23:50:45 INFO - mozversion application_version: 50.0a1 23:50:45 INFO - mozversion platform_buildid: 20160704040157 23:50:45 INFO - mozversion platform_changeset: 99bab2d812fc9619c122fcdd84c6f085ced8c1e8 23:50:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:50:45 INFO - mozversion platform_version: 50.0a1 23:50:45 INFO - using testdate: 1469429445 23:50:45 INFO - actual date: 1469429445 23:50:45 INFO - starting webserver on 'localhost:49265' 23:50:45 INFO - SUITE-START | Running 2 tests 23:50:45 INFO - TEST-START | dromaeo_css 23:50:45 INFO - operating with platform_type : mac_ 23:50:45 INFO - Initialising browser for dromaeo_css test... 23:50:45 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpotNPym/profile http://localhost:49265/getInfo.html) 23:50:48 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 23:50:48 INFO - PROCESS | 1650 | colorDepth:24 23:50:48 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 23:50:49 INFO - PROCESS | 1650 | __metrics 23:50:49 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 23:50:49 INFO - TEST-INFO | 1650: exit 0 23:50:49 INFO - Browser initialized. 23:50:49 INFO - Running cycle 1/1 for dromaeo_css test... 23:50:49 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpotNPym/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:51:01 INFO - PROCESS | 1653 | RSS: Main: 255660032 23:51:01 INFO - PROCESS | 1653 | 23:51:51 INFO - PROCESS | 1653 | 1469429511607 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] 23:54:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 23:54:04 INFO - PROCESS | 1653 | RSS: Main: 268357632 23:54:04 INFO - PROCESS | 1653 | 23:57:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 23:57:06 INFO - PROCESS | 1653 | RSS: Main: 277135360 23:57:06 INFO - PROCESS | 1653 | 00:00:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 00:00:09 INFO - PROCESS | 1653 | RSS: Main: 282042368 00:00:09 INFO - PROCESS | 1653 | 00:03:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 00:03:11 INFO - PROCESS | 1653 | RSS: Main: 280879104 00:03:11 INFO - PROCESS | 1653 | 00:06:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 00:06:15 INFO - PROCESS | 1653 | RSS: Main: 284061696 00:06:15 INFO - PROCESS | 1653 | 00:09:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 00:09:18 INFO - PROCESS | 1653 | RSS: Main: 287293440 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | __start_tp_report 00:09:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 00:09:18 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 00:09:18 INFO - PROCESS | 1653 | |i|pagename|runs| 00:09:18 INFO - PROCESS | 1653 | |0;dojo.html;3340.03139089664;3351.195713028873;3366.8821591244296;3374.4263475209214;3379.0369744622803;5660.603757736955;5674.773009079642;5744.253247077884;5760.078387457997;5779.942200577993;4591.4260717410325;5674.205611214434;5693.715314234292;5724.112762521805;5776.59563830532;5093.185090385548;5125.718085505296;5153.81961631343;5175.094358487268;5232.084385232588;5173.793048278064;5238.580913526927;5278.996990571779;5308.416074231832;5342.599305052117;5044.899102017976;5135.460776618476;5190.8183213587345;5192.792288308482;5212.582993360522;4256.9148617027795;5142.537171654569;5176.094183517869;5200.76596553156;5260.053190425722;5055.772490237948;5156.097682905514;5195.142801437758;5201.9479805201845;5206.7917283308625;4378.715383500063;5641.8589535261535;5708.77164913403;5754.079347304453;5776.393478684761;6153.507719382484;6196.907046394308;6212;6235.657038862861;6239.719212635442;6143.511877689543;6184.134221209034;6301.7164227609865;6310.148130002437;6319.146915166486;5735.569832262597;5772.249607550992;5783.450572195677;5791.913121303184;5815.534757219412;6252.562320637603;6291.087792270096;6293.811185664345;6295.307516173218;6321.747130114836;6005.159277701126;6061.242344706911;6084.269887613426;6107.083937409424;6129.325774164839;5744.971275143681;5753.539716822645;6176.7838125665385;6223.595466294676;6237.937620623736;6022.39776022403;6109.647596799064;6132.63204207749;6174.073888916699;6208.751649933952;4931.137051016058;6110.224634763485;6151.507879369639;6243.313235544087;6256.280527739368;4874.756262186947;4877.463479017305;4893.926591101136;4919.385076865392;4945.307656928033;4335.1329734053315;4370.606645401929;4383.408239887638;4395.956040439554;4422.402975598317;5692.57305702074;5844.26946631671;5864.589478736448;5908.379620139909;5915.526757859361;5055.7219352935945;5457;6209.223847019122;6272.278687950853;6290.591111577733;5861.6189947653265;5910.527157827363;5930.05119180929;5933.317668468095;5944.078667806497;4431.82272709088;4626.4910859805395;5380.139177731622;5397.757100930389;5442.809501667422;6325.177726895474;6328.177336946167;6331.556791024584;6333.651649159248;6348.77779277723;6117.218487018478;6190.7214175363115;6196.47329976957;6216.347283535164;6227;5820.388859169726;5864.706764661835;6288.6855657217875;6301.747930082745;6309.684515774285;6121.204243448323;6148.631082135086;6152.600080994721;6163.19878415821;6172;6113.810786210694;6143.327652471189;6174.9073763892675;6182.845428864314;6204.937950620436;5941.079132734433;5999.220101386792;6056.394360563909;6117.571769976059;6159;6031.24609423822;6049.153118563493;6072.8785424292155;6075.483583895241;6108.511319094373;5740.540756739368;5860.120981852756;5894.911576326444;5911.822645320647;5915.733791979285;4996.60027197816;5994.250718660168;6036.637801731735;6063.181470501602;6067.453929146398;5918.704064796829;5925.555583331354;5962.433568810956;5964.821055368346;5966.254218222723 00:09:18 INFO - PROCESS | 1653 | |1;ext.html;474.9930148086056;485.77654279031685;534.1480338859519;540.4379445376807;548;37711.99999999996;40125.799371003144;40158.79523614289;40217.79891100548;40238.79880600593;41111.794441027756;41719.95700107499;41797.74606761802;41911.58088419115;41922.7903860481;9623.133917947383;9752.951235243814;9758.609655613767;9781;9788.412695238272;9627.855582166274;9692.854607180898;9713.902860971406;9717.708468745914;9719.708408747749;9705.320627556073;9723.611055557769;9738.318317717763;9757.073078057609;9764.365316254456;8483.830323393528;9690.982446843085;9738.805223895553;9740.659076932345;9754.512274386323;9589.808203835953;9664.75838104046;9665.371750836173;9671.274654400948;9686.128248457602;39466.408003879966;39573.60426395757;39712;39730.80134599337;39776.60223397758;3444.6727560881677;3509.666581674737;3602.8018458984743;3644.453332000221;3936.960630393688;27861;32128.839355803073;32140.517892231634;32222.677773222436;32282.192945176324;3264;3419.1793969447403;3444.3111377724554;3457.360388328142;3458.342914846171;494890;496158;499772;501300.49349753384;502033.48983255215;46888;47425.76287118543;57476;57893;58109;50422.74788626033;60090;60843;61521.69239153865;61540.38459615347;16079.276432560562;16242.918785405996;16247.83752162487;16250.75623865643;16300.021998680117;12589.370531473389;12678;12756.170848894792;12811.743765124646;12826.294553799444;12824.358782060859;14955.728763055045;15308.617284567752;15688.137152456724;15744.448944287124;5283.233931079972;6066.332703402622;6238.844028899223;6238.844028899314;6246.656433896089;866.5234121233296;982.5431174503747;1030.1707125763683;1056.8414737789394;1073.6778966310076;30056.24859378533;30151.849240753654;30220;30373.392532149675;31091;867.3104881619128;1005.070309963281;1040.204243753514;1067.4502631144967;1094.9069329107115;15563.922180389025;15695.921520392554;15806.60483487922;15858.841411585967;15960.76058859118;660885;664328;675609;678307;679567;58433.95481158139;58701;58709.70645146832;58739.70630146822;58740;1376.3599926034442;1377.6624726941764;1386.2098603795941;1431.8854491640643;1441.4594527052145;30602.84698576493;30675;30740.00189987733;31130.844345778125;31395.84302078475;48471;49312.75343623259;49381.506184937694;49404.752976234886;49412;512030;514021;516975;518274;518311;24896.12863549767;25951;25951;25963.870180648977;25970.87014564915;46022.76988615169;46099.76950115228;46789.76605116953;46841.297380538635;47246.81882952954;38067;38091.80954095212;38557.614423855404;38947.610523895535;39123.80438097791;38294.617053829104;38330.52109181142;38599;39123.69639339614;39271.80364098161;29086.7091329084;36222.8188859054;36557;37179.07052323713;37526.43710344402;33465.49801753024;33540;33954.81158159453;34113;34123.48814767731;20405.979701015185;24491;24745.876270618533;25363.365915851515;25743 00:09:18 INFO - PROCESS | 1653 | |2;jquery.html;372.96270372962704;379.9449079883417;385.0892638908981;397.439610149689;402.7643828360409;18322.725159122623;19659.11533980971;20477.897610511965;21735.23926662567;22680.43298917528;11714.414279286022;12119.697007574816;12270.938645306784;12601.117921745481;20818.687719684218;5520.1443776214755;5713;5720.313562372521;5729.968605650981;5731.083026715726;3980.4825372701653;6049.788257410992;6665.800025999228;6994.58032518048;7020.859582808341;6264.56148069633;6280.371962803706;6889.001094841245;7030.226675065739;7033.788986330418;6103.481204097657;6266.937330626703;6270;6281.7173227204885;6302.747890084391;5917.112433135021;6055.091736239555;6107.389261073901;6125.5099592032975;6165;14366.425342986267;25822.48355032889;26385.604215936775;26851.46297074067;26943.730562694513;2194.7476040255256;2370.8458950168183;2374.9881250593808;2380.773826486481;2402.4561979687596;10016.456390564988;10322.432266225354;10370.377777333384;10599.311044782142;10603;1905.285517930776;1918.7793403758467;1920.5870737791442;1990.6217818614416;1990.7909669484786;78567;85236;122634.38682806618;126196.99999999908;130882;11111.111111111091;11370.260933039492;11664.941675291739;11858.407079645984;11942.34317112567;14607.853921460863;14714.264286785618;14770.039947403598;14774.039687420283;14804.333804978802;5986.700664966821;6843.0077638742105;7052.153741551047;7128.7504937327985;7140.714371425085;5303.416624171338;6295.181626388632;6357.300696923335;6430.807075787734;6468.741250350027;3855.652991230803;4224.922644725637;4536.886577835515;4567;4684.836030738976;1826.3707701312583;1963.0577322885094;1963.1656545967905;1970.221762403871;1979.7826434393285;861.4572819123912;881.5944665453835;945.7730144765206;980.1178938954997;1019.592163134752;19345.51636209078;20605.89697051535;21785.673214901788;22033.779662203495;22114.44713882166;964.0937518732369;964.9517524123765;978.1050338939932;999.4103478947455;1008.5612758450097;9816.754581135528;9981.600735970625;11294.661160165208;11332.2067455279;11336.6032188873;529663;616559;625192;639632;640003.7999809971;135480;135582;135967;137125;138390;1001.7746007148479;1009.1119814563137;1016.9644062457926;1026.5175367577226;1026.6765968719828;15895.682086357976;18972.525686857938;19062.7140592894;19093.71359429582;19204.423867284004;55216;56596.434035660764;56753.71623141858;56929.430705692415;57395.713021434625;70511.64744176246;71667;82397.58801206195;83299;83300;6050.788222412194;8157.409305185432;8236.588170591567;8313.293370063613;8447.493150410995;9629.32594718363;11247.831282530933;12808.359582021048;12854.67863303425;13034.739305213652;9298.907010929805;11006.779864402506;11250.04374628133;11434.599789007345;11535.442715233336;8905.73282801517;9313.86029209576;11139.610113645984;11231.831522527333;11436.970672639169;11481.540738370706;11524.423778811193;11538.423078846192;11541.653750387502;11746.530138794353;6407.455366293917;8127.674893004214;8284.585770711561;8296.419250652396;8352;8364.163583641588;10463.843042354523;10607.41659208751;11267.774644507228;11342 00:09:18 INFO - PROCESS | 1653 | |3;mootools.html;1533.4632878492528;1539.7921280627115;1764.3471915391299;1858.1266804601842;1866.8413184879284;7877.7636670899965;8396.538190399526;8408.327333813295;8870.645174193032;8899.555022248886;6508.609483430985;6951.165860096783;8699.304055675546;8763.956180219106;8769.693060742859;2332.1021406758437;2348.6711860339565;2354.0819080558595;2369.277370402027;2392.928212153638;2345.7654234576576;2362.787349138577;2400.543896659638;2407.8555286682767;2408.434018005767;2383.4279772854475;2386.916457923973;2399.1722855614785;2402.5915594349003;2403.2336232868915;1983.4842940835488;2357.9528409431714;2358.740538540768;2381.868997205153;2381.9642705359433;2328.4528135888054;2354.5290941811536;2362;2368.6328619064075;2370.2296753555165;8852.734417967471;8871.556422178865;8925.107489251088;8926.955365223132;8935.553222338922;5185.818496352647;5399.136138217867;5406.405295417501;5421.213923981;5422.8373148805595;2920.6057182280547;3083.5066389377594;3151.196444906557;3648.087978005499;3762.887113386603;1100.8568886044843;1106.5463160104398;1111.6276047524088;1125.8311253311988;1128.3399211461306;11487.253328533701;11663.650090497298;11685.94157029218;11686.181967262295;11708.94619484242;4598.8620341389815;4641.6054635355695;4821.710697358169;4834.250691142878;4849.345338379274;9994.750131246632;10013.29906906524;10027.749306267402;10028.949855250678;10044.698659040241;5287.02190094826;5305.734713264322;5351.732413379394;5365.731713414314;5367.114426119647;4422.977885110554;4665.6734028617675;4844.709317440965;4878.219484882402;4920.089783390013;3929.1945151243926;3963.8612648557164;4045.1707399982934;4083.816228269735;4118.794060296973;1287.1826390242263;1304.9021323400782;1307.7907534794388;1318.7164759576738;1321.7752981993085;909.6134142989204;927;934.1779234273796;940.8400571902656;989.3420875117966;5228.529432351031;5864;6015;6092.878142437126;6112.938870611326;442.60829166188154;451.8373385581188;463.4322954380924;463.80983796643216;991.980160396788;3585.0320413488735;3949.7037722170953;4022.6982976276895;4055.6552693020835;4133.937990930139;10314.27800053989;10596.046355828012;10744;10759.892401076046;10902.890971090346;10722.142228621691;10744.355338679548;10748.838767418494;10772;10802.243842930919;7851.842963140705;8183.427160098855;8228.876566851503;8350.540720260444;8390.958045209734;5578.77267001259;5981.431763982415;6079.574429790049;6161.630302181883;6255.718492667932;10078.949605251926;10107.64623238183;10201.489925503844;10204.13264872494;10238.846417303597;9455.905440945779;9674.613015479583;9910.207183425164;9954.601815927572;10341.74145646365;6820.215675197416;7343.8898416522725;7438.218987006287;7539;7634.351080158249;8355.415120941476;8370.87443688333;8422.705205317785;8437.662493500191;8441.704540341305;7215.278472152742;7453.329200371993;7636.770896873103;7707.421943354383;7713.537187768752;7432.591207483424;7438.293362130589;7486.737964171228;7514.211007844097;7614.885776713464;7084.079069720903;7130.964345178241;7142.178649455172;7152.713891444284;7193.784186474414;4988.650794444354;5060.063888180698;5151.768170432265;5166.173412254021;5172.069027575073;5120.103981803095;5135.35808023997;5162.406323272721;5315.867103322448;5333.97333013348 00:09:18 INFO - PROCESS | 1653 | |4;prototype.html;306.51723535431694;309.83578703287253;311.5871470301849;313.77408266048445;315.8720718109164;5642.943570564293;5663.688497132656;5677.57418193635;5692.153923038481;5705.74324155413;4946.134426475371;5254.57963362931;5507.339119305689;5525.475079867416;5582.91625625616;3427.9828600856963;3982.283189025974;4088.407180958759;4153.314703073989;4179.495381662599;3698.0939669780805;3947.940780888289;4159.438475805758;4169.687273454488;4203.432536607549;3495.4057810172203;3771.7925514096714;4160.687948403866;4167.520735115455;4205.453291072156;3111.5021596544557;3782.205736795276;3820.1595648957186;4137.13120244749;4171.749695018309;3532.364174448598;3541.9291614167573;3543.840527176283;3548.8048157351336;3565.3938830398893;4294.656427485794;4863.975680121612;5340.465953404668;5397.163439666858;5455.8363249102595;4573.428321459817;4730.172219861511;4737.620990320766;4744.312074749177;4767.237242041257;4896.02079584081;5355.651882627617;5730.455606717355;5771.740271687784;5863;3916.295066887959;3925.371940489508;3934.9016274593077;3943.2310699413624;3951.5060617422823;5179.300794392747;5665.923474539824;6083.787067452662;6170.259568851721;6198.876022479525;3961.8613348532663;3986.5614782373914;3991.7804520751056;4002.679785617144;4005.35914253718;3317.270200555874;3982.123932734794;4058.837646494166;4059.2287465381482;4072.490938632671;3572.481990111471;3627.6190999944624;4695.929561056587;4750.12122757291;4793.257045158006;4586.4266966629175;4738.763061846894;5822.534197264209;5851.444112809277;5871.73757642109;1272.968175795594;1342.5502456676927;1354.6071639224515;1367.7606418876658;1379.5102738527878;793.2781169136058;805.2390490985962;841.0160112668193;841.0706169682535;875.7329014650466;4842.370491836109;5428.240046393508;5447.373552041487;5509.834704958857;5518.034343989866;5221.477852214824;5705.486506214377;5960.195373624505;6006.519478441802;6051.939480605226;5183.50756678115;5463.617546771688;5482.890342193134;5508.779648814003;5514.724263786875;2888.9277768055967;2921.8100823446407;2983.23927398512;3165.6201255849446;3168.556402103707;5175.508326708957;5899.852503687443;6135.662538560422;6185.474234690102;6213.2233470816145;5007.924881126786;5747.453991870766;6078.598953413377;6079.635221886701;6105.542084343692;4884.8290309838985;5241.292425522582;5612.971935140298;6117.541184411098;6169.413905678954;5112.590992720499;5340.866478338073;5402.297701298806;5654.85862853432;5932.1229203836965;4899.681520701143;5224.373075230997;5583.380819562444;5895.764169433174;5901.232839730807;5514.834554963358;5961.7615295387695;6005.609635373861;6050.156426479548;6196.318404975359;3895.668868146239;3935.4293627424436;3962.623550762673;3975.986123538479;3976.483057202661;5213.608979326642;5484.122540393518;5943.58394912352;5978.521718262616;5988.311344195473;4863.683860549053;5131.358580177478;5188.507091826271;5218.399884013235;5818.77805660816;5731.484166425042;5739.4834464896685;5744.448998770392;5760.251167348386;5765.221695071112;4704.929426058543;5468.72656367188;5547.694876781816;5623.409541998195;5625.921207124802;4865.829695960624;5028.673136246132;5672.801451949162;5842.737076831638;5868.501177399798;5437.178545187407;5464.371597266207;5737.340205876378;5764.406017292319;5773.41231161434 00:09:18 INFO - PROCESS | 1653 | |5;yui.html;715.9785206443806;719.4711886763231;757.6325482141164;771.9652615632298;796.8645330293853;10906.781864362718;17595.120243987796;17951.371701990432;18190.81809181911;18253.817461825376;16370.590735231624;16458.506244812645;16534;16539.58651033725;16551.917240413783;700.0654126740807;842.2798507276283;874.1127755328331;902.3728508686465;903.0247332880473;868.9478631282143;871.2420194430854;880.9823803523925;882.483747008001;885.9025507194203;916.9587368568431;943.259541260111;943.6838659049226;945.3099237556588;945.4043952310069;913.4656226107735;935.780091678455;935.8596210568435;936.1153709744294;937.4796987671839;694.8019814352936;695.0963747128712;699.0911814640947;699.40899939551;700.5551474813531;15944.202789860461;16006.679866402606;16116.35534578627;16117;16138.75791863123;13029.283389413578;14661.780073298909;14682;14715.264236788118;14722;10061.89938100617;11681.123915706272;12117.272963622123;12143.271403715806;12681.302528360937;3830.4828848105426;3908.6124425828707;3935.094928166509;3969.2656858480987;4059.167870586523;106386;108293;128774.27354358768;130609.34695326557;132395;11366.545338186545;11367.431628418546;11385;11434.313941163557;11531.250468719507;22911.770882291297;23065.423364416023;23087.88456057709;23093.191738289413;23123;8349.248567628943;8418.368622353348;8426.620802063922;8429.789255268668;8476.152384761475;4625.283081122431;4672.018876036002;4678.438587369537;4698.186734531581;4699.506551812011;3622.492851000862;3742.8877133686033;3750.981245093757;3753.024213704402;3755.962440375561;650.1125963060483;658.9341065893373;660.6102399584269;673.192169396726;677.6814896998501;557.9944200558024;619.662284055191;664.4817042706696;685.1127789512593;692.7852365766629;9092.681756138503;9619.951900240454;9785.238657041658;9799.559019844124;10280.537375818107;630.2594451519446;665.7902760630386;681.8329509270161;716.2622498826217;718.5975853522044;4535.523770004168;4624.838130665411;4644.117617652635;4791.928121078186;4811.446683631427;153399;156565;157621.42378576068;159393;159916.20041899715;79076;86633;124953.37523312507;127144.36427817802;127524;77337.83993240105;94994.5250273758;103343;111313;117385.4130729358;9587.808243835223;9972.750681233027;10219.591216351262;10233.84649230277;10298.267228360417;55330.446695532526;55470.117091803186;58236.41763582479;61912.38087619066;62195.6890215546;79103.20896790958;87011;87130;88512.06782779162;88823.55588222017;13313.001524885478;13567.59297221085;13629.727405452033;13967.092139010932;14136;14851.405943762129;14939.701205976036;15001.174935378658;15107.924460377628;15122.666055247006;13381.330933453482;13814.723705526034;13967.371468283749;14012.019158659205;14035.368408421442;13766.793498097337;14087.859121409065;14110.647233818827;14654.78017829712;14833.777493337824;11330.660080197607;13595.864041359855;13874.583762487142;14023.36894839755;14298.428062877369;11056.841228361696;11173.776524469302;11173.832392514281;11466.71333216643;12104;11021.564363940854;11079.015795203968;11615.361155136548;11620.244684095507;11660.883391166319 00:09:18 INFO - PROCESS | 1653 | __end_tp_report 00:09:18 INFO - PROCESS | 1653 | __start_cc_report 00:09:18 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,447 00:09:18 INFO - PROCESS | 1653 | __end_cc_report 00:09:18 INFO - PROCESS | 1653 | __startTimestamp1469430558252__endTimestamp 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | ------- Summary: start ------- 00:09:18 INFO - PROCESS | 1653 | Number of tests: 6 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5671.58 Median:5911.82 stddev:668.32 (11.3%) stddev-sans-first:644.86 00:09:18 INFO - PROCESS | 1653 | Values: 3340.0 3351.2 3366.9 3374.4 3379.0 5660.6 5674.8 5744.3 5760.1 5779.9 4591.4 5674.2 5693.7 5724.1 5776.6 5093.2 5125.7 5153.8 5175.1 5232.1 5173.8 5238.6 5279.0 5308.4 5342.6 5044.9 5135.5 5190.8 5192.8 5212.6 4256.9 5142.5 5176.1 5200.8 5260.1 5055.8 5156.1 5195.1 5201.9 5206.8 4378.7 5641.9 5708.8 5754.1 5776.4 6153.5 6196.9 6212.0 6235.7 6239.7 6143.5 6184.1 6301.7 6310.1 6319.1 5735.6 5772.2 5783.5 5791.9 5815.5 6252.6 6291.1 6293.8 6295.3 6321.7 6005.2 6061.2 6084.3 6107.1 6129.3 5745.0 5753.5 6176.8 6223.6 6237.9 6022.4 6109.6 6132.6 6174.1 6208.8 4931.1 6110.2 6151.5 6243.3 6256.3 4874.8 4877.5 4893.9 4919.4 4945.3 4335.1 4370.6 4383.4 4396.0 4422.4 5692.6 5844.3 5864.6 5908.4 5915.5 5055.7 5457.0 6209.2 6272.3 6290.6 5861.6 5910.5 5930.1 5933.3 5944.1 4431.8 4626.5 5380.1 5397.8 5442.8 6325.2 6328.2 6331.6 6333.7 6348.8 6117.2 6190.7 6196.5 6216.3 6227.0 5820.4 5864.7 6288.7 6301.7 6309.7 6121.2 6148.6 6152.6 6163.2 6172.0 6113.8 6143.3 6174.9 6182.8 6204.9 5941.1 5999.2 6056.4 6117.6 6159.0 6031.2 6049.2 6072.9 6075.5 6108.5 5740.5 5860.1 5894.9 5911.8 5915.7 4996.6 5994.3 6036.6 6063.2 6067.5 5918.7 5925.6 5962.4 5964.8 5966.3 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:69119.79 Median:39721.40 stddev:151763.95 (382.1%) stddev-sans-first:152102.17 00:09:18 INFO - PROCESS | 1653 | Values: 475.0 485.8 534.1 540.4 548.0 37712.0 40125.8 40158.8 40217.8 40238.8 41111.8 41720.0 41797.7 41911.6 41922.8 9623.1 9753.0 9758.6 9781.0 9788.4 9627.9 9692.9 9713.9 9717.7 9719.7 9705.3 9723.6 9738.3 9757.1 9764.4 8483.8 9691.0 9738.8 9740.7 9754.5 9589.8 9664.8 9665.4 9671.3 9686.1 39466.4 39573.6 39712.0 39730.8 39776.6 3444.7 3509.7 3602.8 3644.5 3937.0 27861.0 32128.8 32140.5 32222.7 32282.2 3264.0 3419.2 3444.3 3457.4 3458.3 494890.0 496158.0 499772.0 501300.5 502033.5 46888.0 47425.8 57476.0 57893.0 58109.0 50422.7 60090.0 60843.0 61521.7 61540.4 16079.3 16242.9 16247.8 16250.8 16300.0 12589.4 12678.0 12756.2 12811.7 12826.3 12824.4 14955.7 15308.6 15688.1 15744.4 5283.2 6066.3 6238.8 6238.8 6246.7 866.5 982.5 1030.2 1056.8 1073.7 30056.2 30151.8 30220.0 30373.4 31091.0 867.3 1005.1 1040.2 1067.5 1094.9 15563.9 15695.9 15806.6 15858.8 15960.8 660885.0 664328.0 675609.0 678307.0 679567.0 58434.0 58701.0 58709.7 58739.7 58740.0 1376.4 1377.7 1386.2 1431.9 1441.5 30602.8 30675.0 30740.0 31130.8 31395.8 48471.0 49312.8 49381.5 49404.8 49412.0 512030.0 514021.0 516975.0 518274.0 518311.0 24896.1 25951.0 25951.0 25963.9 25970.9 46022.8 46099.8 46789.8 46841.3 47246.8 38067.0 38091.8 38557.6 38947.6 39123.8 38294.6 38330.5 38599.0 39123.7 39271.8 29086.7 36222.8 36557.0 37179.1 37526.4 33465.5 33540.0 33954.8 34113.0 34123.5 20406.0 24491.0 24745.9 25363.4 25743.0 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:35127.24 Median:26618.53 stddev:102154.22 (383.8%) stddev-sans-first:102407.46 00:09:18 INFO - PROCESS | 1653 | Values: 373.0 379.9 385.1 397.4 402.8 18322.7 19659.1 20477.9 21735.2 22680.4 11714.4 12119.7 12270.9 12601.1 20818.7 5520.1 5713.0 5720.3 5730.0 5731.1 3980.5 6049.8 6665.8 6994.6 7020.9 6264.6 6280.4 6889.0 7030.2 7033.8 6103.5 6266.9 6270.0 6281.7 6302.7 5917.1 6055.1 6107.4 6125.5 6165.0 14366.4 25822.5 26385.6 26851.5 26943.7 2194.7 2370.8 2375.0 2380.8 2402.5 10016.5 10322.4 10370.4 10599.3 10603.0 1905.3 1918.8 1920.6 1990.6 1990.8 78567.0 85236.0 122634.4 126197.0 130882.0 11111.1 11370.3 11664.9 11858.4 11942.3 14607.9 14714.3 14770.0 14774.0 14804.3 5986.7 6843.0 7052.2 7128.8 7140.7 5303.4 6295.2 6357.3 6430.8 6468.7 3855.7 4224.9 4536.9 4567.0 4684.8 1826.4 1963.1 1963.2 1970.2 1979.8 861.5 881.6 945.8 980.1 1019.6 19345.5 20605.9 21785.7 22033.8 22114.4 964.1 965.0 978.1 999.4 1008.6 9816.8 9981.6 11294.7 11332.2 11336.6 529663.0 616559.0 625192.0 639632.0 640003.8 135480.0 135582.0 135967.0 137125.0 138390.0 1001.8 1009.1 1017.0 1026.5 1026.7 15895.7 18972.5 19062.7 19093.7 19204.4 55216.0 56596.4 56753.7 56929.4 57395.7 70511.6 71667.0 82397.6 83299.0 83300.0 6050.8 8157.4 8236.6 8313.3 8447.5 9629.3 11247.8 12808.4 12854.7 13034.7 9298.9 11006.8 11250.0 11434.6 11535.4 8905.7 9313.9 11139.6 11231.8 11437.0 11481.5 11524.4 11538.4 11541.7 11746.5 6407.5 8127.7 8284.6 8296.4 8352.0 8364.2 10463.8 10607.4 11267.8 11342.0 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5714.21 Median:4847.03 stddev:3201.78 (66.1%) stddev-sans-first:3195.35 00:09:18 INFO - PROCESS | 1653 | Values: 1533.5 1539.8 1764.3 1858.1 1866.8 7877.8 8396.5 8408.3 8870.6 8899.6 6508.6 6951.2 8699.3 8764.0 8769.7 2332.1 2348.7 2354.1 2369.3 2392.9 2345.8 2362.8 2400.5 2407.9 2408.4 2383.4 2386.9 2399.2 2402.6 2403.2 1983.5 2358.0 2358.7 2381.9 2382.0 2328.5 2354.5 2362.0 2368.6 2370.2 8852.7 8871.6 8925.1 8927.0 8935.6 5185.8 5399.1 5406.4 5421.2 5422.8 2920.6 3083.5 3151.2 3648.1 3762.9 1100.9 1106.5 1111.6 1125.8 1128.3 11487.3 11663.7 11685.9 11686.2 11708.9 4598.9 4641.6 4821.7 4834.3 4849.3 9994.8 10013.3 10027.7 10028.9 10044.7 5287.0 5305.7 5351.7 5365.7 5367.1 4423.0 4665.7 4844.7 4878.2 4920.1 3929.2 3963.9 4045.2 4083.8 4118.8 1287.2 1304.9 1307.8 1318.7 1321.8 909.6 927.0 934.2 940.8 989.3 5228.5 5864.0 6015.0 6092.9 6112.9 442.6 451.8 463.4 463.8 992.0 3585.0 3949.7 4022.7 4055.7 4133.9 10314.3 10596.0 10744.0 10759.9 10902.9 10722.1 10744.4 10748.8 10772.0 10802.2 7851.8 8183.4 8228.9 8350.5 8391.0 5578.8 5981.4 6079.6 6161.6 6255.7 10078.9 10107.6 10201.5 10204.1 10238.8 9455.9 9674.6 9910.2 9954.6 10341.7 6820.2 7343.9 7438.2 7539.0 7634.4 8355.4 8370.9 8422.7 8437.7 8441.7 7215.3 7453.3 7636.8 7707.4 7713.5 7432.6 7438.3 7486.7 7514.2 7614.9 7084.1 7131.0 7142.2 7152.7 7193.8 4988.7 5060.1 5151.8 5166.2 5172.1 5120.1 5135.4 5162.4 5315.9 5334.0 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4624.90 Median:5216.00 stddev:1440.37 (27.6%) stddev-sans-first:1407.47 00:09:18 INFO - PROCESS | 1653 | Values: 306.5 309.8 311.6 313.8 315.9 5642.9 5663.7 5677.6 5692.2 5705.7 4946.1 5254.6 5507.3 5525.5 5582.9 3428.0 3982.3 4088.4 4153.3 4179.5 3698.1 3947.9 4159.4 4169.7 4203.4 3495.4 3771.8 4160.7 4167.5 4205.5 3111.5 3782.2 3820.2 4137.1 4171.7 3532.4 3541.9 3543.8 3548.8 3565.4 4294.7 4864.0 5340.5 5397.2 5455.8 4573.4 4730.2 4737.6 4744.3 4767.2 4896.0 5355.7 5730.5 5771.7 5863.0 3916.3 3925.4 3934.9 3943.2 3951.5 5179.3 5665.9 6083.8 6170.3 6198.9 3961.9 3986.6 3991.8 4002.7 4005.4 3317.3 3982.1 4058.8 4059.2 4072.5 3572.5 3627.6 4695.9 4750.1 4793.3 4586.4 4738.8 5822.5 5851.4 5871.7 1273.0 1342.6 1354.6 1367.8 1379.5 793.3 805.2 841.0 841.1 875.7 4842.4 5428.2 5447.4 5509.8 5518.0 5221.5 5705.5 5960.2 6006.5 6051.9 5183.5 5463.6 5482.9 5508.8 5514.7 2888.9 2921.8 2983.2 3165.6 3168.6 5175.5 5899.9 6135.7 6185.5 6213.2 5007.9 5747.5 6078.6 6079.6 6105.5 4884.8 5241.3 5613.0 6117.5 6169.4 5112.6 5340.9 5402.3 5654.9 5932.1 4899.7 5224.4 5583.4 5895.8 5901.2 5514.8 5961.8 6005.6 6050.2 6196.3 3895.7 3935.4 3962.6 3976.0 3976.5 5213.6 5484.1 5943.6 5978.5 5988.3 4863.7 5131.4 5188.5 5218.4 5818.8 5731.5 5739.5 5744.4 5760.3 5765.2 4704.9 5468.7 5547.7 5623.4 5625.9 4865.8 5028.7 5672.8 5842.7 5868.5 5437.2 5464.4 5737.3 5764.4 5773.4 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24677.68 Median:3682.69 stddev:39094.42 (1061.6%) stddev-sans-first:39162.69 00:09:18 INFO - PROCESS | 1653 | Values: 716.0 719.5 757.6 772.0 796.9 10906.8 17595.1 17951.4 18190.8 18253.8 16370.6 16458.5 16534.0 16539.6 16551.9 700.1 842.3 874.1 902.4 903.0 868.9 871.2 881.0 882.5 885.9 917.0 943.3 943.7 945.3 945.4 913.5 935.8 935.9 936.1 937.5 694.8 695.1 699.1 699.4 700.6 15944.2 16006.7 16116.4 16117.0 16138.8 13029.3 14661.8 14682.0 14715.3 14722.0 10061.9 11681.1 12117.3 12143.3 12681.3 3830.5 3908.6 3935.1 3969.3 4059.2 106386.0 108293.0 128774.3 130609.3 132395.0 11366.5 11367.4 11385.0 11434.3 11531.3 22911.8 23065.4 23087.9 23093.2 23123.0 8349.2 8418.4 8426.6 8429.8 8476.2 4625.3 4672.0 4678.4 4698.2 4699.5 3622.5 3742.9 3751.0 3753.0 3756.0 650.1 658.9 660.6 673.2 677.7 558.0 619.7 664.5 685.1 692.8 9092.7 9620.0 9785.2 9799.6 10280.5 630.3 665.8 681.8 716.3 718.6 4535.5 4624.8 4644.1 4791.9 4811.4 153399.0 156565.0 157621.4 159393.0 159916.2 79076.0 86633.0 124953.4 127144.4 127524.0 77337.8 94994.5 103343.0 111313.0 117385.4 9587.8 9972.8 10219.6 10233.8 10298.3 55330.4 55470.1 58236.4 61912.4 62195.7 79103.2 87011.0 87130.0 88512.1 88823.6 13313.0 13567.6 13629.7 13967.1 14136.0 14851.4 14939.7 15001.2 15107.9 15122.7 13381.3 13814.7 13967.4 14012.0 14035.4 13766.8 14087.9 14110.6 14654.8 14833.8 11330.7 13595.9 13874.6 14023.4 14298.4 11056.8 11173.8 11173.8 11466.7 12104.0 11021.6 11079.0 11615.4 11620.2 11660.9 00:09:18 INFO - PROCESS | 1653 | -------- Summary: end -------- 00:09:18 INFO - PROCESS | 1653 | 00:09:18 INFO - TEST-INFO | 1653: exit 0 00:09:18 INFO - TEST-OK | dromaeo_css | took 1112872ms 00:09:18 INFO - TEST-START | kraken 00:09:18 INFO - operating with platform_type : mac_ 00:09:18 INFO - Initialising browser for kraken test... 00:09:18 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmp0KKK8h/profile http://localhost:49265/getInfo.html) 00:09:21 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 00:09:21 INFO - PROCESS | 1663 | colorDepth:24 00:09:21 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 00:09:21 INFO - PROCESS | 1663 | __metrics 00:09:21 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 00:09:22 INFO - TEST-INFO | 1663: exit 0 00:09:22 INFO - Browser initialized. 00:09:22 INFO - Running cycle 1/1 for kraken test... 00:09:22 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmp0KKK8h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:09:34 INFO - PROCESS | 1665 | RSS: Main: 255868928 00:09:34 INFO - PROCESS | 1665 | 00:09:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 00:09:37 INFO - PROCESS | 1665 | RSS: Main: 268591104 00:09:37 INFO - PROCESS | 1665 | 00:09:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 00:09:41 INFO - PROCESS | 1665 | RSS: Main: 272695296 00:09:41 INFO - PROCESS | 1665 | 00:09:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 00:09:45 INFO - PROCESS | 1665 | RSS: Main: 273293312 00:09:45 INFO - PROCESS | 1665 | 00:09:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 00:09:47 INFO - PROCESS | 1665 | RSS: Main: 274202624 00:09:47 INFO - PROCESS | 1665 | 00:09:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:09:50 INFO - PROCESS | 1665 | RSS: Main: 274911232 00:09:50 INFO - PROCESS | 1665 | 00:09:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 00:09:53 INFO - PROCESS | 1665 | RSS: Main: 274915328 00:09:53 INFO - PROCESS | 1665 | 00:09:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 00:09:57 INFO - PROCESS | 1665 | RSS: Main: 275947520 00:09:57 INFO - PROCESS | 1665 | 00:10:00 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 00:10:01 INFO - PROCESS | 1665 | RSS: Main: 276299776 00:10:01 INFO - PROCESS | 1665 | 00:10:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:10:03 INFO - PROCESS | 1665 | RSS: Main: 276107264 00:10:03 INFO - PROCESS | 1665 | 00:10:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:10:06 INFO - PROCESS | 1665 | RSS: Main: 275488768 00:10:06 INFO - PROCESS | 1665 | 00:10:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:10:09 INFO - PROCESS | 1665 | RSS: Main: 275537920 00:10:09 INFO - PROCESS | 1665 | 00:10:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:10:12 INFO - PROCESS | 1665 | RSS: Main: 275755008 00:10:12 INFO - PROCESS | 1665 | 00:10:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:10:16 INFO - PROCESS | 1665 | RSS: Main: 275861504 00:10:16 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 00:10:18 INFO - PROCESS | 1665 | RSS: Main: 276537344 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | __start_tp_report 00:10:18 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 00:10:18 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 00:10:18 INFO - PROCESS | 1665 | |i|pagename|runs| 00:10:18 INFO - PROCESS | 1665 | |0;ai-astar;103;118;107;116;122;124;114;116;117;115 00:10:18 INFO - PROCESS | 1665 | |1;audio-beat-detection;144;108;131;183;110;134;124;110;178;139 00:10:18 INFO - PROCESS | 1665 | |2;audio-dft;197;201;221;268;198;199;199;198;199;196 00:10:18 INFO - PROCESS | 1665 | |3;audio-fft;68;78;71;109;59;59;59;62;135;63 00:10:18 INFO - PROCESS | 1665 | |4;audio-oscillator;70;72;127;104;100;84;113;71;72;114 00:10:18 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;90;88;89;87;100;86;77;129;90;93 00:10:18 INFO - PROCESS | 1665 | |6;imaging-darkroom;104;149;113;154;150;98;114;118;97;96 00:10:18 INFO - PROCESS | 1665 | |7;imaging-desaturate;91;140;126;135;139;135;90;117;123;91 00:10:18 INFO - PROCESS | 1665 | |8;json-parse-financial;58;57;56;56;60;90;85;58;57;58 00:10:18 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;70;72;68;84;73;75;72;70;70 00:10:18 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;88;82;86;84;83;86;89;81;97;86 00:10:18 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;138;154;157;146;152;147;138;137;185;135 00:10:18 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;166;163;164;163;167;165;160;183;223;193 00:10:18 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;69;88;89;85;75;65;70;79;87;94 00:10:18 INFO - PROCESS | 1665 | __end_tp_report 00:10:18 INFO - PROCESS | 1665 | __start_cc_report 00:10:18 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1121 00:10:18 INFO - PROCESS | 1665 | __end_cc_report 00:10:18 INFO - PROCESS | 1665 | __startTimestamp1469430618365__endTimestamp 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | ------- Summary: start ------- 00:10:18 INFO - PROCESS | 1665 | Number of tests: 14 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.50 stddev:6.27 (5.4%) stddev-sans-first:4.85 00:10:18 INFO - PROCESS | 1665 | Values: 103.0 118.0 107.0 116.0 122.0 124.0 114.0 116.0 117.0 115.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:136.50 stddev:26.57 (19.5%) stddev-sans-first:28.03 00:10:18 INFO - PROCESS | 1665 | Values: 144.0 108.0 131.0 183.0 110.0 134.0 124.0 110.0 178.0 139.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:207.60 Median:199.00 stddev:22.42 (11.3%) stddev-sans-first:23.45 00:10:18 INFO - PROCESS | 1665 | Values: 197.0 201.0 221.0 268.0 198.0 199.0 199.0 198.0 199.0 196.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:76.30 Median:62.50 stddev:25.59 (40.9%) stddev-sans-first:26.97 00:10:18 INFO - PROCESS | 1665 | Values: 68.0 78.0 71.0 109.0 59.0 59.0 59.0 62.0 135.0 63.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:70.50 stddev:21.45 (30.4%) stddev-sans-first:21.12 00:10:18 INFO - PROCESS | 1665 | Values: 70.0 72.0 127.0 104.0 100.0 84.0 113.0 71.0 72.0 114.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:88.50 stddev:13.92 (15.7%) stddev-sans-first:14.73 00:10:18 INFO - PROCESS | 1665 | Values: 90.0 88.0 89.0 87.0 100.0 86.0 77.0 129.0 90.0 93.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:119.30 Median:152.00 stddev:23.16 (15.2%) stddev-sans-first:23.89 00:10:18 INFO - PROCESS | 1665 | Values: 104.0 149.0 113.0 154.0 150.0 98.0 114.0 118.0 97.0 96.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:118.70 Median:139.50 stddev:20.63 (14.8%) stddev-sans-first:19.29 00:10:18 INFO - PROCESS | 1665 | Values: 91.0 140.0 126.0 135.0 139.0 135.0 90.0 117.0 123.0 91.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:58.00 stddev:12.76 (22.0%) stddev-sans-first:13.37 00:10:18 INFO - PROCESS | 1665 | Values: 58.0 57.0 56.0 56.0 60.0 90.0 85.0 58.0 57.0 58.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:4.92 (6.8%) stddev-sans-first:4.72 00:10:18 INFO - PROCESS | 1665 | Values: 66.0 70.0 72.0 68.0 84.0 73.0 75.0 72.0 70.0 70.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:86.00 stddev:4.57 (5.3%) stddev-sans-first:4.80 00:10:18 INFO - PROCESS | 1665 | Values: 88.0 82.0 86.0 84.0 83.0 86.0 89.0 81.0 97.0 86.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:149.50 stddev:14.87 (9.9%) stddev-sans-first:15.24 00:10:18 INFO - PROCESS | 1665 | Values: 138.0 154.0 157.0 146.0 152.0 147.0 138.0 137.0 185.0 135.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:166.50 stddev:19.92 (12.0%) stddev-sans-first:20.87 00:10:18 INFO - PROCESS | 1665 | Values: 166.0 163.0 164.0 163.0 167.0 165.0 160.0 183.0 223.0 193.0 00:10:18 INFO - PROCESS | 1665 | 00:10:18 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.10 Median:86.00 stddev:9.93 (11.5%) stddev-sans-first:9.68 00:10:18 INFO - PROCESS | 1665 | Values: 69.0 88.0 89.0 85.0 75.0 65.0 70.0 79.0 87.0 94.0 00:10:18 INFO - PROCESS | 1665 | -------- Summary: end -------- 00:10:18 INFO - PROCESS | 1665 | 00:10:19 INFO - TEST-INFO | 1665: exit 0 00:10:19 INFO - TEST-OK | kraken | took 60291ms 00:10:19 INFO - SUITE-END | took 1173s 00:10:19 INFO - Completed test suite (00:19:33) 00:10:19 INFO - javascript benchmark 00:10:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7356.500709710913, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.03139089664, 3351.195713028873, 3366.8821591244296, 3374.4263475209214, 3379.0369744622803, 5660.603757736955, 5674.773009079642, 5744.253247077884, 5760.078387457997, 5779.942200577993, 4591.4260717410325, 5674.205611214434, 5693.715314234292, 5724.112762521805, 5776.59563830532, 5093.185090385548, 5125.718085505296, 5153.81961631343, 5175.094358487268, 5232.084385232588, 5173.793048278064, 5238.580913526927, 5278.996990571779, 5308.416074231832, 5342.599305052117, 5044.899102017976, 5135.460776618476, 5190.8183213587345, 5192.792288308482, 5212.582993360522, 4256.9148617027795, 5142.537171654569, 5176.094183517869, 5200.76596553156, 5260.053190425722, 5055.772490237948, 5156.097682905514, 5195.142801437758, 5201.9479805201845, 5206.7917283308625, 4378.715383500063, 5641.8589535261535, 5708.77164913403, 5754.079347304453, 5776.393478684761, 6153.507719382484, 6196.907046394308, 6212.0, 6235.657038862861, 6239.719212635442, 6143.511877689543, 6184.134221209034, 6301.7164227609865, 6310.148130002437, 6319.146915166486, 5735.569832262597, 5772.249607550992, 5783.450572195677, 5791.913121303184, 5815.534757219412, 6252.562320637603, 6291.087792270096, 6293.811185664345, 6295.307516173218, 6321.747130114836, 6005.159277701126, 6061.242344706911, 6084.269887613426, 6107.083937409424, 6129.325774164839, 5744.971275143681, 5753.539716822645, 6176.7838125665385, 6223.595466294676, 6237.937620623736, 6022.39776022403, 6109.647596799064, 6132.63204207749, 6174.073888916699, 6208.751649933952, 4931.137051016058, 6110.224634763485, 6151.507879369639, 6243.313235544087, 6256.280527739368, 4874.756262186947, 4877.463479017305, 4893.926591101136, 4919.385076865392, 4945.307656928033, 4335.1329734053315, 4370.606645401929, 4383.408239887638, 4395.956040439554, 4422.402975598317, 5692.57305702074, 5844.26946631671, 5864.589478736448, 5908.379620139909, 5915.526757859361, 5055.7219352935945, 5457.0, 6209.223847019122, 6272.278687950853, 6290.591111577733, 5861.6189947653265, 5910.527157827363, 5930.05119180929, 5933.317668468095, 5944.078667806497, 4431.82272709088, 4626.4910859805395, 5380.139177731622, 5397.757100930389, 5442.809501667422, 6325.177726895474, 6328.177336946167, 6331.556791024584, 6333.651649159248, 6348.77779277723, 6117.218487018478, 6190.7214175363115, 6196.47329976957, 6216.347283535164, 6227.0, 5820.388859169726, 5864.706764661835, 6288.6855657217875, 6301.747930082745, 6309.684515774285, 6121.204243448323, 6148.631082135086, 6152.600080994721, 6163.19878415821, 6172.0, 6113.810786210694, 6143.327652471189, 6174.9073763892675, 6182.845428864314, 6204.937950620436, 5941.079132734433, 5999.220101386792, 6056.394360563909, 6117.571769976059, 6159.0, 6031.24609423822, 6049.153118563493, 6072.8785424292155, 6075.483583895241, 6108.511319094373, 5740.540756739368, 5860.120981852756, 5894.911576326444, 5911.822645320647, 5915.733791979285, 4996.60027197816, 5994.250718660168, 6036.637801731735, 6063.181470501602, 6067.453929146398, 5918.704064796829, 5925.555583331354, 5962.433568810956, 5964.821055368346, 5966.254218222723], "value": 5630.611021216964, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.9930148086056, 485.77654279031685, 534.1480338859519, 540.4379445376807, 548.0, 37711.99999999996, 40125.799371003144, 40158.79523614289, 40217.79891100548, 40238.79880600593, 41111.794441027756, 41719.95700107499, 41797.74606761802, 41911.58088419115, 41922.7903860481, 9623.133917947383, 9752.951235243814, 9758.609655613767, 9781.0, 9788.412695238272, 9627.855582166274, 9692.854607180898, 9713.902860971406, 9717.708468745914, 9719.708408747749, 9705.320627556073, 9723.611055557769, 9738.318317717763, 9757.073078057609, 9764.365316254456, 8483.830323393528, 9690.982446843085, 9738.805223895553, 9740.659076932345, 9754.512274386323, 9589.808203835953, 9664.75838104046, 9665.371750836173, 9671.274654400948, 9686.128248457602, 39466.408003879966, 39573.60426395757, 39712.0, 39730.80134599337, 39776.60223397758, 3444.6727560881677, 3509.666581674737, 3602.8018458984743, 3644.453332000221, 3936.960630393688, 27861.0, 32128.839355803073, 32140.517892231634, 32222.677773222436, 32282.192945176324, 3264.0, 3419.1793969447403, 3444.3111377724554, 3457.360388328142, 3458.342914846171, 494890.0, 496158.0, 499772.0, 501300.49349753384, 502033.48983255215, 46888.0, 47425.76287118543, 57476.0, 57893.0, 58109.0, 50422.74788626033, 60090.0, 60843.0, 61521.69239153865, 61540.38459615347, 16079.276432560562, 16242.918785405996, 16247.83752162487, 16250.75623865643, 16300.021998680117, 12589.370531473389, 12678.0, 12756.170848894792, 12811.743765124646, 12826.294553799444, 12824.358782060859, 14955.728763055045, 15308.617284567752, 15688.137152456724, 15744.448944287124, 5283.233931079972, 6066.332703402622, 6238.844028899223, 6238.844028899314, 6246.656433896089, 866.5234121233296, 982.5431174503747, 1030.1707125763683, 1056.8414737789394, 1073.6778966310076, 30056.24859378533, 30151.849240753654, 30220.0, 30373.392532149675, 31091.0, 867.3104881619128, 1005.070309963281, 1040.204243753514, 1067.4502631144967, 1094.9069329107115, 15563.922180389025, 15695.921520392554, 15806.60483487922, 15858.841411585967, 15960.76058859118, 660885.0, 664328.0, 675609.0, 678307.0, 679567.0, 58433.95481158139, 58701.0, 58709.70645146832, 58739.70630146822, 58740.0, 1376.3599926034442, 1377.6624726941764, 1386.2098603795941, 1431.8854491640643, 1441.4594527052145, 30602.84698576493, 30675.0, 30740.00189987733, 31130.844345778125, 31395.84302078475, 48471.0, 49312.75343623259, 49381.506184937694, 49404.752976234886, 49412.0, 512030.0, 514021.0, 516975.0, 518274.0, 518311.0, 24896.12863549767, 25951.0, 25951.0, 25963.870180648977, 25970.87014564915, 46022.76988615169, 46099.76950115228, 46789.76605116953, 46841.297380538635, 47246.81882952954, 38067.0, 38091.80954095212, 38557.614423855404, 38947.610523895535, 39123.80438097791, 38294.617053829104, 38330.52109181142, 38599.0, 39123.69639339614, 39271.80364098161, 29086.7091329084, 36222.8188859054, 36557.0, 37179.07052323713, 37526.43710344402, 33465.49801753024, 33540.0, 33954.81158159453, 34113.0, 34123.48814767731, 20405.979701015185, 24491.0, 24745.876270618533, 25363.365915851515, 25743.0], "value": 19634.337181500337, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.96270372962704, 379.9449079883417, 385.0892638908981, 397.439610149689, 402.7643828360409, 18322.725159122623, 19659.11533980971, 20477.897610511965, 21735.23926662567, 22680.43298917528, 11714.414279286022, 12119.697007574816, 12270.938645306784, 12601.117921745481, 20818.687719684218, 5520.1443776214755, 5713.0, 5720.313562372521, 5729.968605650981, 5731.083026715726, 3980.4825372701653, 6049.788257410992, 6665.800025999228, 6994.58032518048, 7020.859582808341, 6264.56148069633, 6280.371962803706, 6889.001094841245, 7030.226675065739, 7033.788986330418, 6103.481204097657, 6266.937330626703, 6270.0, 6281.7173227204885, 6302.747890084391, 5917.112433135021, 6055.091736239555, 6107.389261073901, 6125.5099592032975, 6165.0, 14366.425342986267, 25822.48355032889, 26385.604215936775, 26851.46297074067, 26943.730562694513, 2194.7476040255256, 2370.8458950168183, 2374.9881250593808, 2380.773826486481, 2402.4561979687596, 10016.456390564988, 10322.432266225354, 10370.377777333384, 10599.311044782142, 10603.0, 1905.285517930776, 1918.7793403758467, 1920.5870737791442, 1990.6217818614416, 1990.7909669484786, 78567.0, 85236.0, 122634.38682806618, 126196.99999999908, 130882.0, 11111.111111111091, 11370.260933039492, 11664.941675291739, 11858.407079645984, 11942.34317112567, 14607.853921460863, 14714.264286785618, 14770.039947403598, 14774.039687420283, 14804.333804978802, 5986.700664966821, 6843.0077638742105, 7052.153741551047, 7128.7504937327985, 7140.714371425085, 5303.416624171338, 6295.181626388632, 6357.300696923335, 6430.807075787734, 6468.741250350027, 3855.652991230803, 4224.922644725637, 4536.886577835515, 4567.0, 4684.836030738976, 1826.3707701312583, 1963.0577322885094, 1963.1656545967905, 1970.221762403871, 1979.7826434393285, 861.4572819123912, 881.5944665453835, 945.7730144765206, 980.1178938954997, 1019.592163134752, 19345.51636209078, 20605.89697051535, 21785.673214901788, 22033.779662203495, 22114.44713882166, 964.0937518732369, 964.9517524123765, 978.1050338939932, 999.4103478947455, 1008.5612758450097, 9816.754581135528, 9981.600735970625, 11294.661160165208, 11332.2067455279, 11336.6032188873, 529663.0, 616559.0, 625192.0, 639632.0, 640003.7999809971, 135480.0, 135582.0, 135967.0, 137125.0, 138390.0, 1001.7746007148479, 1009.1119814563137, 1016.9644062457926, 1026.5175367577226, 1026.6765968719828, 15895.682086357976, 18972.525686857938, 19062.7140592894, 19093.71359429582, 19204.423867284004, 55216.0, 56596.434035660764, 56753.71623141858, 56929.430705692415, 57395.713021434625, 70511.64744176246, 71667.0, 82397.58801206195, 83299.0, 83300.0, 6050.788222412194, 8157.409305185432, 8236.588170591567, 8313.293370063613, 8447.493150410995, 9629.32594718363, 11247.831282530933, 12808.359582021048, 12854.67863303425, 13034.739305213652, 9298.907010929805, 11006.779864402506, 11250.04374628133, 11434.599789007345, 11535.442715233336, 8905.73282801517, 9313.86029209576, 11139.610113645984, 11231.831522527333, 11436.970672639169, 11481.540738370706, 11524.423778811193, 11538.423078846192, 11541.653750387502, 11746.530138794353, 6407.455366293917, 8127.674893004214, 8284.585770711561, 8296.419250652396, 8352.0, 8364.163583641588, 10463.843042354523, 10607.41659208751, 11267.774644507228, 11342.0], "value": 9404.115583516594, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1533.4632878492528, 1539.7921280627115, 1764.3471915391299, 1858.1266804601842, 1866.8413184879284, 7877.7636670899965, 8396.538190399526, 8408.327333813295, 8870.645174193032, 8899.555022248886, 6508.609483430985, 6951.165860096783, 8699.304055675546, 8763.956180219106, 8769.693060742859, 2332.1021406758437, 2348.6711860339565, 2354.0819080558595, 2369.277370402027, 2392.928212153638, 2345.7654234576576, 2362.787349138577, 2400.543896659638, 2407.8555286682767, 2408.434018005767, 2383.4279772854475, 2386.916457923973, 2399.1722855614785, 2402.5915594349003, 2403.2336232868915, 1983.4842940835488, 2357.9528409431714, 2358.740538540768, 2381.868997205153, 2381.9642705359433, 2328.4528135888054, 2354.5290941811536, 2362.0, 2368.6328619064075, 2370.2296753555165, 8852.734417967471, 8871.556422178865, 8925.107489251088, 8926.955365223132, 8935.553222338922, 5185.818496352647, 5399.136138217867, 5406.405295417501, 5421.213923981, 5422.8373148805595, 2920.6057182280547, 3083.5066389377594, 3151.196444906557, 3648.087978005499, 3762.887113386603, 1100.8568886044843, 1106.5463160104398, 1111.6276047524088, 1125.8311253311988, 1128.3399211461306, 11487.253328533701, 11663.650090497298, 11685.94157029218, 11686.181967262295, 11708.94619484242, 4598.8620341389815, 4641.6054635355695, 4821.710697358169, 4834.250691142878, 4849.345338379274, 9994.750131246632, 10013.29906906524, 10027.749306267402, 10028.949855250678, 10044.698659040241, 5287.02190094826, 5305.734713264322, 5351.732413379394, 5365.731713414314, 5367.114426119647, 4422.977885110554, 4665.6734028617675, 4844.709317440965, 4878.219484882402, 4920.089783390013, 3929.1945151243926, 3963.8612648557164, 4045.1707399982934, 4083.816228269735, 4118.794060296973, 1287.1826390242263, 1304.9021323400782, 1307.7907534794388, 1318.7164759576738, 1321.7752981993085, 909.6134142989204, 927.0, 934.1779234273796, 940.8400571902656, 989.3420875117966, 5228.529432351031, 5864.0, 6015.0, 6092.878142437126, 6112.938870611326, 442.60829166188154, 451.8373385581188, 463.4322954380924, 463.80983796643216, 991.980160396788, 3585.0320413488735, 3949.7037722170953, 4022.6982976276895, 4055.6552693020835, 4133.937990930139, 10314.27800053989, 10596.046355828012, 10744.0, 10759.892401076046, 10902.890971090346, 10722.142228621691, 10744.355338679548, 10748.838767418494, 10772.0, 10802.243842930919, 7851.842963140705, 8183.427160098855, 8228.876566851503, 8350.540720260444, 8390.958045209734, 5578.77267001259, 5981.431763982415, 6079.574429790049, 6161.630302181883, 6255.718492667932, 10078.949605251926, 10107.64623238183, 10201.489925503844, 10204.13264872494, 10238.846417303597, 9455.905440945779, 9674.613015479583, 9910.207183425164, 9954.601815927572, 10341.74145646365, 6820.215675197416, 7343.8898416522725, 7438.218987006287, 7539.0, 7634.351080158249, 8355.415120941476, 8370.87443688333, 8422.705205317785, 8437.662493500191, 8441.704540341305, 7215.278472152742, 7453.329200371993, 7636.770896873103, 7707.421943354383, 7713.537187768752, 7432.591207483424, 7438.293362130589, 7486.737964171228, 7514.211007844097, 7614.885776713464, 7084.079069720903, 7130.964345178241, 7142.178649455172, 7152.713891444284, 7193.784186474414, 4988.650794444354, 5060.063888180698, 5151.768170432265, 5166.173412254021, 5172.069027575073, 5120.103981803095, 5135.35808023997, 5162.406323272721, 5315.867103322448, 5333.97333013348], "value": 4555.2870500552535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.51723535431694, 309.83578703287253, 311.5871470301849, 313.77408266048445, 315.8720718109164, 5642.943570564293, 5663.688497132656, 5677.57418193635, 5692.153923038481, 5705.74324155413, 4946.134426475371, 5254.57963362931, 5507.339119305689, 5525.475079867416, 5582.91625625616, 3427.9828600856963, 3982.283189025974, 4088.407180958759, 4153.314703073989, 4179.495381662599, 3698.0939669780805, 3947.940780888289, 4159.438475805758, 4169.687273454488, 4203.432536607549, 3495.4057810172203, 3771.7925514096714, 4160.687948403866, 4167.520735115455, 4205.453291072156, 3111.5021596544557, 3782.205736795276, 3820.1595648957186, 4137.13120244749, 4171.749695018309, 3532.364174448598, 3541.9291614167573, 3543.840527176283, 3548.8048157351336, 3565.3938830398893, 4294.656427485794, 4863.975680121612, 5340.465953404668, 5397.163439666858, 5455.8363249102595, 4573.428321459817, 4730.172219861511, 4737.620990320766, 4744.312074749177, 4767.237242041257, 4896.02079584081, 5355.651882627617, 5730.455606717355, 5771.740271687784, 5863.0, 3916.295066887959, 3925.371940489508, 3934.9016274593077, 3943.2310699413624, 3951.5060617422823, 5179.300794392747, 5665.923474539824, 6083.787067452662, 6170.259568851721, 6198.876022479525, 3961.8613348532663, 3986.5614782373914, 3991.7804520751056, 4002.679785617144, 4005.35914253718, 3317.270200555874, 3982.123932734794, 4058.837646494166, 4059.2287465381482, 4072.490938632671, 3572.481990111471, 3627.6190999944624, 4695.929561056587, 4750.12122757291, 4793.257045158006, 4586.4266966629175, 4738.763061846894, 5822.534197264209, 5851.444112809277, 5871.73757642109, 1272.968175795594, 1342.5502456676927, 1354.6071639224515, 1367.7606418876658, 1379.5102738527878, 793.2781169136058, 805.2390490985962, 841.0160112668193, 841.0706169682535, 875.7329014650466, 4842.370491836109, 5428.240046393508, 5447.373552041487, 5509.834704958857, 5518.034343989866, 5221.477852214824, 5705.486506214377, 5960.195373624505, 6006.519478441802, 6051.939480605226, 5183.50756678115, 5463.617546771688, 5482.890342193134, 5508.779648814003, 5514.724263786875, 2888.9277768055967, 2921.8100823446407, 2983.23927398512, 3165.6201255849446, 3168.556402103707, 5175.508326708957, 5899.852503687443, 6135.662538560422, 6185.474234690102, 6213.2233470816145, 5007.924881126786, 5747.453991870766, 6078.598953413377, 6079.635221886701, 6105.542084343692, 4884.8290309838985, 5241.292425522582, 5612.971935140298, 6117.541184411098, 6169.413905678954, 5112.590992720499, 5340.866478338073, 5402.297701298806, 5654.85862853432, 5932.1229203836965, 4899.681520701143, 5224.373075230997, 5583.380819562444, 5895.764169433174, 5901.232839730807, 5514.834554963358, 5961.7615295387695, 6005.609635373861, 6050.156426479548, 6196.318404975359, 3895.668868146239, 3935.4293627424436, 3962.623550762673, 3975.986123538479, 3976.483057202661, 5213.608979326642, 5484.122540393518, 5943.58394912352, 5978.521718262616, 5988.311344195473, 4863.683860549053, 5131.358580177478, 5188.507091826271, 5218.399884013235, 5818.77805660816, 5731.484166425042, 5739.4834464896685, 5744.448998770392, 5760.251167348386, 5765.221695071112, 4704.929426058543, 5468.72656367188, 5547.694876781816, 5623.409541998195, 5625.921207124802, 4865.829695960624, 5028.673136246132, 5672.801451949162, 5842.737076831638, 5868.501177399798, 5437.178545187407, 5464.371597266207, 5737.340205876378, 5764.406017292319, 5773.41231161434], "value": 4161.845697269781, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.9785206443806, 719.4711886763231, 757.6325482141164, 771.9652615632298, 796.8645330293853, 10906.781864362718, 17595.120243987796, 17951.371701990432, 18190.81809181911, 18253.817461825376, 16370.590735231624, 16458.506244812645, 16534.0, 16539.58651033725, 16551.917240413783, 700.0654126740807, 842.2798507276283, 874.1127755328331, 902.3728508686465, 903.0247332880473, 868.9478631282143, 871.2420194430854, 880.9823803523925, 882.483747008001, 885.9025507194203, 916.9587368568431, 943.259541260111, 943.6838659049226, 945.3099237556588, 945.4043952310069, 913.4656226107735, 935.780091678455, 935.8596210568435, 936.1153709744294, 937.4796987671839, 694.8019814352936, 695.0963747128712, 699.0911814640947, 699.40899939551, 700.5551474813531, 15944.202789860461, 16006.679866402606, 16116.35534578627, 16117.0, 16138.75791863123, 13029.283389413578, 14661.780073298909, 14682.0, 14715.264236788118, 14722.0, 10061.89938100617, 11681.123915706272, 12117.272963622123, 12143.271403715806, 12681.302528360937, 3830.4828848105426, 3908.6124425828707, 3935.094928166509, 3969.2656858480987, 4059.167870586523, 106386.0, 108293.0, 128774.27354358768, 130609.34695326557, 132395.0, 11366.545338186545, 11367.431628418546, 11385.0, 11434.313941163557, 11531.250468719507, 22911.770882291297, 23065.423364416023, 23087.88456057709, 23093.191738289413, 23123.0, 8349.248567628943, 8418.368622353348, 8426.620802063922, 8429.789255268668, 8476.152384761475, 4625.283081122431, 4672.018876036002, 4678.438587369537, 4698.186734531581, 4699.506551812011, 3622.492851000862, 3742.8877133686033, 3750.981245093757, 3753.024213704402, 3755.962440375561, 650.1125963060483, 658.9341065893373, 660.6102399584269, 673.192169396726, 677.6814896998501, 557.9944200558024, 619.662284055191, 664.4817042706696, 685.1127789512593, 692.7852365766629, 9092.681756138503, 9619.951900240454, 9785.238657041658, 9799.559019844124, 10280.537375818107, 630.2594451519446, 665.7902760630386, 681.8329509270161, 716.2622498826217, 718.5975853522044, 4535.523770004168, 4624.838130665411, 4644.117617652635, 4791.928121078186, 4811.446683631427, 153399.0, 156565.0, 157621.42378576068, 159393.0, 159916.20041899715, 79076.0, 86633.0, 124953.37523312507, 127144.36427817802, 127524.0, 77337.83993240105, 94994.5250273758, 103343.0, 111313.0, 117385.4130729358, 9587.808243835223, 9972.750681233027, 10219.591216351262, 10233.84649230277, 10298.267228360417, 55330.446695532526, 55470.117091803186, 58236.41763582479, 61912.38087619066, 62195.6890215546, 79103.20896790958, 87011.0, 87130.0, 88512.06782779162, 88823.55588222017, 13313.001524885478, 13567.59297221085, 13629.727405452033, 13967.092139010932, 14136.0, 14851.405943762129, 14939.701205976036, 15001.174935378658, 15107.924460377628, 15122.666055247006, 13381.330933453482, 13814.723705526034, 13967.371468283749, 14012.019158659205, 14035.368408421442, 13766.793498097337, 14087.859121409065, 14110.647233818827, 14654.78017829712, 14833.777493337824, 11330.660080197607, 13595.864041359855, 13874.583762487142, 14023.36894839755, 14298.428062877369, 11056.841228361696, 11173.776524469302, 11173.832392514281, 11466.71333216643, 12104.0, 11021.564363940854, 11079.015795203968, 11615.361155136548, 11620.244684095507, 11660.883391166319], "value": 8040.653100522742, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1584.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 118.0, 107.0, 116.0, 122.0, 124.0, 114.0, 116.0, 117.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 131.0, 183.0, 110.0, 134.0, 124.0, 110.0, 178.0, 139.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 221.0, 268.0, 198.0, 199.0, 199.0, 198.0, 199.0, 196.0], "value": 207.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 71.0, 109.0, 59.0, 59.0, 59.0, 62.0, 135.0, 63.0], "value": 76.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 127.0, 104.0, 100.0, 84.0, 113.0, 71.0, 72.0, 114.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 87.0, 100.0, 86.0, 77.0, 129.0, 90.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 149.0, 113.0, 154.0, 150.0, 98.0, 114.0, 118.0, 97.0, 96.0], "value": 119.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 140.0, 126.0, 135.0, 139.0, 135.0, 90.0, 117.0, 123.0, 91.0], "value": 118.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 56.0, 60.0, 90.0, 85.0, 58.0, 57.0, 58.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 72.0, 68.0, 84.0, 73.0, 75.0, 72.0, 70.0, 70.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 82.0, 86.0, 84.0, 83.0, 86.0, 89.0, 81.0, 97.0, 86.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 154.0, 157.0, 146.0, 152.0, 147.0, 138.0, 137.0, 185.0, 135.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 164.0, 163.0, 167.0, 165.0, 160.0, 183.0, 223.0, 193.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 88.0, 89.0, 85.0, 75.0, 65.0, 70.0, 79.0, 87.0, 94.0], "value": 80.1, "unit": "score"}]}]} 00:10:19 INFO - Return code: 0 00:10:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:10:19 ERROR - # TBPL SUCCESS # 00:10:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:10:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:10:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:10:19 INFO - 2016-07-25 00:10:19.662 screenresolution[1670:10586] starting screenresolution argv=screenresolution get 00:10:19 INFO - 2016-07-25 00:10:19.664 screenresolution[1670:10586] Display 0: 1600x1200x32@60 00:10:19 INFO - 2016-07-25 00:10:19.670 screenresolution[1671:10589] starting screenresolution argv=screenresolution list 00:10:19 INFO - Available Modes on Display 0 00:10:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:10:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:10:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:10:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:10:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:10:19 INFO - Intel Iris: 00:10:19 INFO - Chipset Model: Intel Iris 00:10:19 INFO - Type: GPU 00:10:19 INFO - Bus: Built-In 00:10:19 INFO - VRAM (Dynamic, Max): 1536 MB 00:10:19 INFO - Vendor: Intel (0x8086) 00:10:19 INFO - Device ID: 0x0a2e 00:10:19 INFO - Revision ID: 0x0009 00:10:19 INFO - Displays: 00:10:19 INFO - Display: 00:10:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:10:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:10:19 INFO - Display Serial Number: 1600x1200 60 00:10:19 INFO - Main Display: Yes 00:10:19 INFO - Mirror: Off 00:10:19 INFO - Online: Yes 00:10:19 INFO - Rotation: Supported 00:10:19 INFO - Return code: 0 00:10:19 INFO - Running post-action listener: _resource_record_post_action 00:10:19 INFO - ##### 00:10:19 INFO - ##### Finished run-tests step (success) 00:10:19 INFO - ##### 00:10:19 INFO - Running post-run listener: _resource_record_post_run 00:10:19 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 41391616; Write bytes: 714324992; Read time: 475; Write time: 2609 00:10:19 INFO - TinderboxPrint: CPU usage
54.3% 00:10:19 INFO - TinderboxPrint: I/O read bytes / time
41,391,616 / 475 00:10:19 INFO - TinderboxPrint: I/O write bytes / time
714,324,992 / 2,609 00:10:19 INFO - TinderboxPrint: CPU idle
2,212.1 (45.7%) 00:10:19 INFO - TinderboxPrint: CPU system
974.9 (20.2%) 00:10:19 INFO - TinderboxPrint: CPU user
1,650.6 (34.1%) 00:10:19 INFO - TinderboxPrint: Swap in / out
447,528,960 / 0 00:10:19 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391279104; Write bytes: 380999168; Read time: 28921; Write time: 803 00:10:19 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 35139072; Write bytes: 303822336; Read time: 396; Write time: 1754 00:10:20 INFO - Running post-run listener: _upload_blobber_files 00:10:20 INFO - Blob upload gear active. 00:10:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:10:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 00:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:10:20 INFO - (blobuploader) - INFO - Open directory for files ... 00:10:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:10:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:10:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:10:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:10:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:10:21 INFO - (blobuploader) - INFO - Done attempting. 00:10:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:10:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:10:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:10:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:10:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:10:22 INFO - (blobuploader) - INFO - Done attempting. 00:10:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:10:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:10:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:10:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:10:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:10:26 INFO - (blobuploader) - INFO - Done attempting. 00:10:26 INFO - (blobuploader) - INFO - Iteration through files over. 00:10:26 INFO - Return code: 0 00:10:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:10:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:10:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f207bbaf3a6dbdab091c6263197f5386b9e4788db950087b6a99230f7aca269806ca0fa5d4bafc45c2e7ae6829b447b49f5e76feb3d1e886801f432809e6c804", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4d4c842a4f70a6ebea127f58b3e8f6d2c7f4c52b841f086014c60f58eadb274d101786e56fd168bbfe8a33a447413eb3b3bb176ac9b403701eb9fc34aa578d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01f7220a4399631e235173a5150296a76b2c0ac3bd2206d4edc4496cfacfb900fce87a66cae515b302ebbdcbe62cf824d12777a36c7df4d4623b3c4a86161b37"} 00:10:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:10:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:10:26 INFO - Contents: 00:10:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f207bbaf3a6dbdab091c6263197f5386b9e4788db950087b6a99230f7aca269806ca0fa5d4bafc45c2e7ae6829b447b49f5e76feb3d1e886801f432809e6c804", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4d4c842a4f70a6ebea127f58b3e8f6d2c7f4c52b841f086014c60f58eadb274d101786e56fd168bbfe8a33a447413eb3b3bb176ac9b403701eb9fc34aa578d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01f7220a4399631e235173a5150296a76b2c0ac3bd2206d4edc4496cfacfb900fce87a66cae515b302ebbdcbe62cf824d12777a36c7df4d4623b3c4a86161b37"} 00:10:26 INFO - Running post-run listener: copy_logs_to_upload_dir 00:10:26 INFO - Copying logs to upload dir... 00:10:26 INFO - mkdir: /builds/slave/test/build/upload/logs 00:10:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.701505 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-25 00:10:26.288500) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 00:10:26.291966) ========= 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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f207bbaf3a6dbdab091c6263197f5386b9e4788db950087b6a99230f7aca269806ca0fa5d4bafc45c2e7ae6829b447b49f5e76feb3d1e886801f432809e6c804", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4d4c842a4f70a6ebea127f58b3e8f6d2c7f4c52b841f086014c60f58eadb274d101786e56fd168bbfe8a33a447413eb3b3bb176ac9b403701eb9fc34aa578d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01f7220a4399631e235173a5150296a76b2c0ac3bd2206d4edc4496cfacfb900fce87a66cae515b302ebbdcbe62cf824d12777a36c7df4d4623b3c4a86161b37"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011087 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1467630117/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f207bbaf3a6dbdab091c6263197f5386b9e4788db950087b6a99230f7aca269806ca0fa5d4bafc45c2e7ae6829b447b49f5e76feb3d1e886801f432809e6c804", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4d4c842a4f70a6ebea127f58b3e8f6d2c7f4c52b841f086014c60f58eadb274d101786e56fd168bbfe8a33a447413eb3b3bb176ac9b403701eb9fc34aa578d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01f7220a4399631e235173a5150296a76b2c0ac3bd2206d4edc4496cfacfb900fce87a66cae515b302ebbdcbe62cf824d12777a36c7df4d4623b3c4a86161b37"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 00:10:26.325261) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 00:10:26.325642) ========= 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.29uZLmL1Y6/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.SEtd8XlrIO/Listeners TMPDIR=/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/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.005081 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 00:10:26.375766) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 00:10:26.376146) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 00:10:26.376554) ========= ========= Total master_lag: 0.18 =========