builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0085 starttime: 1481528800.05 results: success (0) buildid: 20161211221251 builduid: 730b75e7dcde4d809e1b767113707ddb revision: 325896b10e7cb0323e9a4e6c81a41ab8424899bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.046698) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.047159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.047496) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.195886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.196281) ========= 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.NhR3HsqfDv/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.SoVveMv3BU/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-12-11 23:46:40-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 48.2M=0s 2016-12-11 23:46:40 (48.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.197752 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.438353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.439120) ========= 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.NhR3HsqfDv/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.SoVveMv3BU/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.018965 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.492385) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:40.492762) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 325896b10e7cb0323e9a4e6c81a41ab8424899bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 325896b10e7cb0323e9a4e6c81a41ab8424899bd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NhR3HsqfDv/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.SoVveMv3BU/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-12-11 23:46:40,568 truncating revision to first 12 chars 2016-12-11 23:46:40,568 Setting DEBUG logging. 2016-12-11 23:46:40,568 attempt 1/10 2016-12-11 23:46:40,568 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/325896b10e7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-11 23:46:40,902 unpacking tar archive at: graphics-325896b10e7c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.593483 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:41.114009) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:41.114366) ========= script_repo_revision: 325896b10e7cb0323e9a4e6c81a41ab8424899bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:41.114832) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:41.115144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-11 23:46:41.136059) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-11 23:46:41.136376) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NhR3HsqfDv/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.SoVveMv3BU/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:46:41 INFO - MultiFileLogger online at 20161211 23:46:41 in /builds/slave/test 23:46:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 23:46:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:46:41 INFO - {'append_to_log': False, 23:46:41 INFO - 'base_work_dir': '/builds/slave/test', 23:46:41 INFO - 'blob_upload_branch': 'Graphics', 23:46:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:46:41 INFO - 'branch': 'Graphics', 23:46:41 INFO - 'buildbot_json_path': 'buildprops.json', 23:46:41 INFO - 'config_files': ('talos/mac_config.py',), 23:46:41 INFO - 'default_actions': ('clobber', 23:46:41 INFO - 'read-buildbot-config', 23:46:41 INFO - 'download-and-extract', 23:46:41 INFO - 'populate-webroot', 23:46:41 INFO - 'create-virtualenv', 23:46:41 INFO - 'install', 23:46:41 INFO - 'run-tests'), 23:46:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:46:41 INFO - 'download_minidump_stackwalk': True, 23:46:41 INFO - 'download_symbols': 'ondemand', 23:46:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:46:41 INFO - 'tooltool.py': '/tools/tooltool.py', 23:46:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:46:41 INFO - '/tools/misc-python/virtualenv.py')}, 23:46:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:46:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:46:41 INFO - 'installer_path': 'installer.exe', 23:46:41 INFO - 'log_level': 'info', 23:46:41 INFO - 'log_name': 'talos', 23:46:41 INFO - 'log_to_console': True, 23:46:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:46:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:46:41 INFO - 'opt_config_files': (), 23:46:41 INFO - 'pip_index': False, 23:46:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:46:41 INFO - 'cmd': ('bash', 23:46:41 INFO - '-c', 23:46:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:46:41 INFO - 'enabled': True, 23:46:41 INFO - 'halt_on_failure': False, 23:46:41 INFO - 'name': 'check_screen_resolution'},), 23:46:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:46:41 INFO - 'cmd': ('bash', 23:46:41 INFO - '-c', 23:46:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:46:41 INFO - 'enabled': True, 23:46:41 INFO - 'halt_on_failure': False, 23:46:41 INFO - 'name': 'check_screen_resolution'},), 23:46:41 INFO - 'run_cmd_checks_enabled': True, 23:46:41 INFO - 'sps_profile': False, 23:46:41 INFO - 'sps_profile_interval': 0, 23:46:41 INFO - 'suite': 'dromaeojs-e10s', 23:46:41 INFO - 'system_bits': '32', 23:46:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:46:41 INFO - 'title': 't-yosemite-r7-0085', 23:46:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:46:41 INFO - 'use_talos_json': True, 23:46:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:46:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:46:41 INFO - 'work_dir': 'build'} 23:46:41 INFO - [mozharness: 2016-12-12 07:46:41.334493Z] Running clobber step. 23:46:41 INFO - Running pre-action listener: _resource_record_pre_action 23:46:41 INFO - Running main action method: clobber 23:46:41 INFO - rmtree: /builds/slave/test/build 23:46:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:46:43 INFO - Running post-action listener: _resource_record_post_action 23:46:43 INFO - [mozharness: 2016-12-12 07:46:43.053825Z] Finished clobber step (success) 23:46:43 INFO - [mozharness: 2016-12-12 07:46:43.054013Z] Running read-buildbot-config step. 23:46:43 INFO - Running pre-action listener: _resource_record_pre_action 23:46:43 INFO - Running main action method: read_buildbot_config 23:46:43 INFO - Using buildbot properties: 23:46:43 INFO - { 23:46:43 INFO - "project": "", 23:46:43 INFO - "product": "firefox", 23:46:43 INFO - "script_repo_revision": "default", 23:46:43 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 23:46:43 INFO - "repository": "", 23:46:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 23:46:43 INFO - "buildid": "20161211221251", 23:46:43 INFO - "pgo_build": "False", 23:46:43 INFO - "basedir": "/builds/slave/test", 23:46:43 INFO - "buildnumber": 5, 23:46:43 INFO - "slavename": "t-yosemite-r7-0085", 23:46:43 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 23:46:43 INFO - "platform": "yosemite_r7", 23:46:43 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 23:46:43 INFO - "branch": "graphics", 23:46:43 INFO - "repo_path": "projects/graphics", 23:46:43 INFO - "revision": "325896b10e7cb0323e9a4e6c81a41ab8424899bd", 23:46:43 INFO - "stage_platform": "macosx64", 23:46:43 INFO - "builduid": "730b75e7dcde4d809e1b767113707ddb", 23:46:43 INFO - "slavebuilddir": "test" 23:46:43 INFO - } 23:46:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg. 23:46:43 INFO - Running post-action listener: _resource_record_post_action 23:46:43 INFO - [mozharness: 2016-12-12 07:46:43.059554Z] Finished read-buildbot-config step (success) 23:46:43 INFO - [mozharness: 2016-12-12 07:46:43.059772Z] Running download-and-extract step. 23:46:43 INFO - Running pre-action listener: _resource_record_pre_action 23:46:43 INFO - Running main action method: download_and_extract 23:46:43 INFO - mkdir: /builds/slave/test/build/tests 23:46: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:46:43 INFO - trying https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json 23:46:43 INFO - Downloading https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 23:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json'}, attempt #1 23:46:44 INFO - Downloaded 1235 bytes. 23:46:44 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 23:46:44 INFO - Using the following test package requirements: 23:46:44 INFO - {u'common': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 23:46:44 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'firefox-53.0a1.en-US.mac64.cppunittest.tests.zip'], 23:46:44 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'firefox-53.0a1.en-US.mac64.gtest.tests.zip'], 23:46:44 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'jsshell-mac64.zip'], 23:46:44 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'firefox-53.0a1.en-US.mac64.mochitest.tests.zip'], 23:46:44 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 23:46:44 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'firefox-53.0a1.en-US.mac64.reftest.tests.zip'], 23:46:44 INFO - u'talos': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'firefox-53.0a1.en-US.mac64.talos.tests.zip'], 23:46:44 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'firefox-53.0a1.en-US.mac64.web-platform.tests.zip'], 23:46:44 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 23:46:44 INFO - u'firefox-53.0a1.en-US.mac64.xpcshell.tests.zip']} 23:46:44 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip'] for test suite category: common 23:46:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 23:46:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 23:46:44 INFO - Fetch https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 23:46:45 INFO - Expected file size: 16287270 23:46:45 INFO - Obtained file size: 16287270 23:46:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip', u'firefox-53.0a1.en-US.mac64.talos.tests.zip'] for test suite category: talos 23:46:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 23:46:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 23:46:52 INFO - Fetch https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 23:46:52 INFO - Expected file size: 16287270 23:46:52 INFO - Obtained file size: 16287270 23:46:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip 23:46:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip'}, attempt #1 23:46:59 INFO - Fetch https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip into memory 23:47:00 INFO - Expected file size: 14409734 23:47:00 INFO - Obtained file size: 14409734 23:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:00 INFO - trying https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 23:47:00 INFO - Downloading https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg 23:47:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg'}, attempt #1 23:47:02 INFO - Downloaded 69777943 bytes. 23:47:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 23:47:02 INFO - mkdir: /builds/slave/test/properties 23:47:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:47:02 INFO - Writing to file /builds/slave/test/properties/build_url 23:47:02 INFO - Contents: 23:47:02 INFO - build_url:https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 23:47:03 INFO - Running post-action listener: _resource_record_post_action 23:47:03 INFO - Running post-action listener: set_extra_try_arguments 23:47:03 INFO - [mozharness: 2016-12-12 07:47:03.342450Z] Finished download-and-extract step (success) 23:47:03 INFO - [mozharness: 2016-12-12 07:47:03.342561Z] Running populate-webroot step. 23:47:03 INFO - Running pre-action listener: _resource_record_pre_action 23:47:03 INFO - Running main action method: populate_webroot 23:47:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:47:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:47:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:47:03 INFO - '--tppagecycles', 23:47:03 INFO - '7'], 23:47:03 INFO - 'tests': ['tsvgm']}}, 23:47:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:47:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:47:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:47:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:47:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:47:03 INFO - 'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:47:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:47:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:47:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:47:03 INFO - 'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['damp', 'tps']}, 23:47:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:47:03 INFO - 'tests': ['damp', 'tps']}, 23:47:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['dromaeo_dom']}, 23:47:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:47:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['basic_compositor_video']}, 23:47:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:47:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['a11yr', 23:47:03 INFO - 'ts_paint', 23:47:03 INFO - 'tpaint', 23:47:03 INFO - 'sessionrestore', 23:47:03 INFO - 'sessionrestore_no_auto_restore', 23:47:03 INFO - 'tabpaint']}, 23:47:03 INFO - 'other-e10s': {'tests': ['a11yr', 23:47:03 INFO - 'ts_paint', 23:47:03 INFO - 'tpaint', 23:47:03 INFO - 'sessionrestore', 23:47:03 INFO - 'sessionrestore_no_auto_restore', 23:47:03 INFO - 'tabpaint']}, 23:47:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:47:03 INFO - 'ts_paint', 23:47:03 INFO - 'tpaint', 23:47:03 INFO - 'sessionrestore', 23:47:03 INFO - 'sessionrestore_no_auto_restore', 23:47:03 INFO - 'tabpaint']}, 23:47:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:47:03 INFO - 'ts_paint', 23:47:03 INFO - 'tpaint', 23:47:03 INFO - 'sessionrestore', 23:47:03 INFO - 'sessionrestore_no_auto_restore', 23:47:03 INFO - 'tabpaint']}, 23:47:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['a11yr', 23:47:03 INFO - 'ts_paint', 23:47:03 INFO - 'tpaint', 23:47:03 INFO - 'sessionrestore', 23:47:03 INFO - 'sessionrestore_no_auto_restore', 23:47:03 INFO - 'tabpaint']}, 23:47:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['a11yr', 23:47:03 INFO - 'ts_paint', 23:47:03 INFO - 'tpaint', 23:47:03 INFO - 'sessionrestore', 23:47:03 INFO - 'sessionrestore_no_auto_restore', 23:47:03 INFO - 'tabpaint']}, 23:47:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['tsvgx', 23:47:03 INFO - 'tsvgr_opacity', 23:47:03 INFO - 'tart', 23:47:03 INFO - 'tscrollx', 23:47:03 INFO - 'cart', 23:47:03 INFO - 'tsvg_static']}, 23:47:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:47:03 INFO - 'tsvgr_opacity', 23:47:03 INFO - 'tart', 23:47:03 INFO - 'tscrollx', 23:47:03 INFO - 'cart', 23:47:03 INFO - 'tsvg_static']}, 23:47:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:47:03 INFO - 'talos_options': ['--disable-e10s'], 23:47:03 INFO - 'tests': ['tp5o']}, 23:47:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:47:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:47:03 INFO - 'talos_options': ['--disable-e10s', 23:47:03 INFO - '--xperf_path', 23:47:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:47:03 INFO - 'tests': ['tp5n']}, 23:47:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:47:03 INFO - 'talos_options': ['--xperf_path', 23:47:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:47:03 INFO - 'tests': ['tp5n']}}, 23:47:03 INFO - 'talos.zip': {'path': '', 23:47:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:47:03 INFO - Running post-action listener: _resource_record_post_action 23:47:03 INFO - [mozharness: 2016-12-12 07:47:03.361219Z] Finished populate-webroot step (success) 23:47:03 INFO - [mozharness: 2016-12-12 07:47:03.361306Z] Running create-virtualenv step. 23:47:03 INFO - Running pre-action listener: _resource_record_pre_action 23:47:03 INFO - Running main action method: create_virtualenv 23:47:03 INFO - Creating virtualenv /builds/slave/test/build/venv 23:47:03 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:47:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:47:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:47:03 INFO - Using real prefix '/tools/python27' 23:47:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:47:04 INFO - Installing distribute.............................................................................................................................................................................................done. 23:47:07 INFO - Installing pip.................done. 23:47:07 INFO - Return code: 0 23:47:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:47:07 INFO - Reading from file tmpfile_stdout 23:47:07 INFO - Output received: 23:47:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:47:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:07 INFO - 'HOME': '/Users/cltbld', 23:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:07 INFO - 'LOGNAME': 'cltbld', 23:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:07 INFO - 'MOZ_NO_REMOTE': '1', 23:47:07 INFO - 'NO_EM_RESTART': '1', 23:47:07 INFO - 'PAGER': '/bin/cat', 23:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:07 INFO - 'PWD': '/builds/slave/test', 23:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:07 INFO - 'SHELL': '/bin/bash', 23:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:07 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:07 INFO - 'USER': 'cltbld', 23:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:07 INFO - 'XPC_FLAGS': '0x0', 23:47:07 INFO - 'XPC_SERVICE_NAME': '0', 23:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:07 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:47:07 INFO - Cleaning up... 23:47:07 INFO - Return code: 0 23:47:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:07 INFO - 'HOME': '/Users/cltbld', 23:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:07 INFO - 'LOGNAME': 'cltbld', 23:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:07 INFO - 'MOZ_NO_REMOTE': '1', 23:47:07 INFO - 'NO_EM_RESTART': '1', 23:47:07 INFO - 'PAGER': '/bin/cat', 23:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:07 INFO - 'PWD': '/builds/slave/test', 23:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:07 INFO - 'SHELL': '/bin/bash', 23:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:07 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:07 INFO - 'USER': 'cltbld', 23:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:07 INFO - 'XPC_FLAGS': '0x0', 23:47:07 INFO - 'XPC_SERVICE_NAME': '0', 23:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:07 INFO - Downloading/unpacking psutil>=3.1.1 23:47:07 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:47:07 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:47:07 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:47:07 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:47:07 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:47:07 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:47:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:47:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:47:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:47:11 INFO - Installing collected packages: psutil 23:47:11 INFO - Running setup.py install for psutil 23:47:11 INFO - building 'psutil._psutil_osx' extension 23:47:11 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:47:11 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:47:11 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:47:11 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:47:12 INFO - building 'psutil._psutil_posix' extension 23:47:12 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:47:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:47:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:47:12 INFO - ^ 23:47:12 INFO - 1 warning generated. 23:47:12 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:47:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:47:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:47:12 INFO - Successfully installed psutil 23:47:12 INFO - Cleaning up... 23:47:12 INFO - Return code: 0 23:47:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:47:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:12 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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:12 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:47:12 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:47:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:12 INFO - 'HOME': '/Users/cltbld', 23:47:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:12 INFO - 'LOGNAME': 'cltbld', 23:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:12 INFO - 'MOZ_NO_REMOTE': '1', 23:47:12 INFO - 'NO_EM_RESTART': '1', 23:47:12 INFO - 'PAGER': '/bin/cat', 23:47:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:12 INFO - 'PWD': '/builds/slave/test', 23:47:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:12 INFO - 'SHELL': '/bin/bash', 23:47:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:12 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:12 INFO - 'USER': 'cltbld', 23:47:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:12 INFO - 'XPC_FLAGS': '0x0', 23:47:12 INFO - 'XPC_SERVICE_NAME': '0', 23:47:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:47:12 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:47:12 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:47:12 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:47:12 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:47:12 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:47:12 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:47:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:47:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:47:15 INFO - Installing collected packages: mozsystemmonitor 23:47:15 INFO - Running setup.py install for mozsystemmonitor 23:47:15 INFO - Successfully installed mozsystemmonitor 23:47:15 INFO - Cleaning up... 23:47:15 INFO - Return code: 0 23:47:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:47:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:16 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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:16 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:47:16 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:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:16 INFO - 'HOME': '/Users/cltbld', 23:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:16 INFO - 'LOGNAME': 'cltbld', 23:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:16 INFO - 'MOZ_NO_REMOTE': '1', 23:47:16 INFO - 'NO_EM_RESTART': '1', 23:47:16 INFO - 'PAGER': '/bin/cat', 23:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:16 INFO - 'PWD': '/builds/slave/test', 23:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:16 INFO - 'SHELL': '/bin/bash', 23:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:16 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:16 INFO - 'USER': 'cltbld', 23:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:16 INFO - 'XPC_FLAGS': '0x0', 23:47:16 INFO - 'XPC_SERVICE_NAME': '0', 23:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:17 INFO - Downloading/unpacking jsonschema==2.5.1 23:47:17 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:47:17 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:47:17 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:47:17 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:47:17 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:47:17 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:47:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:47:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:47:20 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:47:20 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:47:20 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:47:20 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:47:20 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:47:20 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:47:20 INFO - Downloading functools32-3.2.3-2.tar.gz 23:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:47:20 INFO - warning: no files found matching '*.txt' 23:47:20 INFO - no previously-included directories found matching 'build' 23:47:20 INFO - no previously-included directories found matching 'dist' 23:47:20 INFO - no previously-included directories found matching '.git*' 23:47:20 INFO - Installing collected packages: jsonschema, functools32 23:47:20 INFO - Running setup.py install for functools32 23:47:20 INFO - warning: no files found matching '*.txt' 23:47:20 INFO - no previously-included directories found matching 'build' 23:47:20 INFO - no previously-included directories found matching 'dist' 23:47:20 INFO - no previously-included directories found matching '.git*' 23:47:20 INFO - Successfully installed jsonschema functools32 23:47:20 INFO - Cleaning up... 23:47:20 INFO - Return code: 0 23:47:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:47:20 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:20 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:20 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:20 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:47:20 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 functools32==3.2.3-2 23:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:20 INFO - 'HOME': '/Users/cltbld', 23:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:20 INFO - 'LOGNAME': 'cltbld', 23:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:20 INFO - 'MOZ_NO_REMOTE': '1', 23:47:20 INFO - 'NO_EM_RESTART': '1', 23:47:20 INFO - 'PAGER': '/bin/cat', 23:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:20 INFO - 'PWD': '/builds/slave/test', 23:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:20 INFO - 'SHELL': '/bin/bash', 23:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:20 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:20 INFO - 'USER': 'cltbld', 23:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:20 INFO - 'XPC_FLAGS': '0x0', 23:47:20 INFO - 'XPC_SERVICE_NAME': '0', 23:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:47:21 INFO - Cleaning up... 23:47:21 INFO - Return code: 0 23:47:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:47:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:21 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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:21 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:47:21 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:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:21 INFO - 'HOME': '/Users/cltbld', 23:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:21 INFO - 'LOGNAME': 'cltbld', 23:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:21 INFO - 'MOZ_NO_REMOTE': '1', 23:47:21 INFO - 'NO_EM_RESTART': '1', 23:47:21 INFO - 'PAGER': '/bin/cat', 23:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:21 INFO - 'PWD': '/builds/slave/test', 23:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:21 INFO - 'SHELL': '/bin/bash', 23:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:21 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:21 INFO - 'USER': 'cltbld', 23:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:21 INFO - 'XPC_FLAGS': '0x0', 23:47:21 INFO - 'XPC_SERVICE_NAME': '0', 23:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:21 INFO - Downloading/unpacking blobuploader==1.2.4 23:47:21 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:47:21 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:47:21 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:47:21 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:47:21 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:47:21 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:47:24 INFO - Downloading blobuploader-1.2.4.tar.gz 23:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:47:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:47:24 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:47:24 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:47:24 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:47:24 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:47:24 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:47:24 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:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:47:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:47:24 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:47:24 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:47:24 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:47:24 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:47:24 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:47:24 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:47:25 INFO - Downloading docopt-0.6.1.tar.gz 23:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:47:25 INFO - Installing collected packages: blobuploader, requests, docopt 23:47:25 INFO - Running setup.py install for blobuploader 23:47:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:47:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:47:25 INFO - Running setup.py install for requests 23:47:26 INFO - Running setup.py install for docopt 23:47:26 INFO - Successfully installed blobuploader requests docopt 23:47:26 INFO - Cleaning up... 23:47:26 INFO - Return code: 0 23:47:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:26 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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:26 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:47:26 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:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:26 INFO - 'HOME': '/Users/cltbld', 23:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:26 INFO - 'LOGNAME': 'cltbld', 23:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:26 INFO - 'MOZ_NO_REMOTE': '1', 23:47:26 INFO - 'NO_EM_RESTART': '1', 23:47:26 INFO - 'PAGER': '/bin/cat', 23:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:26 INFO - 'PWD': '/builds/slave/test', 23:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:26 INFO - 'SHELL': '/bin/bash', 23:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:26 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:26 INFO - 'USER': 'cltbld', 23:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:26 INFO - 'XPC_FLAGS': '0x0', 23:47:26 INFO - 'XPC_SERVICE_NAME': '0', 23:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:47:26 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-G5XxXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:47:26 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-q_82ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:47:26 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-8Y9Dzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:47:26 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-vprEnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-uVq_LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-WOvB3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-J1txYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-n9C8yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-VN_AL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-a6XDWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Z51mYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-TP62lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-mHoY7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:47:27 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-V60Yvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:28 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-w6SmuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:47:28 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-yLQu5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:47:28 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-gmrReg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:47:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:47:28 INFO - Running setup.py install for manifestparser 23:47:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:47:28 INFO - Running setup.py install for mozcrash 23:47:28 INFO - Running setup.py install for mozdebug 23:47:28 INFO - Running setup.py install for mozdevice 23:47:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:47:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Running setup.py install for mozfile 23:47:29 INFO - Running setup.py install for mozhttpd 23:47:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Running setup.py install for mozinfo 23:47:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Running setup.py install for mozInstall 23:47:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Running setup.py install for mozleak 23:47:29 INFO - Running setup.py install for mozlog 23:47:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:47:29 INFO - Running setup.py install for moznetwork 23:47:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:47:30 INFO - Running setup.py install for mozprocess 23:47:30 INFO - Running setup.py install for mozprofile 23:47:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:47:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:47:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:47:30 INFO - Running setup.py install for mozrunner 23:47:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:47:30 INFO - Running setup.py install for mozscreenshot 23:47:30 INFO - Running setup.py install for moztest 23:47:30 INFO - Running setup.py install for mozversion 23:47:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:47:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:47:30 INFO - Cleaning up... 23:47:30 INFO - Return code: 0 23:47:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:30 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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:30 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:47:30 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:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:30 INFO - 'HOME': '/Users/cltbld', 23:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:30 INFO - 'LOGNAME': 'cltbld', 23:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:30 INFO - 'MOZ_NO_REMOTE': '1', 23:47:30 INFO - 'NO_EM_RESTART': '1', 23:47:30 INFO - 'PAGER': '/bin/cat', 23:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:30 INFO - 'PWD': '/builds/slave/test', 23:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:30 INFO - 'SHELL': '/bin/bash', 23:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:30 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:30 INFO - 'USER': 'cltbld', 23:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:30 INFO - 'XPC_FLAGS': '0x0', 23:47:30 INFO - 'XPC_SERVICE_NAME': '0', 23:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-MEFoz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:47:31 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:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-X4jbLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-itRioH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:47:31 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:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-u09cfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-m7WPmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:47:31 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:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-zj2q9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:47:31 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:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-mB7gYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:47:31 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:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:47:31 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-9uD33s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:47:32 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-WyghiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:47:32 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-OmGVrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-AMTzA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:47:32 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-N9qhx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-Q_4itf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:47:32 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-W5JLtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-k4z3p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:32 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-KMN3Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:47:32 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:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:47:32 INFO - Running setup.py (path:/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/pip-GfLPgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:47:33 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:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:47:33 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:47:33 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:47:33 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:47:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:47:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:47:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:47:35 INFO - Downloading blessings-1.6.tar.gz 23:47:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:47:35 INFO - Installing collected packages: blessings 23:47:35 INFO - Running setup.py install for blessings 23:47:35 INFO - Successfully installed blessings 23:47:35 INFO - Cleaning up... 23:47:36 INFO - Return code: 0 23:47:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:47:36 INFO - Reading from file tmpfile_stdout 23:47:36 INFO - Current package versions: 23:47:36 INFO - blessings == 1.6 23:47:36 INFO - blobuploader == 1.2.4 23:47:36 INFO - docopt == 0.6.1 23:47:36 INFO - functools32 == 3.2.3-2 23:47:36 INFO - jsonschema == 2.5.1 23:47:36 INFO - manifestparser == 1.1 23:47:36 INFO - mozInstall == 1.12 23:47:36 INFO - mozcrash == 1.0 23:47:36 INFO - mozdebug == 0.1 23:47:36 INFO - mozdevice == 0.49 23:47:36 INFO - mozfile == 1.2 23:47:36 INFO - mozhttpd == 0.7 23:47:36 INFO - mozinfo == 0.9 23:47:36 INFO - mozleak == 0.1 23:47:36 INFO - mozlog == 3.4 23:47:36 INFO - moznetwork == 0.27 23:47:36 INFO - mozprocess == 0.23 23:47:36 INFO - mozprofile == 0.28 23:47:36 INFO - mozrunner == 6.13 23:47:36 INFO - mozscreenshot == 0.1 23:47:36 INFO - mozsystemmonitor == 0.3 23:47:36 INFO - moztest == 0.7 23:47:36 INFO - mozversion == 1.4 23:47:36 INFO - psutil == 3.1.1 23:47:36 INFO - requests == 1.2.3 23:47:36 INFO - wsgiref == 0.1.2 23:47:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:47: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:36 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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:36 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:47:36 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:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:36 INFO - 'HOME': '/Users/cltbld', 23:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:36 INFO - 'LOGNAME': 'cltbld', 23:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:36 INFO - 'MOZ_NO_REMOTE': '1', 23:47:36 INFO - 'NO_EM_RESTART': '1', 23:47:36 INFO - 'PAGER': '/bin/cat', 23:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:36 INFO - 'PWD': '/builds/slave/test', 23:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:36 INFO - 'SHELL': '/bin/bash', 23:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:36 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:36 INFO - 'USER': 'cltbld', 23:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:36 INFO - 'XPC_FLAGS': '0x0', 23:47:36 INFO - 'XPC_SERVICE_NAME': '0', 23:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:36 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:47:36 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:47:36 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:47:36 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:47:36 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:47:36 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:47:36 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:47:36 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:47:36 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:47:36 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:47:36 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:47:36 INFO - Cleaning up... 23:47:36 INFO - Return code: 0 23:47:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:47: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:36 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 0x10447b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbcc1b00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, '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.NhR3HsqfDv/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.SoVveMv3BU/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:47:36 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:47:36 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:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:36 INFO - 'HOME': '/Users/cltbld', 23:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:36 INFO - 'LOGNAME': 'cltbld', 23:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:36 INFO - 'MOZ_NO_REMOTE': '1', 23:47:36 INFO - 'NO_EM_RESTART': '1', 23:47:36 INFO - 'PAGER': '/bin/cat', 23:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:36 INFO - 'PWD': '/builds/slave/test', 23:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:36 INFO - 'SHELL': '/bin/bash', 23:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:36 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:36 INFO - 'USER': 'cltbld', 23:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:36 INFO - 'XPC_FLAGS': '0x0', 23:47:36 INFO - 'XPC_SERVICE_NAME': '0', 23:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:47:36 INFO - Cleaning up... 23:47:36 INFO - Return code: 0 23:47:36 INFO - Running post-action listener: _resource_record_post_action 23:47:36 INFO - Running post-action listener: _start_resource_monitoring 23:47:36 INFO - Starting resource monitoring. 23:47:36 INFO - [mozharness: 2016-12-12 07:47:36.870305Z] Finished create-virtualenv step (success) 23:47:36 INFO - [mozharness: 2016-12-12 07:47:36.871298Z] Running install step. 23:47:36 INFO - Running pre-action listener: _resource_record_pre_action 23:47:36 INFO - Running main action method: install 23:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:47:37 INFO - Reading from file tmpfile_stdout 23:47:37 INFO - Detecting whether we're running mozinstall >=1.0... 23:47:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:47:37 INFO - Reading from file tmpfile_stdout 23:47:37 INFO - Output received: 23:47:37 INFO - Usage: mozinstall [options] installer 23:47:37 INFO - Options: 23:47:37 INFO - -h, --help show this help message and exit 23:47:37 INFO - -d DEST, --destination=DEST 23:47:37 INFO - Directory to install application into. [default: 23:47:37 INFO - "/builds/slave/test"] 23:47:37 INFO - --app=APP Application being installed. [default: firefox] 23:47:37 INFO - mkdir: /builds/slave/test/build/application 23:47:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg', '--destination', '/builds/slave/test/build/application'] 23:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg --destination /builds/slave/test/build/application 23:47:59 INFO - Reading from file tmpfile_stdout 23:47:59 INFO - Output received: 23:47:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:47:59 INFO - Running post-action listener: _resource_record_post_action 23:47:59 INFO - [mozharness: 2016-12-12 07:47:59.232378Z] Finished install step (success) 23:47:59 INFO - [mozharness: 2016-12-12 07:47:59.232507Z] Running run-tests step. 23:47:59 INFO - Running pre-action listener: _resource_record_pre_action 23:47:59 INFO - Running main action method: run_tests 23:47:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:47:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:47:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:47:59 INFO - 2016-12-11 23:47:59.246 screenresolution[1903:20407] starting screenresolution argv=screenresolution get 23:47:59 INFO - 2016-12-11 23:47:59.249 screenresolution[1903:20407] Display 0: 1600x1200x32@60 23:47:59 INFO - 2016-12-11 23:47:59.254 screenresolution[1904:20410] starting screenresolution argv=screenresolution list 23:47:59 INFO - Available Modes on Display 0 23:47:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:47:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:47:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:47:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:47:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:47:59 INFO - Intel Iris: 23:47:59 INFO - Chipset Model: Intel Iris 23:47:59 INFO - Type: GPU 23:47:59 INFO - Bus: Built-In 23:47:59 INFO - VRAM (Dynamic, Max): 1536 MB 23:47:59 INFO - Vendor: Intel (0x8086) 23:47:59 INFO - Device ID: 0x0a2e 23:47:59 INFO - Revision ID: 0x0009 23:47:59 INFO - Displays: 23:47:59 INFO - Display: 23:47:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:47:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:47:59 INFO - Display Serial Number: 1600x1200 60 23:47:59 INFO - Main Display: Yes 23:47:59 INFO - Mirror: Off 23:47:59 INFO - Online: Yes 23:47:59 INFO - Rotation: Supported 23:47:59 INFO - Return code: 0 23:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:47:59 INFO - Python 2.7.3 23:47:59 INFO - Return code: 0 23:47:59 INFO - grabbing minidump binary from tooltool 23:47: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:59 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 0x104290d30>, '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 0x1040f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104288b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:47:59 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:47:59 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:47:59 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:47:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:47:59 INFO - Return code: 0 23:47:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:47:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:47:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:47:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:47:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.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:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.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:47:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhR3HsqfDv/Render', 23:47:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:59 INFO - 'HOME': '/Users/cltbld', 23:47:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:59 INFO - 'LOGNAME': 'cltbld', 23:47:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:47:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:59 INFO - 'MOZ_NO_REMOTE': '1', 23:47:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:47:59 INFO - 'NO_EM_RESTART': '1', 23:47:59 INFO - 'PAGER': '/bin/cat', 23:47:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:59 INFO - 'PWD': '/builds/slave/test', 23:47:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:47:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:59 INFO - 'SHELL': '/bin/bash', 23:47:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SoVveMv3BU/Listeners', 23:47:59 INFO - 'TMPDIR': '/var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/', 23:47:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:59 INFO - 'USER': 'cltbld', 23:47:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:59 INFO - 'XPC_FLAGS': '0x0', 23:47:59 INFO - 'XPC_SERVICE_NAME': '0', 23:47:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.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:47:59 INFO - mozversion application_buildid: 20161211221251 23:47:59 INFO - mozversion application_changeset: 325896b10e7cb0323e9a4e6c81a41ab8424899bd 23:47:59 INFO - mozversion application_display_name: Nightly 23:47:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:47:59 INFO - mozversion application_name: Firefox 23:47:59 INFO - mozversion application_remotingname: firefox 23:47:59 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 23:47:59 INFO - mozversion application_vendor: Mozilla 23:47:59 INFO - mozversion application_version: 53.0a1 23:47:59 INFO - mozversion platform_buildid: 20161211221251 23:47:59 INFO - mozversion platform_changeset: 325896b10e7cb0323e9a4e6c81a41ab8424899bd 23:47:59 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 23:47:59 INFO - mozversion platform_version: 53.0a1 23:47:59 INFO - using testdate: 1481528879 23:47:59 INFO - actual date: 1481528879 23:47:59 INFO - starting webserver on 'localhost:56170' 23:47:59 INFO - SUITE-START | Running 2 tests 23:47:59 INFO - TEST-START | dromaeo_css 23:47:59 INFO - operating with platform_type : mac_ 23:47:59 INFO - Initialising browser for dromaeo_css test... 23:47:59 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpsCUiHR/profile http://localhost:56170/getInfo.html) 23:48:02 INFO - PROCESS | 1914 | 2016-12-11 23:48:02.913 plugin-container[1916:20566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 23:48:02 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:48:02 INFO - PROCESS | 1914 | 2016-12-11 23:48:02.916 plugin-container[1916:20566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9603, name = 'com.apple.CFPasteboardClient' 23:48:02 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:48:02 INFO - PROCESS | 1914 | 2016-12-11 23:48:02.916 plugin-container[1916:20566] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:48:02 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 23:48:02 INFO - PROCESS | 1914 | colorDepth:24 23:48:02 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 23:48:02 INFO - PROCESS | 1914 | __metrics 23:48:03 ERROR - PROCESS | 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 23:48:03 INFO - TEST-INFO | 1914: exit 0 23:48:03 INFO - Browser initialized. 23:48:03 INFO - Running cycle 1/1 for dromaeo_css test... 23:48:03 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpsCUiHR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:48:06 INFO - PROCESS | 1917 | 2016-12-11 23:48:06.049 plugin-container[1918:20690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 23:48:06 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:48:06 INFO - PROCESS | 1917 | 2016-12-11 23:48:06.049 plugin-container[1918:20690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 23:48:06 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:48:06 INFO - PROCESS | 1917 | 2016-12-11 23:48:06.049 plugin-container[1918:20690] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:48:15 INFO - PROCESS | 1917 | RSS: Main: 237105152 23:48:15 INFO - PROCESS | 1917 | 23:49:05 INFO - PROCESS | 1917 | 1481528945357 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:51:19 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:56170/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56170/tests/dromaeo/cssquery-ext.html) 23:51:21 INFO - PROCESS | 1917 | RSS: Main: 250048512 23:51:21 INFO - PROCESS | 1917 | 23:54:24 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:56170/tests/dromaeo/cssquery-ext.html (next: http://localhost:56170/tests/dromaeo/cssquery-jquery.html) 23:54:24 INFO - PROCESS | 1917 | RSS: Main: 267984896 23:54:24 INFO - PROCESS | 1917 | 23:57:27 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:56170/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56170/tests/dromaeo/cssquery-mootools.html) 23:57:27 INFO - PROCESS | 1917 | RSS: Main: 272207872 23:57:27 INFO - PROCESS | 1917 | 00:00:29 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:56170/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56170/tests/dromaeo/cssquery-prototype.html) 00:00:30 INFO - PROCESS | 1917 | RSS: Main: 279568384 00:00:30 INFO - PROCESS | 1917 | 00:03:33 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:56170/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56170/tests/dromaeo/cssquery-yui.html) 00:03:33 INFO - PROCESS | 1917 | RSS: Main: 279257088 00:03:33 INFO - PROCESS | 1917 | 00:06:36 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:56170/tests/dromaeo/cssquery-yui.html (next: http://localhost:56170/tests/dromaeo/cssquery-dojo.html) 00:06:37 INFO - PROCESS | 1917 | RSS: Main: 277110784 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | __start_tp_report 00:06:37 INFO - PROCESS | 1917 | _x_x_mozilla_page_load 00:06:37 INFO - PROCESS | 1917 | _x_x_mozilla_page_load_details 00:06:37 INFO - PROCESS | 1917 | |i|pagename|runs| 00:06:37 INFO - PROCESS | 1917 | |0;dojo.html;3402;3406.1825161961124;3424.2295483516227;3431.5538979932603;3455.8963231103066;5772.163036359725;5784.855378615537;5820.53435725142;5823.242978412805;5840.182374467578;5589.273394458714;5697.173909783079;5699.715014249292;5731.226284451598;5746.281714785652;5179.093658609729;5196.480351964801;5276.894462110756;5335.866603334909;5357.142857142858;5243.449437809032;5257.737113144347;5284.920726189091;5292.311999440068;5315.255864179018;5192.974035129838;5284.339457567804;5308.628396012281;5339.759710813003;5420.051490989081;4968.90062198754;5117.462666420028;5275.997560463499;5276.366835979668;5337.146056630959;4185.121124563845;4695.225287827504;5091.66904151229;5103.260027296022;5148.588112950955;4655.930161047587;5675.432456754333;5764.07774756037;5783.566232532535;5804.303483581955;6132.172156758869;6134;6216.067589861512;6225.377462253784;6247.5939063960695;5074.883525624394;6219.502439804804;6235.127082208494;6238.251409830804;6257.687115644245;4862.927056094161;5701.914471282934;5763.106718458645;5840.182374467578;5950.345461999176;6316.177422728629;6341.302456729756;6359.236891573041;6380.55336126476;6414.102025716404;6128.601640893327;6148.885084036735;6159.907601385983;6215.595986260879;6248.250209974741;5553.111502159716;5616.719164041781;5769.307683077974;5812.476877081083;6106.430331452215;6160.168377269011;6162.414570615784;6215.689215539205;6229.283632382243;6240.687965601702;6026.035834266497;6066.272047354346;6233.812985610439;6242.563020588515;6245.250569931637;4632.420947381577;4851.51484851512;4863.051704917507;4928.926066109011;5002.59979201663;4471.597556219956;4482.977585112054;4483.663725220556;4504.1216962692615;4510.729356238636;5772.567057470623;5791.0734282514595;5804.709764511842;5810.5061069809535;5865.2961644602065;6103.186149163621;6233.3143354231;6261.749530018839;6264.812055638338;6285.842853928689;5936.13925980739;5951.9107213391835;5954.76180952757;5961.731722072431;5975.492083172892;5340.706261155674;5386.030514507426;5473.644213126134;5497.175423686399;5499.560035197174;6259.530535209878;6319.304876463493;6341.587796793194;6359.49124070064;6453.645049522322;6116.082587611894;6207.16203312547;6218.378162183745;6223.595466294676;6252.218472690914;6403.551751377358;6446.452051575481;6448.29068802422;6453.515986300952;6457.935420645921;6098.176746139215;6136.7028951139955;6162.198914141133;6187.81436556904;6206.379362063938;6142.539309551712;6170.753169873155;6239.220097487814;6244.594101383395;6261.467775239155;5288.704267454498;6030.7889223876955;6096.512279017757;6174.66039367839;6183.938160618336;6042.546808989256;6110.7250173741395;6172.814815555541;6176.598521096114;6196.845078873064;5927.140564618193;5930.110483427347;5938.049912014057;5983.311919129356;5992.550558708027;6031.095335699505;6100.450959413674;6105.9694701526205;6137.0399217141685;6148.938510614837;5881.206037184926;5979.252593425822;5997.400259973968;6051.667158306335;6071.483923866521 00:06:37 INFO - PROCESS | 1917 | |1;ext.html;435.727670206121;453.8434240187135;459.90112125892927;483.4198961246503;488.8219391267047;30702.99999999997;39581.40627890584;39719.80140099299;39884;39980.00049998744;40494;40891.79554102233;40938.59061409385;40973.385399219034;41116.58883411165;8388.035375931755;9093.59078841452;9390.765230869249;9417.952910235474;9429.387089839138;8416.873746893802;11054.778904421946;11316.434178291094;11325.490352934097;11493.310401375902;11259.54961801527;11279.041281491083;11301.660950171508;11315.490802913888;11362.261453005569;11160.330380177174;11169.44152792357;11169.609063682814;11211.66365009051;11232.719182020433;11147.442627868573;11220.66338009861;11225.158113141466;11231.494582743797;11266.605668801552;39009;39424.60575394238;39431.40852887209;39495.40756888649;39495.60504394977;3372.629010808809;3383.086566626997;3484.3205574912904;3573.624769399202;3578.982105089484;29747.40505189884;29823.552646710315;30052.69947300521;30197.547036794465;30286.54570181449;3191.377681352137;3198.4882418812876;3272.836358182105;3284.7372210223366;3313.8508767105295;433205;473459;475485;481961;482589;59172.81654366889;59308.11037834436;59396.40603593995;59560.80878382408;59662.10506842401;61025.20260575238;61269.69365153146;61544;61622.69188654028;61725.382746172865;15335.233238338038;15924.840751592335;15961.760573591406;15973.440929567641;15978;11180.385078820744;11439.084873210124;11590.478428470738;12310;12430.502779888884;13567.05030647865;15772;16004.199790010452;16227.107509086878;16383.67232655364;6488.480921526267;6593.078015598156;6594.86810263792;6598.47212223021;6655.700493477805;780.4185881518305;951.5813042261383;973.2019743810008;1045.6967479431032;1047.9685609431729;25621.61567576488;28422.85788571085;29400.558991615144;29995.70004299929;30521.694783051884;912.8265629530368;994.557421947241;1031.8855635913196;1075.4031512510476;1122.865256169265;12971.805422918664;13928.37322320498;15177.6205594861;15457.381704731684;15913.363465461252;177509.1124544369;618658;619262;622102;622394;57488;57969;57998.7100064497;58310.125348119815;58354.70822645944;1168.4041139019162;1390.0200358747059;1408.2114016150988;1410.8307003159482;1416.8795652369458;29412.852935735184;29586.852065739535;29589.408211836075;30298.394032119675;30394;46893.765531172125;48407.27389089237;48465.69945907009;49015.509844902524;51307.74346128246;551278;557144;557350;557478;559136;21870;22432;23527.647085293393;24244.636330454705;24503.264902052968;41870.58129418667;41934.580654193065;41962;42202.788986054875;42634.78682606567;34859;34903.65096349004;34963.82518087393;35656.10859728424;36142.27715445729;27954.86022569874;34513.721196486295;34563.30873382569;34924.65075349214;35318.82340588281;34450.310993780484;34472.82763586266;34516.48225276572;34545.790897318475;35346.82326588454;28537.857310713312;29164.854175728986;29210.707892921648;31117.910873119334;31189.532157018115;23694.88152559226;23735.28794136179;23783.881080594485;24016.39959001039;24124 00:06:37 INFO - PROCESS | 1917 | |2;jquery.html;363.83263698698596;369.4071016019289;371.53186984399656;377.23609690376963;396.8629875406958;19005.52486187846;19320.90339548302;19911.800881991177;20141.798582014195;20231.898840505815;9602.135807777298;11825.881741182586;12091.637250882466;12094;12097.939510302458;4851.660383773128;5145.305383773199;5206.140986737184;5259.447757985395;5285.312909321802;3761.8119094045187;4017.096153365484;5194.246834209038;5239.842804715865;5522.889542209154;5777.335606405275;5890.764369425217;5893.852653683649;5896.646201227919;5918.201042859224;4906.754662266872;5056.924146137811;5603.859903502404;5833.591648584601;5864.70676466175;5604.579656525777;5647.040003199466;5661.490465858093;5663.0655941769555;5667.886642267173;18404.815951840443;23679.28962131139;23717.932693028855;23966.76033239663;24169.274921752374;2139.155033761671;2180.5420861619077;2197.9890100549555;2205.856619319739;2908.8400137992403;10029.04724051214;10167.034131757546;10214;10294.43380614066;13697.904167666562;1785.3215778004405;1786.196211704738;1807.204829874851;1820.7633007708912;1827.6436094961489;87941;116451.41774291074;118505;119348.40325798403;119922.4003879975;9214.539273036322;9799.412035277906;10966.177536684621;11271.718207044725;11507;14532.418703251751;14545.20001399912;14550.41798328055;14578.562643120724;14588.270586470846;8035.0759662638375;8154.469959452736;8192.385571082194;8281.54451505173;8336.791580210422;6474.805755827333;7227.566905719741;7861.174576669482;7878.290953814184;8136.75589732309;3653.5433070866143;4517.683762136685;4569.428821397325;4586.862394128181;4687.601553867959;1382.8202333696554;1567.890247682674;1869.5232715657692;1935.312963897796;1944.1640094759107;772.4901564967095;900.7612982559627;930.7673081729567;954.4034978138664;974.5809302000208;15564.92217538905;18005.819941800415;20364;20560.897195514226;20834.270800522212;887.6582515731361;918.7749001494676;932.6269492203173;937.465644582595;981.9803603927882;8680.609372578248;8776.648934042694;9160.725178244664;10024.298299119138;10398.844017339747;560022;560104;565246;580293;581236;116490.41754791171;117143;119254.8074519261;119530;120203;923.4090182283344;940.1726480696943;952.552300418802;956.5886668732372;957.2533423929347;18359.35742249015;18485.630287394444;18507.629847403245;18530.907345463726;18582.535436614195;49655.50344496653;50739;51103.233451497515;52359;52459.21311180259;67575;72626.54746904927;73053.53892922006;73108.63445682738;74410.2558974425;7055.153381594241;7091.361777440055;7289.307515785992;7291.52605080668;7299.781006569811;11148.275362101438;11756.353400563052;11769.17615766888;12137.575184868487;12220.69448263765;8699.56502174876;10317.587296508356;10611.575536978744;10659.14726821868;11181.273217240854;9510.286728495528;10323.638672646723;10527.526261318106;10777.299475534066;10956;10240.948795255976;10581.894181058398;10665.89334106649;10848.349099054081;11285.21003529745;6262.937370626236;7107.609081500567;7218.458615603746;7266.164391094881;7412.073490813648;8952.776180595538;10408.323458975143;10538.155822730838;10619.94690026545;10712.035916767529 00:06:37 INFO - PROCESS | 1917 | |3;mootools.html;1219.3598360860547;1412.7457057729612;1546.1341648676741;1812.4019073705679;1824.5256233379318;7508.286712762292;7786.805329866756;8079;8441.029281632607;8475.533845638487;7930.444868859167;8189.91810081899;8198.672053117869;8349.123342049073;8360.163983601651;2288.0733303012266;2337.2170322941834;2341.203990643181;2342.61346877765;2354.3054798834387;2319.8724070176136;2339.6841426407386;2345.566070277004;2364.3734410381176;2369.7274813396507;2296.5062511560013;2298.2645553422913;2312.3756585721844;2318;2327.2552783109413;2010.286348346338;2198.208644887839;2237.2393386248755;2253.5830871288863;2276.2943487518755;2053.8459615528745;2319.153508969231;2347.1550241912896;2362.7282862470865;2366.349253955159;8368.246857782768;8422.24199822019;8425.283850872684;8439.198276163756;8449.32405407566;4907.460179380265;5100.464451232604;5107.744612769384;5109.565686916617;5114.7698353573805;2743.43759529296;2952.217662319487;2954.822710637347;3236.91907702307;3531.364354416204;996.681062060141;1022.6369638778201;1037.424229552598;1040.9167266618729;1042.9009244121796;10354.378737275787;10551.525181366856;10670.946645266802;10688.519016644192;10796.352218866894;3770.321342158438;4297;4341.218580655449;4482.103579284156;4499.302608095685;8607.569621519024;8679.566021698889;8707.956460217658;8717.99743029547;8744.038155802857;5061.772220250024;5111.361079865017;5117.795288188505;5118.769655365518;5120.718360490135;4295.871123866289;4453.4433195850515;4586.816527338936;4620.422447194101;4817;3260.494623333387;3636.1636823530207;3718.442233664972;3724.217914237986;3802.7147963902817;1125.2502358831932;1130.1014295982827;1137.2891942535914;1138.5787258714163;1152.0783373301492;874.6719980007497;876.2070326354659;888.2804928008334;916.5142474488532;924.5608336040327;5492.670439773627;5558.777648893999;5560.888782224414;5772.769089236467;5800.187973683687;899.1233547291339;900.4867225681298;902.5712786426526;910.9544522773834;966.9323147379616;3710.4805327254207;3788.8484460621817;3846.942295865564;3873.9806300968316;3947.2500224957166;9661.758456038655;10029.398236105711;10126.493675316206;10145.188384929188;10180.643677471253;10072.244581656405;10085.798284034277;10109.848352274721;10110;10121.69634910954;5565.9443405566235;7636.427267954926;7682.8079298018;7838.529688218725;7852.411069169835;5510.531604813636;5685.459881311269;5807;5827.455724233167;5838.766449342149;7152.392046676091;8974.19232269099;9003.864942026006;9074.319426043205;9298.907010930076;9331.673391431268;9357.20463760588;9415.105564971316;9516.38143520669;9549.522523873917;6579.703913323816;6796.286389929184;6981.965090174516;6994.475414343843;7218.783436496913;7167.892481612885;8004.399670024888;8010.319122874621;8033.638486268249;8040.316573091352;7000.47496437759;7140.6072666003865;7151.964240178766;7204.675789589376;7260.927390726025;6767.35710107539;6795.93204067953;6916.723331066701;7013.2986701329455;7019.859602808017;6313.905291420724;6525.543211975306;6612.702428390638;6618.569792963442;6695.196576410862;4130.876073717794;4484.237679594542;4782.187028205144;4822.156122678587;4904.896398310351;4594.839180628662;4685.437747470189;4767.356406885026;4809.182438985311;4884.535969082932 00:06:37 INFO - PROCESS | 1917 | |4;prototype.html;329.89773170317187;351.7713486233948;359.33881657749737;361.9963800361996;368.8321813574824;5419.729013549327;5484.61607687462;5489.14564426498;5495.505404513593;5528.364238112618;5012.849614511561;5379.220013098098;5381.408045115044;5424.945750542493;5440.319960004999;3579.964200358002;3750.324941510527;3991.32147534919;4101.979490102545;4127.979360103195;3759.6804271636943;4197.769122698251;4208.684348673846;4214.262504061792;4234.640055595278;3742.6818720408796;4206.369044643313;4229.821641854447;4238.682098842583;4240.766757828318;3002.7297543221;3663.230721548478;4227.619514243717;4240.491141063061;4252.723572967747;2906.9273268168254;3470.861165553375;3501.4747787831775;3525.94711079334;3567.357875582394;4167.895802604929;5272.604554658416;5301.655392399482;5304.9920515102;5319.707416092114;4482.417285752864;4663.347131401607;4683.765811709401;4683.929741053887;4708.4820669726305;4767.785449654739;4889.559939605452;5597.412271711452;5728.656280623176;5756;3578.6202175553226;3867.7679339239735;3868.78721670308;3869.729118961674;3872.3223435898885;5395.838124856261;5626.324841019104;5818.243628328375;5991.5206783457215;6021.879562408771;3030.7423868970945;3850.6534411902503;3873.709471789627;3896.59085795993;3937.4684417603257;3648.7081033517256;3929.155231625214;3952.150287688161;3969.821358038895;3981.5819338969573;4090.3250963590676;4634.073185362941;4666.066786642685;4670.486246512881;4700.318453824177;4693;5400.351957765093;5687.317521897398;5743.3395159557185;5778.479936805707;1102.7077824376547;1149.5057125436142;1149.545929357899;1152.423788105947;1255.1966741285585;680.6290571638469;866.7919699272129;882.6381183714775;882.6954700628273;883.4743327719997;4407.471103467605;4710.599599034121;4915.926261106087;5118.846434606968;5155.819546315861;4667.556582124693;5626.493615574536;5659.603827732019;5703;5713.8285851424525;4512.9097418052115;4662.323963025342;4925.655204135748;5014.774335154927;5163.509466600667;2608.608708693711;2788.4283721836973;2842.872070756821;2962.74831128344;2982.8657710403086;5872.7357268923;5879.941200587939;5882.411758824083;5901.675407852609;5902.291724993029;5409.94422119592;5415.102919937893;5416.8104116355735;5710.428957104339;5841.824745257564;5050.520200581012;5351.785928562892;5419.945800542102;5652.74562644682;5883.470487656046;4770.427548694179;5540.806071787468;5614.24207731951;5638.323401191883;5657.575681823962;4862.468322478408;4943.332650092191;5097.057044446789;5145.897082058413;5433.293671822796;5116.974415128049;5350.438203988525;5386.8383948481605;5763.827085187451;5948.643081415129;3866.594007629271;3875.6124387561017;3891.922161556696;3892.863749768858;3895.2599006188734;5026.974865125651;5487.286652735119;5627.21219029328;5908.143319218775;5976.8804623908145;4620.976895115503;5368.208531563487;5679.858003549779;5767.509761670305;5804.477597016289;4911.870269837886;5611.915821262603;5681.2330335404195;5699.059655156852;5699.8860022800145;4858.663867436483;5163;5612.915806262828;5624;5627.437256274504;4723.834665786681;5665.374160537342;5688.383343661962;5699.8860022800145;5723.828285151452;5642.266505354278;5642.351129620146;5666.518345940642;5693.259876216065;5711.685857277724 00:06:37 INFO - PROCESS | 1917 | |5;yui.html;701.8806802843519;746.3059354800035;754.2645920227778;755.2296657409439;765.5177238339846;11559.65321040368;15295.92352038241;15335.92332038341;15341.92329038356;15413.306401211945;13364.131331463448;13437.932810335958;13442.663933401669;13449.193048417103;13518.391672374742;587.5916238214421;702.097804321447;841.8232171244047;856.0540602634093;873.5326600268858;860.311750599521;863.7236084452977;881.0968757024037;881.9073997230295;884.1688812516214;934.8223837470892;934.9298802589799;936.171488232912;942.1661829281102;943.9622415103388;930.3208657679897;931.3992474853749;931.65063101337;931.7018554062703;932.6922115701802;686.2177118085386;686.6498085976139;688.3323176518769;689.3588962265092;691.87892118879;14466.855331446655;14531.927340363336;14752.704945901021;14773.482928097465;14938.850611493963;11960.940195299056;14069;14321.283935803169;14346.139231646135;14362.784558231635;11500.194986350958;11615.361155136465;11629.185956983014;11839.822402663967;12029.93985030078;3634.709223262159;3661.5789184243886;3674.779513229215;3684.705223582107;3715.034091136297;58970.70514647442;97910;112494;120544.79455205423;122143;10727.892721072689;10752.677419677422;10804.297720648132;10879.510422031028;11042.392668403316;21234.362969110953;21369.572608547744;21532.24637137724;21556.784432155477;21636.78363216348;7819.609019549;7880.84238315242;7910.762677119696;7913;7995.521388369624;4264.488261408651;4353.9129217415475;4354.107407981356;4361.825526978949;4371.803268852909;3496.5629296337956;3575.1419659282105;3580.0333909844717;3611.2777444511203;3620.9637903621156;604.6341963112342;616.9321374648785;659.9902149710854;667.9432248258952;670;437.56243756243754;520.6849855837164;528.5058470330176;596.1683451585101;664.6809531424847;8704.6880620278;9168.404053736485;9218.53907304632;9360.438373697463;9424.811503769886;623.2427600465456;668.6489592963733;669.7350416718498;677.5494296293009;679.6296018669836;4244.554321796229;4249.2563801334645;4542.958414457577;4599.954000460019;4671.929921051187;84381.15618843734;84807;95267.04732952721;97775;99795.50102249443;80674;94761.52619236859;96083.46998360241;97847.53228701575;99126.69649328843;67254;67747.98378024236;68005.65997170082;68793;69532;9299.539814528382;9422.575984080595;9751.902480975383;9777.755556111155;9920.206383489447;39668.603313966494;40694.77915662535;52034.73982630214;53861.65345866228;54237.45762542324;65291.69416611736;66289;69817.65091174512;70755.29244707487;75002;12605;12854.935725321313;12894.355282235663;13088.08383413152;13088.73822523525;14679.290175473636;14694.338754755849;14928.7760683592;14947.701045979236;15064.096154230781;13299.268540229994;13590.45638174462;13686.178829270275;13811.309434528433;13916.58250252494;13040.869591303965;13151.276679782704;13379;13551.932240338736;13619.93190034083;12992.090553661154;13332.133411328232;13333.266670333225;13699.589012329647;13712.17726936387;10889.911008899047;11051.336919784839;11214.15893807984;11407.258528195973;11856.170068095496;10614.416207108683;11189.339697692854;11493.770124597628;11534.884651153381;11557.942210288895 00:06:37 INFO - PROCESS | 1917 | __end_tp_report 00:06:37 INFO - PROCESS | 1917 | __start_cc_report 00:06:37 INFO - PROCESS | 1917 | _x_x_mozilla_cycle_collect,388 00:06:37 INFO - PROCESS | 1917 | __end_cc_report 00:06:37 INFO - PROCESS | 1917 | __startTimestamp1481529997012__endTimestamp 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | ------- Summary: start ------- 00:06:37 INFO - PROCESS | 1917 | Number of tests: 6 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | [#0] dojo.html Cycles:165 Average:5731.80 Median:5954.76 stddev:652.93 (11.0%) stddev-sans-first:628.83 00:06:37 INFO - PROCESS | 1917 | Values: 3402.0 3406.2 3424.2 3431.6 3455.9 5772.2 5784.9 5820.5 5823.2 5840.2 5589.3 5697.2 5699.7 5731.2 5746.3 5179.1 5196.5 5276.9 5335.9 5357.1 5243.4 5257.7 5284.9 5292.3 5315.3 5193.0 5284.3 5308.6 5339.8 5420.1 4968.9 5117.5 5276.0 5276.4 5337.1 4185.1 4695.2 5091.7 5103.3 5148.6 4655.9 5675.4 5764.1 5783.6 5804.3 6132.2 6134.0 6216.1 6225.4 6247.6 5074.9 6219.5 6235.1 6238.3 6257.7 4862.9 5701.9 5763.1 5840.2 5950.3 6316.2 6341.3 6359.2 6380.6 6414.1 6128.6 6148.9 6159.9 6215.6 6248.3 5553.1 5616.7 5769.3 5812.5 6106.4 6160.2 6162.4 6215.7 6229.3 6240.7 6026.0 6066.3 6233.8 6242.6 6245.3 4632.4 4851.5 4863.1 4928.9 5002.6 4471.6 4483.0 4483.7 4504.1 4510.7 5772.6 5791.1 5804.7 5810.5 5865.3 6103.2 6233.3 6261.7 6264.8 6285.8 5936.1 5951.9 5954.8 5961.7 5975.5 5340.7 5386.0 5473.6 5497.2 5499.6 6259.5 6319.3 6341.6 6359.5 6453.6 6116.1 6207.2 6218.4 6223.6 6252.2 6403.6 6446.5 6448.3 6453.5 6457.9 6098.2 6136.7 6162.2 6187.8 6206.4 6142.5 6170.8 6239.2 6244.6 6261.5 5288.7 6030.8 6096.5 6174.7 6183.9 6042.5 6110.7 6172.8 6176.6 6196.8 5927.1 5930.1 5938.0 5983.3 5992.6 6031.1 6100.5 6106.0 6137.0 6148.9 5881.2 5979.3 5997.4 6051.7 6071.5 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | [#1] ext.html Cycles:180 Average:65099.62 Median:34493.27 stddev:141950.26 (411.5%) stddev-sans-first:142265.44 00:06:37 INFO - PROCESS | 1917 | Values: 435.7 453.8 459.9 483.4 488.8 30703.0 39581.4 39719.8 39884.0 39980.0 40494.0 40891.8 40938.6 40973.4 41116.6 8388.0 9093.6 9390.8 9418.0 9429.4 8416.9 11054.8 11316.4 11325.5 11493.3 11259.5 11279.0 11301.7 11315.5 11362.3 11160.3 11169.4 11169.6 11211.7 11232.7 11147.4 11220.7 11225.2 11231.5 11266.6 39009.0 39424.6 39431.4 39495.4 39495.6 3372.6 3383.1 3484.3 3573.6 3579.0 29747.4 29823.6 30052.7 30197.5 30286.5 3191.4 3198.5 3272.8 3284.7 3313.9 433205.0 473459.0 475485.0 481961.0 482589.0 59172.8 59308.1 59396.4 59560.8 59662.1 61025.2 61269.7 61544.0 61622.7 61725.4 15335.2 15924.8 15961.8 15973.4 15978.0 11180.4 11439.1 11590.5 12310.0 12430.5 13567.1 15772.0 16004.2 16227.1 16383.7 6488.5 6593.1 6594.9 6598.5 6655.7 780.4 951.6 973.2 1045.7 1048.0 25621.6 28422.9 29400.6 29995.7 30521.7 912.8 994.6 1031.9 1075.4 1122.9 12971.8 13928.4 15177.6 15457.4 15913.4 177509.1 618658.0 619262.0 622102.0 622394.0 57488.0 57969.0 57998.7 58310.1 58354.7 1168.4 1390.0 1408.2 1410.8 1416.9 29412.9 29586.9 29589.4 30298.4 30394.0 46893.8 48407.3 48465.7 49015.5 51307.7 551278.0 557144.0 557350.0 557478.0 559136.0 21870.0 22432.0 23527.6 24244.6 24503.3 41870.6 41934.6 41962.0 42202.8 42634.8 34859.0 34903.7 34963.8 35656.1 36142.3 27954.9 34513.7 34563.3 34924.7 35318.8 34450.3 34472.8 34516.5 34545.8 35346.8 28537.9 29164.9 29210.7 31117.9 31189.5 23694.9 23735.3 23783.9 24016.4 24124.0 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | [#2] jquery.html Cycles:180 Average:32903.91 Median:387.05 stddev:95024.79 (24551.1%) stddev-sans-first:95259.95 00:06:37 INFO - PROCESS | 1917 | Values: 363.8 369.4 371.5 377.2 396.9 19005.5 19320.9 19911.8 20141.8 20231.9 9602.1 11825.9 12091.6 12094.0 12097.9 4851.7 5145.3 5206.1 5259.4 5285.3 3761.8 4017.1 5194.2 5239.8 5522.9 5777.3 5890.8 5893.9 5896.6 5918.2 4906.8 5056.9 5603.9 5833.6 5864.7 5604.6 5647.0 5661.5 5663.1 5667.9 18404.8 23679.3 23717.9 23966.8 24169.3 2139.2 2180.5 2198.0 2205.9 2908.8 10029.0 10167.0 10214.0 10294.4 13697.9 1785.3 1786.2 1807.2 1820.8 1827.6 87941.0 116451.4 118505.0 119348.4 119922.4 9214.5 9799.4 10966.2 11271.7 11507.0 14532.4 14545.2 14550.4 14578.6 14588.3 8035.1 8154.5 8192.4 8281.5 8336.8 6474.8 7227.6 7861.2 7878.3 8136.8 3653.5 4517.7 4569.4 4586.9 4687.6 1382.8 1567.9 1869.5 1935.3 1944.2 772.5 900.8 930.8 954.4 974.6 15564.9 18005.8 20364.0 20560.9 20834.3 887.7 918.8 932.6 937.5 982.0 8680.6 8776.6 9160.7 10024.3 10398.8 560022.0 560104.0 565246.0 580293.0 581236.0 116490.4 117143.0 119254.8 119530.0 120203.0 923.4 940.2 952.6 956.6 957.3 18359.4 18485.6 18507.6 18530.9 18582.5 49655.5 50739.0 51103.2 52359.0 52459.2 67575.0 72626.5 73053.5 73108.6 74410.3 7055.2 7091.4 7289.3 7291.5 7299.8 11148.3 11756.4 11769.2 12137.6 12220.7 8699.6 10317.6 10611.6 10659.1 11181.3 9510.3 10323.6 10527.5 10777.3 10956.0 10240.9 10581.9 10665.9 10848.3 11285.2 6262.9 7107.6 7218.5 7266.2 7412.1 8952.8 10408.3 10538.2 10619.9 10712.0 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | [#3] mootools.html Cycles:180 Average:5340.34 Median:5104.10 stddev:2935.18 (57.5%) stddev-sans-first:2927.07 00:06:37 INFO - PROCESS | 1917 | Values: 1219.4 1412.7 1546.1 1812.4 1824.5 7508.3 7786.8 8079.0 8441.0 8475.5 7930.4 8189.9 8198.7 8349.1 8360.2 2288.1 2337.2 2341.2 2342.6 2354.3 2319.9 2339.7 2345.6 2364.4 2369.7 2296.5 2298.3 2312.4 2318.0 2327.3 2010.3 2198.2 2237.2 2253.6 2276.3 2053.8 2319.2 2347.2 2362.7 2366.3 8368.2 8422.2 8425.3 8439.2 8449.3 4907.5 5100.5 5107.7 5109.6 5114.8 2743.4 2952.2 2954.8 3236.9 3531.4 996.7 1022.6 1037.4 1040.9 1042.9 10354.4 10551.5 10670.9 10688.5 10796.4 3770.3 4297.0 4341.2 4482.1 4499.3 8607.6 8679.6 8708.0 8718.0 8744.0 5061.8 5111.4 5117.8 5118.8 5120.7 4295.9 4453.4 4586.8 4620.4 4817.0 3260.5 3636.2 3718.4 3724.2 3802.7 1125.3 1130.1 1137.3 1138.6 1152.1 874.7 876.2 888.3 916.5 924.6 5492.7 5558.8 5560.9 5772.8 5800.2 899.1 900.5 902.6 911.0 966.9 3710.5 3788.8 3846.9 3874.0 3947.3 9661.8 10029.4 10126.5 10145.2 10180.6 10072.2 10085.8 10109.8 10110.0 10121.7 5565.9 7636.4 7682.8 7838.5 7852.4 5510.5 5685.5 5807.0 5827.5 5838.8 7152.4 8974.2 9003.9 9074.3 9298.9 9331.7 9357.2 9415.1 9516.4 9549.5 6579.7 6796.3 6982.0 6994.5 7218.8 7167.9 8004.4 8010.3 8033.6 8040.3 7000.5 7140.6 7152.0 7204.7 7260.9 6767.4 6795.9 6916.7 7013.3 7019.9 6313.9 6525.5 6612.7 6618.6 6695.2 4130.9 4484.2 4782.2 4822.2 4904.9 4594.8 4685.4 4767.4 4809.2 4884.5 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | [#4] prototype.html Cycles:180 Average:4524.63 Median:5038.75 stddev:1400.06 (27.8%) stddev-sans-first:1368.13 00:06:37 INFO - PROCESS | 1917 | Values: 329.9 351.8 359.3 362.0 368.8 5419.7 5484.6 5489.1 5495.5 5528.4 5012.8 5379.2 5381.4 5424.9 5440.3 3580.0 3750.3 3991.3 4102.0 4128.0 3759.7 4197.8 4208.7 4214.3 4234.6 3742.7 4206.4 4229.8 4238.7 4240.8 3002.7 3663.2 4227.6 4240.5 4252.7 2906.9 3470.9 3501.5 3525.9 3567.4 4167.9 5272.6 5301.7 5305.0 5319.7 4482.4 4663.3 4683.8 4683.9 4708.5 4767.8 4889.6 5597.4 5728.7 5756.0 3578.6 3867.8 3868.8 3869.7 3872.3 5395.8 5626.3 5818.2 5991.5 6021.9 3030.7 3850.7 3873.7 3896.6 3937.5 3648.7 3929.2 3952.2 3969.8 3981.6 4090.3 4634.1 4666.1 4670.5 4700.3 4693.0 5400.4 5687.3 5743.3 5778.5 1102.7 1149.5 1149.5 1152.4 1255.2 680.6 866.8 882.6 882.7 883.5 4407.5 4710.6 4915.9 5118.8 5155.8 4667.6 5626.5 5659.6 5703.0 5713.8 4512.9 4662.3 4925.7 5014.8 5163.5 2608.6 2788.4 2842.9 2962.7 2982.9 5872.7 5879.9 5882.4 5901.7 5902.3 5409.9 5415.1 5416.8 5710.4 5841.8 5050.5 5351.8 5419.9 5652.7 5883.5 4770.4 5540.8 5614.2 5638.3 5657.6 4862.5 4943.3 5097.1 5145.9 5433.3 5117.0 5350.4 5386.8 5763.8 5948.6 3866.6 3875.6 3891.9 3892.9 3895.3 5027.0 5487.3 5627.2 5908.1 5976.9 4621.0 5368.2 5679.9 5767.5 5804.5 4911.9 5611.9 5681.2 5699.1 5699.9 4858.7 5163.0 5612.9 5624.0 5627.4 4723.8 5665.4 5688.4 5699.9 5723.8 5642.3 5642.4 5666.5 5693.3 5711.7 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - PROCESS | 1917 | [#5] yui.html Cycles:180 Average:19866.91 Median:2399.69 stddev:28508.72 (1188.0%) stddev-sans-first:28552.37 00:06:37 INFO - PROCESS | 1917 | Values: 701.9 746.3 754.3 755.2 765.5 11559.7 15295.9 15335.9 15341.9 15413.3 13364.1 13437.9 13442.7 13449.2 13518.4 587.6 702.1 841.8 856.1 873.5 860.3 863.7 881.1 881.9 884.2 934.8 934.9 936.2 942.2 944.0 930.3 931.4 931.7 931.7 932.7 686.2 686.6 688.3 689.4 691.9 14466.9 14531.9 14752.7 14773.5 14938.9 11960.9 14069.0 14321.3 14346.1 14362.8 11500.2 11615.4 11629.2 11839.8 12029.9 3634.7 3661.6 3674.8 3684.7 3715.0 58970.7 97910.0 112494.0 120544.8 122143.0 10727.9 10752.7 10804.3 10879.5 11042.4 21234.4 21369.6 21532.2 21556.8 21636.8 7819.6 7880.8 7910.8 7913.0 7995.5 4264.5 4353.9 4354.1 4361.8 4371.8 3496.6 3575.1 3580.0 3611.3 3621.0 604.6 616.9 660.0 667.9 670.0 437.6 520.7 528.5 596.2 664.7 8704.7 9168.4 9218.5 9360.4 9424.8 623.2 668.6 669.7 677.5 679.6 4244.6 4249.3 4543.0 4600.0 4671.9 84381.2 84807.0 95267.0 97775.0 99795.5 80674.0 94761.5 96083.5 97847.5 99126.7 67254.0 67748.0 68005.7 68793.0 69532.0 9299.5 9422.6 9751.9 9777.8 9920.2 39668.6 40694.8 52034.7 53861.7 54237.5 65291.7 66289.0 69817.7 70755.3 75002.0 12605.0 12854.9 12894.4 13088.1 13088.7 14679.3 14694.3 14928.8 14947.7 15064.1 13299.3 13590.5 13686.2 13811.3 13916.6 13040.9 13151.3 13379.0 13551.9 13619.9 12992.1 13332.1 13333.3 13699.6 13712.2 10889.9 11051.3 11214.2 11407.3 11856.2 10614.4 11189.3 11493.8 11534.9 11557.9 00:06:37 INFO - PROCESS | 1917 | -------- Summary: end -------- 00:06:37 INFO - PROCESS | 1917 | 00:06:37 INFO - TEST-INFO | 1917: exit 0 00:06:37 INFO - TEST-OK | dromaeo_css | took 1117828ms 00:06:37 INFO - TEST-START | kraken 00:06:37 INFO - operating with platform_type : mac_ 00:06:37 INFO - Initialising browser for kraken test... 00:06:37 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpQ8x2N2/profile http://localhost:56170/getInfo.html) 00:06:40 INFO - PROCESS | 1922 | 2016-12-12 00:06:40.462 plugin-container[1923:22886] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 00:06:40 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:06:40 INFO - PROCESS | 1922 | 2016-12-12 00:06:40.465 plugin-container[1923:22886] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 00:06:40 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:06:40 INFO - PROCESS | 1922 | 2016-12-12 00:06:40.465 plugin-container[1923:22886] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:06:40 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 00:06:40 INFO - PROCESS | 1922 | colorDepth:24 00:06:40 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 00:06:40 INFO - PROCESS | 1922 | __metrics 00:06:40 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 00:06:40 INFO - PROCESS | 1922 | [Parent 1922] WARNING: pipe error: Broken pipe: file /builds/slave/gfx-m64-0000000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 709 00:06:41 INFO - TEST-INFO | 1922: exit 0 00:06:41 INFO - Browser initialized. 00:06:41 INFO - Running cycle 1/1 for kraken test... 00:06:41 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gh/t76btwz501jcblhsxv28_zf400000w/T/tmpQ8x2N2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:06:43 INFO - PROCESS | 1924 | 2016-12-12 00:06:43.796 plugin-container[1925:23004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 00:06:43 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:06:43 INFO - PROCESS | 1924 | 2016-12-12 00:06:43.796 plugin-container[1925:23004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 00:06:43 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:06:43 INFO - PROCESS | 1924 | 2016-12-12 00:06:43.796 plugin-container[1925:23004] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:06:53 INFO - PROCESS | 1924 | RSS: Main: 237293568 00:06:53 INFO - PROCESS | 1924 | 00:06:56 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56170/tests/kraken/driver.html?testName=audio-beat-detection) 00:06:57 INFO - PROCESS | 1924 | RSS: Main: 249819136 00:06:57 INFO - PROCESS | 1924 | 00:07:00 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56170/tests/kraken/driver.html?testName=audio-dft) 00:07:00 INFO - PROCESS | 1924 | RSS: Main: 257290240 00:07:00 INFO - PROCESS | 1924 | 00:07:04 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56170/tests/kraken/driver.html?testName=audio-fft) 00:07:04 INFO - PROCESS | 1924 | RSS: Main: 260055040 00:07:04 INFO - PROCESS | 1924 | 00:07:07 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56170/tests/kraken/driver.html?testName=audio-oscillator) 00:07:07 INFO - PROCESS | 1924 | RSS: Main: 262352896 00:07:07 INFO - PROCESS | 1924 | 00:07:09 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56170/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:07:10 INFO - PROCESS | 1924 | RSS: Main: 262643712 00:07:10 INFO - PROCESS | 1924 | 00:07:13 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56170/tests/kraken/driver.html?testName=imaging-darkroom) 00:07:14 INFO - PROCESS | 1924 | RSS: Main: 269217792 00:07:14 INFO - PROCESS | 1924 | 00:07:17 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56170/tests/kraken/driver.html?testName=imaging-desaturate) 00:07:18 INFO - PROCESS | 1924 | RSS: Main: 267976704 00:07:18 INFO - PROCESS | 1924 | 00:07:20 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56170/tests/kraken/driver.html?testName=json-parse-financial) 00:07:21 INFO - PROCESS | 1924 | RSS: Main: 268230656 00:07:21 INFO - PROCESS | 1924 | 00:07:23 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56170/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:07:23 INFO - PROCESS | 1924 | RSS: Main: 267341824 00:07:23 INFO - PROCESS | 1924 | 00:07:25 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:07:26 INFO - PROCESS | 1924 | RSS: Main: 267358208 00:07:26 INFO - PROCESS | 1924 | 00:07:28 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:07:29 INFO - PROCESS | 1924 | RSS: Main: 267444224 00:07:29 INFO - PROCESS | 1924 | 00:07:32 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:07:33 INFO - PROCESS | 1924 | RSS: Main: 267800576 00:07:33 INFO - PROCESS | 1924 | 00:07:36 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:07:36 INFO - PROCESS | 1924 | RSS: Main: 267857920 00:07:36 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56170/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56170/tests/kraken/driver.html?testName=ai-astar) 00:07:38 INFO - PROCESS | 1924 | RSS: Main: 269848576 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | __start_tp_report 00:07:38 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 00:07:38 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 00:07:38 INFO - PROCESS | 1924 | |i|pagename|runs| 00:07:38 INFO - PROCESS | 1924 | |0;ai-astar;105;116;118;116;117;118;118;143;176;120 00:07:38 INFO - PROCESS | 1924 | |1;audio-beat-detection;151;116;116;156;116;118;143;107;150;152 00:07:38 INFO - PROCESS | 1924 | |2;audio-dft;206;203;249;288;219;202;194;203;198;250 00:07:38 INFO - PROCESS | 1924 | |3;audio-fft;104;57;70;110;87;83;65;60;78;60 00:07:38 INFO - PROCESS | 1924 | |4;audio-oscillator;91;72;85;72;73;72;81;96;60;102 00:07:38 INFO - PROCESS | 1924 | |5;imaging-gaussian-blur;113;138;120;173;168;119;113;148;113;119 00:07:38 INFO - PROCESS | 1924 | |6;imaging-darkroom;106;103;97;96;98;116;155;114;154;153 00:07:38 INFO - PROCESS | 1924 | |7;imaging-desaturate;92;95;92;92;107;138;93;94;94;90 00:07:38 INFO - PROCESS | 1924 | |8;json-parse-financial;57;56;56;58;57;58;59;57;58;59 00:07:38 INFO - PROCESS | 1924 | |9;json-stringify-tinderbox;74;69;67;84;68;97;104;98;104;102 00:07:38 INFO - PROCESS | 1924 | |10;stanford-crypto-aes;93;96;98;95;91;95;98;95;158;92 00:07:38 INFO - PROCESS | 1924 | |11;stanford-crypto-ccm;182;162;148;148;146;191;156;146;164;159 00:07:38 INFO - PROCESS | 1924 | |12;stanford-crypto-pbkdf2;181;182;238;188;183;178;182;181;180;180 00:07:38 INFO - PROCESS | 1924 | |13;stanford-crypto-sha256-iterative;69;67;69;69;68;67;69;66;85;66 00:07:38 INFO - PROCESS | 1924 | __end_tp_report 00:07:38 INFO - PROCESS | 1924 | __start_cc_report 00:07:38 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,947 00:07:38 INFO - PROCESS | 1924 | __end_cc_report 00:07:38 INFO - PROCESS | 1924 | __startTimestamp1481530058821__endTimestamp 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | ------- Summary: start ------- 00:07:38 INFO - PROCESS | 1924 | Number of tests: 14 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#0] ai-astar Cycles:10 Average:124.70 Median:118.00 stddev:20.34 (17.2%) stddev-sans-first:20.28 00:07:38 INFO - PROCESS | 1924 | Values: 105.0 116.0 118.0 116.0 117.0 118.0 118.0 143.0 176.0 120.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:146.50 stddev:19.35 (13.2%) stddev-sans-first:19.33 00:07:38 INFO - PROCESS | 1924 | Values: 151.0 116.0 116.0 156.0 116.0 118.0 143.0 107.0 150.0 152.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#2] audio-dft Cycles:10 Average:221.20 Median:212.50 stddev:30.93 (14.6%) stddev-sans-first:32.31 00:07:38 INFO - PROCESS | 1924 | Values: 206.0 203.0 249.0 288.0 219.0 202.0 194.0 203.0 198.0 250.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#3] audio-fft Cycles:10 Average:77.40 Median:67.50 stddev:18.63 (27.6%) stddev-sans-first:17.10 00:07:38 INFO - PROCESS | 1924 | Values: 104.0 57.0 70.0 110.0 87.0 83.0 65.0 60.0 78.0 60.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:77.00 stddev:13.02 (16.9%) stddev-sans-first:13.24 00:07:38 INFO - PROCESS | 1924 | Values: 91.0 72.0 85.0 72.0 73.0 72.0 81.0 96.0 60.0 102.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#5] imaging-gaussian-blur Cycles:10 Average:132.40 Median:129.00 stddev:23.17 (18.0%) stddev-sans-first:23.49 00:07:38 INFO - PROCESS | 1924 | Values: 113.0 138.0 120.0 173.0 168.0 119.0 113.0 148.0 113.0 119.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#6] imaging-darkroom Cycles:10 Average:119.20 Median:154.50 stddev:24.92 (16.1%) stddev-sans-first:25.97 00:07:38 INFO - PROCESS | 1924 | Values: 106.0 103.0 97.0 96.0 98.0 116.0 155.0 114.0 154.0 153.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#7] imaging-desaturate Cycles:10 Average:98.70 Median:92.50 stddev:14.58 (15.8%) stddev-sans-first:15.27 00:07:38 INFO - PROCESS | 1924 | Values: 92.0 95.0 92.0 92.0 107.0 138.0 93.0 94.0 94.0 90.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.08 (1.9%) stddev-sans-first:1.13 00:07:38 INFO - PROCESS | 1924 | Values: 57.0 56.0 56.0 58.0 57.0 58.0 59.0 57.0 58.0 59.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#9] json-stringify-tinderbox Cycles:10 Average:86.70 Median:71.50 stddev:15.94 (22.3%) stddev-sans-first:16.23 00:07:38 INFO - PROCESS | 1924 | Values: 74.0 69.0 67.0 84.0 68.0 97.0 104.0 98.0 104.0 102.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#10] stanford-crypto-aes Cycles:10 Average:101.10 Median:95.00 stddev:20.12 (21.2%) stddev-sans-first:21.13 00:07:38 INFO - PROCESS | 1924 | Values: 93.0 96.0 98.0 95.0 91.0 95.0 98.0 95.0 158.0 92.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#11] stanford-crypto-ccm Cycles:10 Average:160.20 Median:160.50 stddev:15.50 (9.7%) stddev-sans-first:14.29 00:07:38 INFO - PROCESS | 1924 | Values: 182.0 162.0 148.0 148.0 146.0 191.0 156.0 146.0 164.0 159.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:182.00 stddev:18.01 (9.9%) stddev-sans-first:18.95 00:07:38 INFO - PROCESS | 1924 | Values: 181.0 182.0 238.0 188.0 183.0 178.0 182.0 181.0 180.0 180.0 00:07:38 INFO - PROCESS | 1924 | 00:07:38 INFO - PROCESS | 1924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.00 stddev:5.58 (8.1%) stddev-sans-first:5.92 00:07:38 INFO - PROCESS | 1924 | Values: 69.0 67.0 69.0 69.0 68.0 67.0 69.0 66.0 85.0 66.0 00:07:38 INFO - PROCESS | 1924 | -------- Summary: end -------- 00:07:38 INFO - PROCESS | 1924 | 00:07:39 INFO - TEST-INFO | 1924: exit 0 00:07:39 INFO - TEST-OK | kraken | took 61953ms 00:07:39 INFO - SUITE-END | took 1179s 00:07:39 INFO - Completed test suite (00:19:40) 00:07:39 INFO - javascript benchmark 00:07:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7090.304699939897, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3402.0, 3406.1825161961124, 3424.2295483516227, 3431.5538979932603, 3455.8963231103066, 5772.163036359725, 5784.855378615537, 5820.53435725142, 5823.242978412805, 5840.182374467578, 5589.273394458714, 5697.173909783079, 5699.715014249292, 5731.226284451598, 5746.281714785652, 5179.093658609729, 5196.480351964801, 5276.894462110756, 5335.866603334909, 5357.142857142858, 5243.449437809032, 5257.737113144347, 5284.920726189091, 5292.311999440068, 5315.255864179018, 5192.974035129838, 5284.339457567804, 5308.628396012281, 5339.759710813003, 5420.051490989081, 4968.90062198754, 5117.462666420028, 5275.997560463499, 5276.366835979668, 5337.146056630959, 4185.121124563845, 4695.225287827504, 5091.66904151229, 5103.260027296022, 5148.588112950955, 4655.930161047587, 5675.432456754333, 5764.07774756037, 5783.566232532535, 5804.303483581955, 6132.172156758869, 6134.0, 6216.067589861512, 6225.377462253784, 6247.5939063960695, 5074.883525624394, 6219.502439804804, 6235.127082208494, 6238.251409830804, 6257.687115644245, 4862.927056094161, 5701.914471282934, 5763.106718458645, 5840.182374467578, 5950.345461999176, 6316.177422728629, 6341.302456729756, 6359.236891573041, 6380.55336126476, 6414.102025716404, 6128.601640893327, 6148.885084036735, 6159.907601385983, 6215.595986260879, 6248.250209974741, 5553.111502159716, 5616.719164041781, 5769.307683077974, 5812.476877081083, 6106.430331452215, 6160.168377269011, 6162.414570615784, 6215.689215539205, 6229.283632382243, 6240.687965601702, 6026.035834266497, 6066.272047354346, 6233.812985610439, 6242.563020588515, 6245.250569931637, 4632.420947381577, 4851.51484851512, 4863.051704917507, 4928.926066109011, 5002.59979201663, 4471.597556219956, 4482.977585112054, 4483.663725220556, 4504.1216962692615, 4510.729356238636, 5772.567057470623, 5791.0734282514595, 5804.709764511842, 5810.5061069809535, 5865.2961644602065, 6103.186149163621, 6233.3143354231, 6261.749530018839, 6264.812055638338, 6285.842853928689, 5936.13925980739, 5951.9107213391835, 5954.76180952757, 5961.731722072431, 5975.492083172892, 5340.706261155674, 5386.030514507426, 5473.644213126134, 5497.175423686399, 5499.560035197174, 6259.530535209878, 6319.304876463493, 6341.587796793194, 6359.49124070064, 6453.645049522322, 6116.082587611894, 6207.16203312547, 6218.378162183745, 6223.595466294676, 6252.218472690914, 6403.551751377358, 6446.452051575481, 6448.29068802422, 6453.515986300952, 6457.935420645921, 6098.176746139215, 6136.7028951139955, 6162.198914141133, 6187.81436556904, 6206.379362063938, 6142.539309551712, 6170.753169873155, 6239.220097487814, 6244.594101383395, 6261.467775239155, 5288.704267454498, 6030.7889223876955, 6096.512279017757, 6174.66039367839, 6183.938160618336, 6042.546808989256, 6110.7250173741395, 6172.814815555541, 6176.598521096114, 6196.845078873064, 5927.140564618193, 5930.110483427347, 5938.049912014057, 5983.311919129356, 5992.550558708027, 6031.095335699505, 6100.450959413674, 6105.9694701526205, 6137.0399217141685, 6148.938510614837, 5881.206037184926, 5979.252593425822, 5997.400259973968, 6051.667158306335, 6071.483923866521], "value": 5691.202840933427, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.727670206121, 453.8434240187135, 459.90112125892927, 483.4198961246503, 488.8219391267047, 30702.99999999997, 39581.40627890584, 39719.80140099299, 39884.0, 39980.00049998744, 40494.0, 40891.79554102233, 40938.59061409385, 40973.385399219034, 41116.58883411165, 8388.035375931755, 9093.59078841452, 9390.765230869249, 9417.952910235474, 9429.387089839138, 8416.873746893802, 11054.778904421946, 11316.434178291094, 11325.490352934097, 11493.310401375902, 11259.54961801527, 11279.041281491083, 11301.660950171508, 11315.490802913888, 11362.261453005569, 11160.330380177174, 11169.44152792357, 11169.609063682814, 11211.66365009051, 11232.719182020433, 11147.442627868573, 11220.66338009861, 11225.158113141466, 11231.494582743797, 11266.605668801552, 39009.0, 39424.60575394238, 39431.40852887209, 39495.40756888649, 39495.60504394977, 3372.629010808809, 3383.086566626997, 3484.3205574912904, 3573.624769399202, 3578.982105089484, 29747.40505189884, 29823.552646710315, 30052.69947300521, 30197.547036794465, 30286.54570181449, 3191.377681352137, 3198.4882418812876, 3272.836358182105, 3284.7372210223366, 3313.8508767105295, 433205.0, 473459.0, 475485.0, 481961.0, 482589.0, 59172.81654366889, 59308.11037834436, 59396.40603593995, 59560.80878382408, 59662.10506842401, 61025.20260575238, 61269.69365153146, 61544.0, 61622.69188654028, 61725.382746172865, 15335.233238338038, 15924.840751592335, 15961.760573591406, 15973.440929567641, 15978.0, 11180.385078820744, 11439.084873210124, 11590.478428470738, 12310.0, 12430.502779888884, 13567.05030647865, 15772.0, 16004.199790010452, 16227.107509086878, 16383.67232655364, 6488.480921526267, 6593.078015598156, 6594.86810263792, 6598.47212223021, 6655.700493477805, 780.4185881518305, 951.5813042261383, 973.2019743810008, 1045.6967479431032, 1047.9685609431729, 25621.61567576488, 28422.85788571085, 29400.558991615144, 29995.70004299929, 30521.694783051884, 912.8265629530368, 994.557421947241, 1031.8855635913196, 1075.4031512510476, 1122.865256169265, 12971.805422918664, 13928.37322320498, 15177.6205594861, 15457.381704731684, 15913.363465461252, 177509.1124544369, 618658.0, 619262.0, 622102.0, 622394.0, 57488.0, 57969.0, 57998.7100064497, 58310.125348119815, 58354.70822645944, 1168.4041139019162, 1390.0200358747059, 1408.2114016150988, 1410.8307003159482, 1416.8795652369458, 29412.852935735184, 29586.852065739535, 29589.408211836075, 30298.394032119675, 30394.0, 46893.765531172125, 48407.27389089237, 48465.69945907009, 49015.509844902524, 51307.74346128246, 551278.0, 557144.0, 557350.0, 557478.0, 559136.0, 21870.0, 22432.0, 23527.647085293393, 24244.636330454705, 24503.264902052968, 41870.58129418667, 41934.580654193065, 41962.0, 42202.788986054875, 42634.78682606567, 34859.0, 34903.65096349004, 34963.82518087393, 35656.10859728424, 36142.27715445729, 27954.86022569874, 34513.721196486295, 34563.30873382569, 34924.65075349214, 35318.82340588281, 34450.310993780484, 34472.82763586266, 34516.48225276572, 34545.790897318475, 35346.82326588454, 28537.857310713312, 29164.854175728986, 29210.707892921648, 31117.910873119334, 31189.532157018115, 23694.88152559226, 23735.28794136179, 23783.881080594485, 24016.39959001039, 24124.0], "value": 19269.197985195227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.83263698698596, 369.4071016019289, 371.53186984399656, 377.23609690376963, 396.8629875406958, 19005.52486187846, 19320.90339548302, 19911.800881991177, 20141.798582014195, 20231.898840505815, 9602.135807777298, 11825.881741182586, 12091.637250882466, 12094.0, 12097.939510302458, 4851.660383773128, 5145.305383773199, 5206.140986737184, 5259.447757985395, 5285.312909321802, 3761.8119094045187, 4017.096153365484, 5194.246834209038, 5239.842804715865, 5522.889542209154, 5777.335606405275, 5890.764369425217, 5893.852653683649, 5896.646201227919, 5918.201042859224, 4906.754662266872, 5056.924146137811, 5603.859903502404, 5833.591648584601, 5864.70676466175, 5604.579656525777, 5647.040003199466, 5661.490465858093, 5663.0655941769555, 5667.886642267173, 18404.815951840443, 23679.28962131139, 23717.932693028855, 23966.76033239663, 24169.274921752374, 2139.155033761671, 2180.5420861619077, 2197.9890100549555, 2205.856619319739, 2908.8400137992403, 10029.04724051214, 10167.034131757546, 10214.0, 10294.43380614066, 13697.904167666562, 1785.3215778004405, 1786.196211704738, 1807.204829874851, 1820.7633007708912, 1827.6436094961489, 87941.0, 116451.41774291074, 118505.0, 119348.40325798403, 119922.4003879975, 9214.539273036322, 9799.412035277906, 10966.177536684621, 11271.718207044725, 11507.0, 14532.418703251751, 14545.20001399912, 14550.41798328055, 14578.562643120724, 14588.270586470846, 8035.0759662638375, 8154.469959452736, 8192.385571082194, 8281.54451505173, 8336.791580210422, 6474.805755827333, 7227.566905719741, 7861.174576669482, 7878.290953814184, 8136.75589732309, 3653.5433070866143, 4517.683762136685, 4569.428821397325, 4586.862394128181, 4687.601553867959, 1382.8202333696554, 1567.890247682674, 1869.5232715657692, 1935.312963897796, 1944.1640094759107, 772.4901564967095, 900.7612982559627, 930.7673081729567, 954.4034978138664, 974.5809302000208, 15564.92217538905, 18005.819941800415, 20364.0, 20560.897195514226, 20834.270800522212, 887.6582515731361, 918.7749001494676, 932.6269492203173, 937.465644582595, 981.9803603927882, 8680.609372578248, 8776.648934042694, 9160.725178244664, 10024.298299119138, 10398.844017339747, 560022.0, 560104.0, 565246.0, 580293.0, 581236.0, 116490.41754791171, 117143.0, 119254.8074519261, 119530.0, 120203.0, 923.4090182283344, 940.1726480696943, 952.552300418802, 956.5886668732372, 957.2533423929347, 18359.35742249015, 18485.630287394444, 18507.629847403245, 18530.907345463726, 18582.535436614195, 49655.50344496653, 50739.0, 51103.233451497515, 52359.0, 52459.21311180259, 67575.0, 72626.54746904927, 73053.53892922006, 73108.63445682738, 74410.2558974425, 7055.153381594241, 7091.361777440055, 7289.307515785992, 7291.52605080668, 7299.781006569811, 11148.275362101438, 11756.353400563052, 11769.17615766888, 12137.575184868487, 12220.69448263765, 8699.56502174876, 10317.587296508356, 10611.575536978744, 10659.14726821868, 11181.273217240854, 9510.286728495528, 10323.638672646723, 10527.526261318106, 10777.299475534066, 10956.0, 10240.948795255976, 10581.894181058398, 10665.89334106649, 10848.349099054081, 11285.21003529745, 6262.937370626236, 7107.609081500567, 7218.458615603746, 7266.164391094881, 7412.073490813648, 8952.776180595538, 10408.323458975143, 10538.155822730838, 10619.94690026545, 10712.035916767529], "value": 8901.094472595862, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.3598360860547, 1412.7457057729612, 1546.1341648676741, 1812.4019073705679, 1824.5256233379318, 7508.286712762292, 7786.805329866756, 8079.0, 8441.029281632607, 8475.533845638487, 7930.444868859167, 8189.91810081899, 8198.672053117869, 8349.123342049073, 8360.163983601651, 2288.0733303012266, 2337.2170322941834, 2341.203990643181, 2342.61346877765, 2354.3054798834387, 2319.8724070176136, 2339.6841426407386, 2345.566070277004, 2364.3734410381176, 2369.7274813396507, 2296.5062511560013, 2298.2645553422913, 2312.3756585721844, 2318.0, 2327.2552783109413, 2010.286348346338, 2198.208644887839, 2237.2393386248755, 2253.5830871288863, 2276.2943487518755, 2053.8459615528745, 2319.153508969231, 2347.1550241912896, 2362.7282862470865, 2366.349253955159, 8368.246857782768, 8422.24199822019, 8425.283850872684, 8439.198276163756, 8449.32405407566, 4907.460179380265, 5100.464451232604, 5107.744612769384, 5109.565686916617, 5114.7698353573805, 2743.43759529296, 2952.217662319487, 2954.822710637347, 3236.91907702307, 3531.364354416204, 996.681062060141, 1022.6369638778201, 1037.424229552598, 1040.9167266618729, 1042.9009244121796, 10354.378737275787, 10551.525181366856, 10670.946645266802, 10688.519016644192, 10796.352218866894, 3770.321342158438, 4297.0, 4341.218580655449, 4482.103579284156, 4499.302608095685, 8607.569621519024, 8679.566021698889, 8707.956460217658, 8717.99743029547, 8744.038155802857, 5061.772220250024, 5111.361079865017, 5117.795288188505, 5118.769655365518, 5120.718360490135, 4295.871123866289, 4453.4433195850515, 4586.816527338936, 4620.422447194101, 4817.0, 3260.494623333387, 3636.1636823530207, 3718.442233664972, 3724.217914237986, 3802.7147963902817, 1125.2502358831932, 1130.1014295982827, 1137.2891942535914, 1138.5787258714163, 1152.0783373301492, 874.6719980007497, 876.2070326354659, 888.2804928008334, 916.5142474488532, 924.5608336040327, 5492.670439773627, 5558.777648893999, 5560.888782224414, 5772.769089236467, 5800.187973683687, 899.1233547291339, 900.4867225681298, 902.5712786426526, 910.9544522773834, 966.9323147379616, 3710.4805327254207, 3788.8484460621817, 3846.942295865564, 3873.9806300968316, 3947.2500224957166, 9661.758456038655, 10029.398236105711, 10126.493675316206, 10145.188384929188, 10180.643677471253, 10072.244581656405, 10085.798284034277, 10109.848352274721, 10110.0, 10121.69634910954, 5565.9443405566235, 7636.427267954926, 7682.8079298018, 7838.529688218725, 7852.411069169835, 5510.531604813636, 5685.459881311269, 5807.0, 5827.455724233167, 5838.766449342149, 7152.392046676091, 8974.19232269099, 9003.864942026006, 9074.319426043205, 9298.907010930076, 9331.673391431268, 9357.20463760588, 9415.105564971316, 9516.38143520669, 9549.522523873917, 6579.703913323816, 6796.286389929184, 6981.965090174516, 6994.475414343843, 7218.783436496913, 7167.892481612885, 8004.399670024888, 8010.319122874621, 8033.638486268249, 8040.316573091352, 7000.47496437759, 7140.6072666003865, 7151.964240178766, 7204.675789589376, 7260.927390726025, 6767.35710107539, 6795.93204067953, 6916.723331066701, 7013.2986701329455, 7019.859602808017, 6313.905291420724, 6525.543211975306, 6612.702428390638, 6618.569792963442, 6695.196576410862, 4130.876073717794, 4484.237679594542, 4782.187028205144, 4822.156122678587, 4904.896398310351, 4594.839180628662, 4685.437747470189, 4767.356406885026, 4809.182438985311, 4884.535969082932], "value": 4327.8998241390045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.89773170317187, 351.7713486233948, 359.33881657749737, 361.9963800361996, 368.8321813574824, 5419.729013549327, 5484.61607687462, 5489.14564426498, 5495.505404513593, 5528.364238112618, 5012.849614511561, 5379.220013098098, 5381.408045115044, 5424.945750542493, 5440.319960004999, 3579.964200358002, 3750.324941510527, 3991.32147534919, 4101.979490102545, 4127.979360103195, 3759.6804271636943, 4197.769122698251, 4208.684348673846, 4214.262504061792, 4234.640055595278, 3742.6818720408796, 4206.369044643313, 4229.821641854447, 4238.682098842583, 4240.766757828318, 3002.7297543221, 3663.230721548478, 4227.619514243717, 4240.491141063061, 4252.723572967747, 2906.9273268168254, 3470.861165553375, 3501.4747787831775, 3525.94711079334, 3567.357875582394, 4167.895802604929, 5272.604554658416, 5301.655392399482, 5304.9920515102, 5319.707416092114, 4482.417285752864, 4663.347131401607, 4683.765811709401, 4683.929741053887, 4708.4820669726305, 4767.785449654739, 4889.559939605452, 5597.412271711452, 5728.656280623176, 5756.0, 3578.6202175553226, 3867.7679339239735, 3868.78721670308, 3869.729118961674, 3872.3223435898885, 5395.838124856261, 5626.324841019104, 5818.243628328375, 5991.5206783457215, 6021.879562408771, 3030.7423868970945, 3850.6534411902503, 3873.709471789627, 3896.59085795993, 3937.4684417603257, 3648.7081033517256, 3929.155231625214, 3952.150287688161, 3969.821358038895, 3981.5819338969573, 4090.3250963590676, 4634.073185362941, 4666.066786642685, 4670.486246512881, 4700.318453824177, 4693.0, 5400.351957765093, 5687.317521897398, 5743.3395159557185, 5778.479936805707, 1102.7077824376547, 1149.5057125436142, 1149.545929357899, 1152.423788105947, 1255.1966741285585, 680.6290571638469, 866.7919699272129, 882.6381183714775, 882.6954700628273, 883.4743327719997, 4407.471103467605, 4710.599599034121, 4915.926261106087, 5118.846434606968, 5155.819546315861, 4667.556582124693, 5626.493615574536, 5659.603827732019, 5703.0, 5713.8285851424525, 4512.9097418052115, 4662.323963025342, 4925.655204135748, 5014.774335154927, 5163.509466600667, 2608.608708693711, 2788.4283721836973, 2842.872070756821, 2962.74831128344, 2982.8657710403086, 5872.7357268923, 5879.941200587939, 5882.411758824083, 5901.675407852609, 5902.291724993029, 5409.94422119592, 5415.102919937893, 5416.8104116355735, 5710.428957104339, 5841.824745257564, 5050.520200581012, 5351.785928562892, 5419.945800542102, 5652.74562644682, 5883.470487656046, 4770.427548694179, 5540.806071787468, 5614.24207731951, 5638.323401191883, 5657.575681823962, 4862.468322478408, 4943.332650092191, 5097.057044446789, 5145.897082058413, 5433.293671822796, 5116.974415128049, 5350.438203988525, 5386.8383948481605, 5763.827085187451, 5948.643081415129, 3866.594007629271, 3875.6124387561017, 3891.922161556696, 3892.863749768858, 3895.2599006188734, 5026.974865125651, 5487.286652735119, 5627.21219029328, 5908.143319218775, 5976.8804623908145, 4620.976895115503, 5368.208531563487, 5679.858003549779, 5767.509761670305, 5804.477597016289, 4911.870269837886, 5611.915821262603, 5681.2330335404195, 5699.059655156852, 5699.8860022800145, 4858.663867436483, 5163.0, 5612.915806262828, 5624.0, 5627.437256274504, 4723.834665786681, 5665.374160537342, 5688.383343661962, 5699.8860022800145, 5723.828285151452, 5642.266505354278, 5642.351129620146, 5666.518345940642, 5693.259876216065, 5711.685857277724], "value": 4081.6649561566833, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.8806802843519, 746.3059354800035, 754.2645920227778, 755.2296657409439, 765.5177238339846, 11559.65321040368, 15295.92352038241, 15335.92332038341, 15341.92329038356, 15413.306401211945, 13364.131331463448, 13437.932810335958, 13442.663933401669, 13449.193048417103, 13518.391672374742, 587.5916238214421, 702.097804321447, 841.8232171244047, 856.0540602634093, 873.5326600268858, 860.311750599521, 863.7236084452977, 881.0968757024037, 881.9073997230295, 884.1688812516214, 934.8223837470892, 934.9298802589799, 936.171488232912, 942.1661829281102, 943.9622415103388, 930.3208657679897, 931.3992474853749, 931.65063101337, 931.7018554062703, 932.6922115701802, 686.2177118085386, 686.6498085976139, 688.3323176518769, 689.3588962265092, 691.87892118879, 14466.855331446655, 14531.927340363336, 14752.704945901021, 14773.482928097465, 14938.850611493963, 11960.940195299056, 14069.0, 14321.283935803169, 14346.139231646135, 14362.784558231635, 11500.194986350958, 11615.361155136465, 11629.185956983014, 11839.822402663967, 12029.93985030078, 3634.709223262159, 3661.5789184243886, 3674.779513229215, 3684.705223582107, 3715.034091136297, 58970.70514647442, 97910.0, 112494.0, 120544.79455205423, 122143.0, 10727.892721072689, 10752.677419677422, 10804.297720648132, 10879.510422031028, 11042.392668403316, 21234.362969110953, 21369.572608547744, 21532.24637137724, 21556.784432155477, 21636.78363216348, 7819.609019549, 7880.84238315242, 7910.762677119696, 7913.0, 7995.521388369624, 4264.488261408651, 4353.9129217415475, 4354.107407981356, 4361.825526978949, 4371.803268852909, 3496.5629296337956, 3575.1419659282105, 3580.0333909844717, 3611.2777444511203, 3620.9637903621156, 604.6341963112342, 616.9321374648785, 659.9902149710854, 667.9432248258952, 670.0, 437.56243756243754, 520.6849855837164, 528.5058470330176, 596.1683451585101, 664.6809531424847, 8704.6880620278, 9168.404053736485, 9218.53907304632, 9360.438373697463, 9424.811503769886, 623.2427600465456, 668.6489592963733, 669.7350416718498, 677.5494296293009, 679.6296018669836, 4244.554321796229, 4249.2563801334645, 4542.958414457577, 4599.954000460019, 4671.929921051187, 84381.15618843734, 84807.0, 95267.04732952721, 97775.0, 99795.50102249443, 80674.0, 94761.52619236859, 96083.46998360241, 97847.53228701575, 99126.69649328843, 67254.0, 67747.98378024236, 68005.65997170082, 68793.0, 69532.0, 9299.539814528382, 9422.575984080595, 9751.902480975383, 9777.755556111155, 9920.206383489447, 39668.603313966494, 40694.77915662535, 52034.73982630214, 53861.65345866228, 54237.45762542324, 65291.69416611736, 66289.0, 69817.65091174512, 70755.29244707487, 75002.0, 12605.0, 12854.935725321313, 12894.355282235663, 13088.08383413152, 13088.73822523525, 14679.290175473636, 14694.338754755849, 14928.7760683592, 14947.701045979236, 15064.096154230781, 13299.268540229994, 13590.45638174462, 13686.178829270275, 13811.309434528433, 13916.58250252494, 13040.869591303965, 13151.276679782704, 13379.0, 13551.932240338736, 13619.93190034083, 12992.090553661154, 13332.133411328232, 13333.266670333225, 13699.589012329647, 13712.17726936387, 10889.911008899047, 11051.336919784839, 11214.15893807984, 11407.258528195973, 11856.170068095496, 10614.416207108683, 11189.339697692854, 11493.770124597628, 11534.884651153381, 11557.942210288895], "value": 7367.462190452707, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1648.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 118.0, 116.0, 117.0, 118.0, 118.0, 143.0, 176.0, 120.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 116.0, 156.0, 116.0, 118.0, 143.0, 107.0, 150.0, 152.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 203.0, 249.0, 288.0, 219.0, 202.0, 194.0, 203.0, 198.0, 250.0], "value": 221.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 57.0, 70.0, 110.0, 87.0, 83.0, 65.0, 60.0, 78.0, 60.0], "value": 77.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 85.0, 72.0, 73.0, 72.0, 81.0, 96.0, 60.0, 102.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 138.0, 120.0, 173.0, 168.0, 119.0, 113.0, 148.0, 113.0, 119.0], "value": 132.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 103.0, 97.0, 96.0, 98.0, 116.0, 155.0, 114.0, 154.0, 153.0], "value": 119.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 92.0, 92.0, 107.0, 138.0, 93.0, 94.0, 94.0, 90.0], "value": 98.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 58.0, 57.0, 58.0, 59.0, 57.0, 58.0, 59.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 69.0, 67.0, 84.0, 68.0, 97.0, 104.0, 98.0, 104.0, 102.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 98.0, 95.0, 91.0, 95.0, 98.0, 95.0, 158.0, 92.0], "value": 101.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 162.0, 148.0, 148.0, 146.0, 191.0, 156.0, 146.0, 164.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 238.0, 188.0, 183.0, 178.0, 182.0, 181.0, 180.0, 180.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 69.0, 68.0, 67.0, 69.0, 66.0, 85.0, 66.0], "value": 69.5, "unit": "score"}]}]} 00:07:40 INFO - Return code: 0 00:07:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:07:40 ERROR - # TBPL SUCCESS # 00:07:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:07:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:07:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:07:40 INFO - 2016-12-12 00:07:40.165 screenresolution[1929:23431] starting screenresolution argv=screenresolution get 00:07:40 INFO - 2016-12-12 00:07:40.168 screenresolution[1929:23431] Display 0: 1600x1200x32@60 00:07:40 INFO - 2016-12-12 00:07:40.174 screenresolution[1930:23434] starting screenresolution argv=screenresolution list 00:07:40 INFO - Available Modes on Display 0 00:07:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:07:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:07:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:07:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:07:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:07:40 INFO - Intel Iris: 00:07:40 INFO - Chipset Model: Intel Iris 00:07:40 INFO - Type: GPU 00:07:40 INFO - Bus: Built-In 00:07:40 INFO - VRAM (Dynamic, Max): 1536 MB 00:07:40 INFO - Vendor: Intel (0x8086) 00:07:40 INFO - Device ID: 0x0a2e 00:07:40 INFO - Revision ID: 0x0009 00:07:40 INFO - Displays: 00:07:40 INFO - Display: 00:07:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:07:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:07:40 INFO - Display Serial Number: 1600x1200 60 00:07:40 INFO - Main Display: Yes 00:07:40 INFO - Mirror: Off 00:07:40 INFO - Online: Yes 00:07:40 INFO - Rotation: Supported 00:07:40 INFO - Return code: 0 00:07:40 INFO - Running post-action listener: _resource_record_post_action 00:07:40 INFO - [mozharness: 2016-12-12 08:07:40.415948Z] Finished run-tests step (success) 00:07:40 INFO - Running post-run listener: _resource_record_post_run 00:07:40 INFO - Total resource usage - Wall time: 1203s; CPU: 55.0%; Read bytes: 5363200; Write bytes: 562680832; Read time: 148; Write time: 1941 00:07:40 INFO - TinderboxPrint: CPU usage
54.6% 00:07:40 INFO - TinderboxPrint: I/O read bytes / time
5,363,200 / 148 00:07:40 INFO - TinderboxPrint: I/O write bytes / time
562,680,832 / 1,941 00:07:40 INFO - TinderboxPrint: CPU idle
2,183.8 (45.4%) 00:07:40 INFO - TinderboxPrint: CPU system
973.4 (20.2%) 00:07:40 INFO - TinderboxPrint: CPU user
1,656.5 (34.4%) 00:07:40 INFO - TinderboxPrint: Swap in / out
253,030,400 / 0 00:07:40 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245387776; Write bytes: 239152128; Read time: 17951; Write time: 442 00:07:40 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 5326336; Write bytes: 309872640; Read time: 147; Write time: 1465 00:07:40 INFO - Running post-run listener: _upload_blobber_files 00:07:40 INFO - Blob upload gear active. 00:07:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:07:40 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:07:40 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:07:40 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:07:41 INFO - (blobuploader) - INFO - Open directory for files ... 00:07:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:07:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:07:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:42 INFO - (blobuploader) - INFO - Done attempting. 00:07:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:07:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:07:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:43 INFO - (blobuploader) - INFO - Done attempting. 00:07:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:07:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:46 INFO - (blobuploader) - INFO - Done attempting. 00:07:46 INFO - (blobuploader) - INFO - Iteration through files over. 00:07:46 INFO - Return code: 0 00:07:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:07:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:07:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/20062d9fb98f006b1d28c4751c32d079dde30b282f4904fd45fb15a0a4bebc46f0a42c49d7d61a09588454084c24162ddda928fb5bf594709be40cbf084d9139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1b7804d4ef4bae8fae74e1a336cfad69f5a2bee73cb049699c5f2b576f344789361e0d108d5c03367626e3db21894801d1cd19f97bd799909d31c611e4a57808", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/777afec4f7729dc2aa1ea10fd8231f84f2f611445833d9a14085486621b7a31be203eae8c1c4e22d0c9f1cd45048eee2dd891815e23c71fc7c2daf7bc827c2fb"} 00:07:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:07:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:07:46 INFO - Contents: 00:07:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/20062d9fb98f006b1d28c4751c32d079dde30b282f4904fd45fb15a0a4bebc46f0a42c49d7d61a09588454084c24162ddda928fb5bf594709be40cbf084d9139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1b7804d4ef4bae8fae74e1a336cfad69f5a2bee73cb049699c5f2b576f344789361e0d108d5c03367626e3db21894801d1cd19f97bd799909d31c611e4a57808", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/777afec4f7729dc2aa1ea10fd8231f84f2f611445833d9a14085486621b7a31be203eae8c1c4e22d0c9f1cd45048eee2dd891815e23c71fc7c2daf7bc827c2fb"} 00:07:46 INFO - Running post-run listener: copy_logs_to_upload_dir 00:07:46 INFO - Copying logs to upload dir... 00:07:46 INFO - mkdir: /builds/slave/test/build/upload/logs 00:07:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.072617 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-12-12 00:07:46.254308) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 00:07:46.257907) ========= 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.NhR3HsqfDv/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.SoVveMv3BU/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/Graphics/sha512/20062d9fb98f006b1d28c4751c32d079dde30b282f4904fd45fb15a0a4bebc46f0a42c49d7d61a09588454084c24162ddda928fb5bf594709be40cbf084d9139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1b7804d4ef4bae8fae74e1a336cfad69f5a2bee73cb049699c5f2b576f344789361e0d108d5c03367626e3db21894801d1cd19f97bd799909d31c611e4a57808", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/777afec4f7729dc2aa1ea10fd8231f84f2f611445833d9a14085486621b7a31be203eae8c1c4e22d0c9f1cd45048eee2dd891815e23c71fc7c2daf7bc827c2fb"} build_url:https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/TzHebdALS8iDNQVzfiUL2Q/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/20062d9fb98f006b1d28c4751c32d079dde30b282f4904fd45fb15a0a4bebc46f0a42c49d7d61a09588454084c24162ddda928fb5bf594709be40cbf084d9139", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1b7804d4ef4bae8fae74e1a336cfad69f5a2bee73cb049699c5f2b576f344789361e0d108d5c03367626e3db21894801d1cd19f97bd799909d31c611e4a57808", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/777afec4f7729dc2aa1ea10fd8231f84f2f611445833d9a14085486621b7a31be203eae8c1c4e22d0c9f1cd45048eee2dd891815e23c71fc7c2daf7bc827c2fb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-12 00:07:46.292812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 00:07:46.293151) ========= 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.NhR3HsqfDv/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.SoVveMv3BU/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.005205 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-12 00:07:46.343300) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 00:07:46.343728) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-12 00:07:46.344344) ========= ========= Total master_lag: 0.22 =========