builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0170 starttime: 1479969204.13 results: success (0) buildid: 20161123185735 builduid: ae46e924bc1a45a09a439eebf476adca revision: 50f234c53a7a97548a655efd992d04c2792a1954 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.135251) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.135680) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.135972) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.169324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.169728) ========= 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.DNTQFJIYom/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.idt7R3vjrL/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-11-23 22:33:24-- 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% 145M=0s 2016-11-23 22:33:24 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123822 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.319728) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.320062) ========= 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.DNTQFJIYom/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.idt7R3vjrL/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.025859 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:24.369320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 22:33:24.369665) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 50f234c53a7a97548a655efd992d04c2792a1954 --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 50f234c53a7a97548a655efd992d04c2792a1954 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DNTQFJIYom/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.idt7R3vjrL/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-11-23 22:33:24,464 truncating revision to first 12 chars 2016-11-23 22:33:24,464 Setting DEBUG logging. 2016-11-23 22:33:24,464 attempt 1/10 2016-11-23 22:33:24,464 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/50f234c53a7a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 22:33:25,289 unpacking tar archive at: graphics-50f234c53a7a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.094088 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 22:33:25.487164) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:25.487675) ========= script_repo_revision: 50f234c53a7a97548a655efd992d04c2792a1954 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:25.488093) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:25.488436) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 22:33:25.508039) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-23 22:33:25.508367) ========= /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.DNTQFJIYom/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.idt7R3vjrL/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:33:25 INFO - MultiFileLogger online at 20161123 22:33:25 in /builds/slave/test 22:33:25 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 22:33:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:33:25 INFO - {'append_to_log': False, 22:33:25 INFO - 'base_work_dir': '/builds/slave/test', 22:33:25 INFO - 'blob_upload_branch': 'Graphics', 22:33:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:33:25 INFO - 'branch': 'Graphics', 22:33:25 INFO - 'buildbot_json_path': 'buildprops.json', 22:33:25 INFO - 'config_files': ('talos/mac_config.py',), 22:33:25 INFO - 'default_actions': ('clobber', 22:33:25 INFO - 'read-buildbot-config', 22:33:25 INFO - 'download-and-extract', 22:33:25 INFO - 'populate-webroot', 22:33:25 INFO - 'create-virtualenv', 22:33:25 INFO - 'install', 22:33:25 INFO - 'run-tests'), 22:33:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:33:25 INFO - 'download_minidump_stackwalk': True, 22:33:25 INFO - 'download_symbols': 'ondemand', 22:33:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:33:25 INFO - 'tooltool.py': '/tools/tooltool.py', 22:33:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:33:25 INFO - '/tools/misc-python/virtualenv.py')}, 22:33:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:33:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:33:25 INFO - 'installer_path': 'installer.exe', 22:33:25 INFO - 'log_level': 'info', 22:33:25 INFO - 'log_name': 'talos', 22:33:25 INFO - 'log_to_console': True, 22:33:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:33:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:33:25 INFO - 'opt_config_files': (), 22:33:25 INFO - 'pip_index': False, 22:33:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:33:25 INFO - 'cmd': ('bash', 22:33:25 INFO - '-c', 22:33:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:33:25 INFO - 'enabled': True, 22:33:25 INFO - 'halt_on_failure': False, 22:33:25 INFO - 'name': 'check_screen_resolution'},), 22:33:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:33:25 INFO - 'cmd': ('bash', 22:33:25 INFO - '-c', 22:33:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:33:25 INFO - 'enabled': True, 22:33:25 INFO - 'halt_on_failure': False, 22:33:25 INFO - 'name': 'check_screen_resolution'},), 22:33:25 INFO - 'run_cmd_checks_enabled': True, 22:33:25 INFO - 'sps_profile': False, 22:33:25 INFO - 'sps_profile_interval': 0, 22:33:25 INFO - 'suite': 'dromaeojs-e10s', 22:33:25 INFO - 'system_bits': '32', 22:33:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:33:25 INFO - 'title': 't-yosemite-r7-0170', 22:33:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:33:25 INFO - 'use_talos_json': True, 22:33:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:33:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:33:25 INFO - 'work_dir': 'build'} 22:33:25 INFO - [mozharness: 2016-11-24 06:33:25.715890Z] Running clobber step. 22:33:25 INFO - Running pre-action listener: _resource_record_pre_action 22:33:25 INFO - Running main action method: clobber 22:33:25 INFO - rmtree: /builds/slave/test/build 22:33:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:33:28 INFO - Running post-action listener: _resource_record_post_action 22:33:28 INFO - [mozharness: 2016-11-24 06:33:28.207639Z] Finished clobber step (success) 22:33:28 INFO - [mozharness: 2016-11-24 06:33:28.207830Z] Running read-buildbot-config step. 22:33:28 INFO - Running pre-action listener: _resource_record_pre_action 22:33:28 INFO - Running main action method: read_buildbot_config 22:33:28 INFO - Using buildbot properties: 22:33:28 INFO - { 22:33:28 INFO - "project": "", 22:33:28 INFO - "product": "firefox", 22:33:28 INFO - "script_repo_revision": "default", 22:33:28 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 22:33:28 INFO - "repository": "", 22:33:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 22:33:28 INFO - "buildid": "20161123185735", 22:33:28 INFO - "pgo_build": "False", 22:33:28 INFO - "basedir": "/builds/slave/test", 22:33:28 INFO - "buildnumber": 4, 22:33:28 INFO - "slavename": "t-yosemite-r7-0170", 22:33:28 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:33:28 INFO - "platform": "yosemite_r7", 22:33:28 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 22:33:28 INFO - "branch": "graphics", 22:33:28 INFO - "repo_path": "projects/graphics", 22:33:28 INFO - "revision": "50f234c53a7a97548a655efd992d04c2792a1954", 22:33:28 INFO - "stage_platform": "macosx64", 22:33:28 INFO - "builduid": "ae46e924bc1a45a09a439eebf476adca", 22:33:28 INFO - "slavebuilddir": "test" 22:33:28 INFO - } 22:33:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 22:33:28 INFO - Running post-action listener: _resource_record_post_action 22:33:28 INFO - [mozharness: 2016-11-24 06:33:28.213427Z] Finished read-buildbot-config step (success) 22:33:28 INFO - [mozharness: 2016-11-24 06:33:28.213578Z] Running download-and-extract step. 22:33:28 INFO - Running pre-action listener: _resource_record_pre_action 22:33:28 INFO - Running main action method: download_and_extract 22:33:28 INFO - mkdir: /builds/slave/test/build/tests 22:33:28 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')]} 22:33:28 INFO - trying https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 22:33:28 INFO - Downloading https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:33:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 22:33:29 INFO - Downloaded 1199 bytes. 22:33:29 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:33:29 INFO - Using the following test package requirements: 22:33:29 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:33:29 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 22:33:29 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 22:33:29 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'jsshell-mac.zip'], 22:33:29 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 22:33:29 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:33:29 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 22:33:29 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 22:33:29 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 22:33:29 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:33:29 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 22:33:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:33:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:33:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:33:29 INFO - Fetch https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:33:29 INFO - Http code: 200 22:33:29 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 22:33:29 INFO - x-amz-cf-id: y-iYRgmw8sLfYSO9TGyodpd7A5SitFlMWSo10gBof6wS94YkgOL0MQ== 22:33:29 INFO - x-amz-version-id: IbwVFMeqCCviF4lx.UujqeXi8N.5Hs1. 22:33:30 INFO - Expected file size: 16267206 22:33:30 INFO - Obtained file size: 16267206 22:33:36 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:33:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:33:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:33:36 INFO - Fetch https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:33:37 INFO - Http code: 200 22:33:37 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 22:33:37 INFO - x-amz-cf-id: pex2C-sPL3zBwF45oiWenIUenlXqnYDRcp36l01LHgAi1FFCy_l6Iw== 22:33:37 INFO - x-amz-version-id: IbwVFMeqCCviF4lx.UujqeXi8N.5Hs1. 22:33:37 INFO - Expected file size: 16267206 22:33:37 INFO - Obtained file size: 16267206 22:33:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 22:33:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:33:43 INFO - Fetch https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 22:33:44 INFO - Http code: 200 22:33:44 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 22:33:44 INFO - x-amz-cf-id: tsDZZw_5ZLbFan_HDyudDhd0sLxMYZwSehTW4XK0-K93OVjyf-t9Ww== 22:33:44 INFO - x-amz-version-id: H1KGYXODO1zmjxo0dQfqtUiJzg9LRuD4 22:33:44 INFO - Expected file size: 14409506 22:33:44 INFO - Obtained file size: 14409506 22:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:45 INFO - trying https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:33:45 INFO - Downloading https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 22:33:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 22:33:47 INFO - Downloaded 107609456 bytes. 22:33:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:33:47 INFO - mkdir: /builds/slave/test/properties 22:33:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:33:47 INFO - Writing to file /builds/slave/test/properties/build_url 22:33:47 INFO - Contents: 22:33:47 INFO - build_url:https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:33:48 INFO - Running post-action listener: _resource_record_post_action 22:33:48 INFO - Running post-action listener: set_extra_try_arguments 22:33:48 INFO - [mozharness: 2016-11-24 06:33:48.605753Z] Finished download-and-extract step (success) 22:33:48 INFO - [mozharness: 2016-11-24 06:33:48.605913Z] Running populate-webroot step. 22:33:48 INFO - Running pre-action listener: _resource_record_pre_action 22:33:48 INFO - Running main action method: populate_webroot 22:33:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:33:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:33:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:33:48 INFO - '--tppagecycles', 22:33:48 INFO - '7'], 22:33:48 INFO - 'tests': ['tsvgm']}}, 22:33:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:33:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:33:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:33:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:33:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:33:48 INFO - 'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:33:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:33:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:33:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:33:48 INFO - 'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['damp', 'tps']}, 22:33:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:33:48 INFO - 'tests': ['damp', 'tps']}, 22:33:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['dromaeo_dom']}, 22:33:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:33:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['basic_compositor_video']}, 22:33:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:33:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['a11yr', 22:33:48 INFO - 'ts_paint', 22:33:48 INFO - 'tpaint', 22:33:48 INFO - 'sessionrestore', 22:33:48 INFO - 'sessionrestore_no_auto_restore', 22:33:48 INFO - 'tabpaint']}, 22:33:48 INFO - 'other-e10s': {'tests': ['a11yr', 22:33:48 INFO - 'ts_paint', 22:33:48 INFO - 'tpaint', 22:33:48 INFO - 'sessionrestore', 22:33:48 INFO - 'sessionrestore_no_auto_restore', 22:33:48 INFO - 'tabpaint']}, 22:33:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:33:48 INFO - 'ts_paint', 22:33:48 INFO - 'tpaint', 22:33:48 INFO - 'sessionrestore', 22:33:48 INFO - 'sessionrestore_no_auto_restore', 22:33:48 INFO - 'tabpaint']}, 22:33:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:33:48 INFO - 'ts_paint', 22:33:48 INFO - 'tpaint', 22:33:48 INFO - 'sessionrestore', 22:33:48 INFO - 'sessionrestore_no_auto_restore', 22:33:48 INFO - 'tabpaint']}, 22:33:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['a11yr', 22:33:48 INFO - 'ts_paint', 22:33:48 INFO - 'tpaint', 22:33:48 INFO - 'sessionrestore', 22:33:48 INFO - 'sessionrestore_no_auto_restore', 22:33:48 INFO - 'tabpaint']}, 22:33:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['a11yr', 22:33:48 INFO - 'ts_paint', 22:33:48 INFO - 'tpaint', 22:33:48 INFO - 'sessionrestore', 22:33:48 INFO - 'sessionrestore_no_auto_restore', 22:33:48 INFO - 'tabpaint']}, 22:33:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['tsvgx', 22:33:48 INFO - 'tsvgr_opacity', 22:33:48 INFO - 'tart', 22:33:48 INFO - 'tscrollx', 22:33:48 INFO - 'cart']}, 22:33:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:33:48 INFO - 'tsvgr_opacity', 22:33:48 INFO - 'tart', 22:33:48 INFO - 'tscrollx', 22:33:48 INFO - 'cart']}, 22:33:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:33:48 INFO - 'talos_options': ['--disable-e10s'], 22:33:48 INFO - 'tests': ['tp5o']}, 22:33:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:33:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:33:48 INFO - 'talos_options': ['--disable-e10s', 22:33:48 INFO - '--xperf_path', 22:33:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:33:48 INFO - 'tests': ['tp5n']}, 22:33:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:33:48 INFO - 'talos_options': ['--xperf_path', 22:33:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:33:48 INFO - 'tests': ['tp5n']}}, 22:33:48 INFO - 'talos.zip': {'path': '', 22:33:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:33:48 INFO - Running post-action listener: _resource_record_post_action 22:33:48 INFO - [mozharness: 2016-11-24 06:33:48.624343Z] Finished populate-webroot step (success) 22:33:48 INFO - [mozharness: 2016-11-24 06:33:48.624472Z] Running create-virtualenv step. 22:33:48 INFO - Running pre-action listener: _resource_record_pre_action 22:33:48 INFO - Running main action method: create_virtualenv 22:33:48 INFO - Creating virtualenv /builds/slave/test/build/venv 22:33:48 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 22:33:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:33:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:33:48 INFO - Using real prefix '/tools/python27' 22:33:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:33:49 INFO - Installing distribute.............................................................................................................................................................................................done. 22:33:52 INFO - Installing pip.................done. 22:33:52 INFO - Return code: 0 22:33:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:33:52 INFO - Reading from file tmpfile_stdout 22:33:52 INFO - Output received: 22:33:52 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) 22:33:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:33:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:52 INFO - 'HOME': '/Users/cltbld', 22:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:52 INFO - 'LOGNAME': 'cltbld', 22:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:52 INFO - 'MOZ_NO_REMOTE': '1', 22:33:52 INFO - 'NO_EM_RESTART': '1', 22:33:52 INFO - 'PAGER': '/bin/cat', 22:33:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:52 INFO - 'PWD': '/builds/slave/test', 22:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:52 INFO - 'SHELL': '/bin/bash', 22:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:33:52 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:52 INFO - 'USER': 'cltbld', 22:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:52 INFO - 'XPC_FLAGS': '0x0', 22:33:52 INFO - 'XPC_SERVICE_NAME': '0', 22:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:52 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 22:33:52 INFO - Cleaning up... 22:33:52 INFO - Return code: 0 22:33:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:33:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:52 INFO - 'HOME': '/Users/cltbld', 22:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:52 INFO - 'LOGNAME': 'cltbld', 22:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:52 INFO - 'MOZ_NO_REMOTE': '1', 22:33:52 INFO - 'NO_EM_RESTART': '1', 22:33:52 INFO - 'PAGER': '/bin/cat', 22:33:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:52 INFO - 'PWD': '/builds/slave/test', 22:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:52 INFO - 'SHELL': '/bin/bash', 22:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:33:52 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:52 INFO - 'USER': 'cltbld', 22:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:52 INFO - 'XPC_FLAGS': '0x0', 22:33:52 INFO - 'XPC_SERVICE_NAME': '0', 22:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:53 INFO - Downloading/unpacking psutil>=3.1.1 22:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:33:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:56 INFO - Installing collected packages: psutil 22:33:56 INFO - Running setup.py install for psutil 22:33:56 INFO - building 'psutil._psutil_osx' extension 22:33:56 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 22:33:57 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 22:33:57 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 22:33:57 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 22:33:57 INFO - building 'psutil._psutil_posix' extension 22:33:57 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 22:33:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:33:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:33:57 INFO - ^ 22:33:57 INFO - 1 warning generated. 22:33:57 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 22:33:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:33:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:33:57 INFO - Successfully installed psutil 22:33:57 INFO - Cleaning up... 22:33:57 INFO - Return code: 0 22:33:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:33:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:33:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:33:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:33:57 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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:33:57 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 22:33:57 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 22:33:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:33:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:33:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:33:57 INFO - 'HOME': '/Users/cltbld', 22:33:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:33:57 INFO - 'LOGNAME': 'cltbld', 22:33:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:33:57 INFO - 'MOZ_NO_REMOTE': '1', 22:33:57 INFO - 'NO_EM_RESTART': '1', 22:33:57 INFO - 'PAGER': '/bin/cat', 22:33:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:33:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:33:57 INFO - 'PWD': '/builds/slave/test', 22:33:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:33:57 INFO - 'SHELL': '/bin/bash', 22:33:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:33:57 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:33:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:33:57 INFO - 'USER': 'cltbld', 22:33:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:33:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:33:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:33:57 INFO - 'XPC_FLAGS': '0x0', 22:33:57 INFO - 'XPC_SERVICE_NAME': '0', 22:33:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:33:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:33:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:34:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:34:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:34:00 INFO - Installing collected packages: mozsystemmonitor 22:34:00 INFO - Running setup.py install for mozsystemmonitor 22:34:00 INFO - Successfully installed mozsystemmonitor 22:34:00 INFO - Cleaning up... 22:34:00 INFO - Return code: 0 22:34:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:34: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')]} 22:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34: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')]} 22:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:00 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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:34:00 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 22:34:00 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 22:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:00 INFO - 'HOME': '/Users/cltbld', 22:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:00 INFO - 'LOGNAME': 'cltbld', 22:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:00 INFO - 'MOZ_NO_REMOTE': '1', 22:34:00 INFO - 'NO_EM_RESTART': '1', 22:34:00 INFO - 'PAGER': '/bin/cat', 22:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:00 INFO - 'PWD': '/builds/slave/test', 22:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:00 INFO - 'SHELL': '/bin/bash', 22:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:00 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:00 INFO - 'USER': 'cltbld', 22:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:00 INFO - 'XPC_FLAGS': '0x0', 22:34:00 INFO - 'XPC_SERVICE_NAME': '0', 22:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:01 INFO - Downloading/unpacking jsonschema==2.5.1 22:34:01 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 22:34:01 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 22:34:01 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 22:34:01 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 22:34:01 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 22:34:01 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 22:34:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:34:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:34:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:34:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:34:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:34:04 INFO - Downloading functools32-3.2.3-2.tar.gz 22:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:34:04 INFO - warning: no files found matching '*.txt' 22:34:04 INFO - no previously-included directories found matching 'build' 22:34:04 INFO - no previously-included directories found matching 'dist' 22:34:04 INFO - no previously-included directories found matching '.git*' 22:34:04 INFO - Installing collected packages: jsonschema, functools32 22:34:04 INFO - Running setup.py install for functools32 22:34:04 INFO - warning: no files found matching '*.txt' 22:34:04 INFO - no previously-included directories found matching 'build' 22:34:04 INFO - no previously-included directories found matching 'dist' 22:34:04 INFO - no previously-included directories found matching '.git*' 22:34:04 INFO - Successfully installed jsonschema functools32 22:34:04 INFO - Cleaning up... 22:34:04 INFO - Return code: 0 22:34:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:04 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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:34:04 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 22:34:04 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 22:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:04 INFO - 'HOME': '/Users/cltbld', 22:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:04 INFO - 'LOGNAME': 'cltbld', 22:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:04 INFO - 'MOZ_NO_REMOTE': '1', 22:34:04 INFO - 'NO_EM_RESTART': '1', 22:34:04 INFO - 'PAGER': '/bin/cat', 22:34:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:04 INFO - 'PWD': '/builds/slave/test', 22:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:04 INFO - 'SHELL': '/bin/bash', 22:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:04 INFO - 'USER': 'cltbld', 22:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:04 INFO - 'XPC_FLAGS': '0x0', 22:34:04 INFO - 'XPC_SERVICE_NAME': '0', 22:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:34:04 INFO - Cleaning up... 22:34:04 INFO - Return code: 0 22:34:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:04 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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:34:04 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 22:34:04 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 22:34:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:04 INFO - 'HOME': '/Users/cltbld', 22:34:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:04 INFO - 'LOGNAME': 'cltbld', 22:34:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:04 INFO - 'MOZ_NO_REMOTE': '1', 22:34:04 INFO - 'NO_EM_RESTART': '1', 22:34:04 INFO - 'PAGER': '/bin/cat', 22:34:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:04 INFO - 'PWD': '/builds/slave/test', 22:34:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:04 INFO - 'SHELL': '/bin/bash', 22:34:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:04 INFO - 'USER': 'cltbld', 22:34:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:04 INFO - 'XPC_FLAGS': '0x0', 22:34:04 INFO - 'XPC_SERVICE_NAME': '0', 22:34:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:04 INFO - Downloading/unpacking blobuploader==1.2.4 22:34:04 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 22:34:04 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 22:34:04 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 22:34:04 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 22:34:04 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 22:34:04 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 22:34:07 INFO - Downloading blobuploader-1.2.4.tar.gz 22:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:34:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:34:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:34:08 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 22:34:08 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 22:34:08 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 22:34:08 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 22:34:08 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 22:34:08 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 22:34:08 INFO - Downloading docopt-0.6.1.tar.gz 22:34:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:34:08 INFO - Installing collected packages: blobuploader, requests, docopt 22:34:08 INFO - Running setup.py install for blobuploader 22:34:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:34:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:34:08 INFO - Running setup.py install for requests 22:34:09 INFO - Running setup.py install for docopt 22:34:09 INFO - Successfully installed blobuploader requests docopt 22:34:09 INFO - Cleaning up... 22:34:09 INFO - Return code: 0 22:34:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:09 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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:34:09 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 22:34:09 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 22:34:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:09 INFO - 'HOME': '/Users/cltbld', 22:34:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:09 INFO - 'LOGNAME': 'cltbld', 22:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:09 INFO - 'MOZ_NO_REMOTE': '1', 22:34:09 INFO - 'NO_EM_RESTART': '1', 22:34:09 INFO - 'PAGER': '/bin/cat', 22:34:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:09 INFO - 'PWD': '/builds/slave/test', 22:34:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:09 INFO - 'SHELL': '/bin/bash', 22:34:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:09 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:09 INFO - 'USER': 'cltbld', 22:34:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:09 INFO - 'XPC_FLAGS': '0x0', 22:34:09 INFO - 'XPC_SERVICE_NAME': '0', 22:34:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:34:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-OfLHAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ysknPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tgrdMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Y4PqRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-inpIOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-GaWrUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ifneqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-dyQ1No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-4F0l4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-kXUTtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:34:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-0bj6FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:34:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-_SqxHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:34:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-rpOtkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:34:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-F6nNnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-R14r0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:34:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-HWwFGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:34:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-uI0GZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:34:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:34:11 INFO - Running setup.py install for manifestparser 22:34:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:34:11 INFO - Running setup.py install for mozcrash 22:34:12 INFO - Running setup.py install for mozdebug 22:34:12 INFO - Running setup.py install for mozdevice 22:34:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Running setup.py install for mozfile 22:34:12 INFO - Running setup.py install for mozhttpd 22:34:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Running setup.py install for mozinfo 22:34:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Running setup.py install for mozInstall 22:34:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:34:12 INFO - Running setup.py install for mozleak 22:34:13 INFO - Running setup.py install for mozlog 22:34:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:34:13 INFO - Running setup.py install for moznetwork 22:34:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:34:13 INFO - Running setup.py install for mozprocess 22:34:13 INFO - Running setup.py install for mozprofile 22:34:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:34:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:34:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:34:13 INFO - Running setup.py install for mozrunner 22:34:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:34:13 INFO - Running setup.py install for mozscreenshot 22:34:13 INFO - Running setup.py install for moztest 22:34:14 INFO - Running setup.py install for mozversion 22:34:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:34:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:34:14 INFO - Cleaning up... 22:34:14 INFO - Return code: 0 22:34:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:15 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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:34:15 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 22:34:15 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 22:34:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:15 INFO - 'HOME': '/Users/cltbld', 22:34:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:15 INFO - 'LOGNAME': 'cltbld', 22:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:15 INFO - 'MOZ_NO_REMOTE': '1', 22:34:15 INFO - 'NO_EM_RESTART': '1', 22:34:15 INFO - 'PAGER': '/bin/cat', 22:34:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:15 INFO - 'PWD': '/builds/slave/test', 22:34:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:15 INFO - 'SHELL': '/bin/bash', 22:34:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:15 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:15 INFO - 'USER': 'cltbld', 22:34:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:15 INFO - 'XPC_FLAGS': '0x0', 22:34:15 INFO - 'XPC_SERVICE_NAME': '0', 22:34:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:34:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-1Tjag8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:34:15 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)) 22:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:34:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Up_d5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:34:15 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)) 22:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:34:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-GQ7kxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:34:15 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)) 22:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:34:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-vcXqjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:34:15 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)) 22:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:34:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-k2MTX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:34:15 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)) 22:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:34:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-hGIUPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:34:15 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)) 22:34:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:34:15 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-NJwq6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Ppq_aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-h_I7_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-WnsvQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-bs7NvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-XfoE5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-7ivPy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-M3wslD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-9CMADb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:34:16 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)) 22:34:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:34:16 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-mPfGyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:34:17 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)) 22:34:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:34:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-BIXu1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:34:17 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)) 22:34:17 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)) 22:34:17 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)) 22:34:17 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)) 22:34:17 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)) 22:34:17 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)) 22:34:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34: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 22:34:19 INFO - Downloading blessings-1.6.tar.gz 22:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:34:19 INFO - Installing collected packages: blessings 22:34:19 INFO - Running setup.py install for blessings 22:34:19 INFO - Successfully installed blessings 22:34:19 INFO - Cleaning up... 22:34:20 INFO - Return code: 0 22:34:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:34:20 INFO - Reading from file tmpfile_stdout 22:34:20 INFO - Current package versions: 22:34:20 INFO - blessings == 1.6 22:34:20 INFO - blobuploader == 1.2.4 22:34:20 INFO - docopt == 0.6.1 22:34:20 INFO - functools32 == 3.2.3-2 22:34:20 INFO - jsonschema == 2.5.1 22:34:20 INFO - manifestparser == 1.1 22:34:20 INFO - mozInstall == 1.12 22:34:20 INFO - mozcrash == 1.0 22:34:20 INFO - mozdebug == 0.1 22:34:20 INFO - mozdevice == 0.49 22:34:20 INFO - mozfile == 1.2 22:34:20 INFO - mozhttpd == 0.7 22:34:20 INFO - mozinfo == 0.9 22:34:20 INFO - mozleak == 0.1 22:34:20 INFO - mozlog == 3.4 22:34:20 INFO - moznetwork == 0.27 22:34:20 INFO - mozprocess == 0.23 22:34:20 INFO - mozprofile == 0.28 22:34:20 INFO - mozrunner == 6.13 22:34:20 INFO - mozscreenshot == 0.1 22:34:20 INFO - mozsystemmonitor == 0.3 22:34:20 INFO - moztest == 0.7 22:34:20 INFO - mozversion == 1.4 22:34:20 INFO - psutil == 3.1.1 22:34:20 INFO - requests == 1.2.3 22:34:20 INFO - wsgiref == 0.1.2 22:34:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:34: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')]} 22:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34: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')]} 22:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34:20 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 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:34:20 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 22:34:20 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 22:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:20 INFO - 'HOME': '/Users/cltbld', 22:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:20 INFO - 'LOGNAME': 'cltbld', 22:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:20 INFO - 'MOZ_NO_REMOTE': '1', 22:34:20 INFO - 'NO_EM_RESTART': '1', 22:34:20 INFO - 'PAGER': '/bin/cat', 22:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:20 INFO - 'PWD': '/builds/slave/test', 22:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:20 INFO - 'SHELL': '/bin/bash', 22:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:20 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:20 INFO - 'USER': 'cltbld', 22:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:20 INFO - 'XPC_FLAGS': '0x0', 22:34:20 INFO - 'XPC_SERVICE_NAME': '0', 22:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 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)) 22:34:20 INFO - Cleaning up... 22:34:20 INFO - Return code: 0 22:34:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:34: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')]} 22:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:34:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34: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')]} 22:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:34:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:34: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10adcc3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad42570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc69c56270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, '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.DNTQFJIYom/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.idt7R3vjrL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 22:34: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', 'jsonschema'] in /builds/slave/test/build 22:34: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 jsonschema 22:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:20 INFO - 'HOME': '/Users/cltbld', 22:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:20 INFO - 'LOGNAME': 'cltbld', 22:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:20 INFO - 'MOZ_NO_REMOTE': '1', 22:34:20 INFO - 'NO_EM_RESTART': '1', 22:34:20 INFO - 'PAGER': '/bin/cat', 22:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:20 INFO - 'PWD': '/builds/slave/test', 22:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:20 INFO - 'SHELL': '/bin/bash', 22:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:20 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:20 INFO - 'USER': 'cltbld', 22:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:20 INFO - 'XPC_FLAGS': '0x0', 22:34:20 INFO - 'XPC_SERVICE_NAME': '0', 22:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:34:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:34:20 INFO - Cleaning up... 22:34:20 INFO - Return code: 0 22:34:20 INFO - Running post-action listener: _resource_record_post_action 22:34:20 INFO - Running post-action listener: _start_resource_monitoring 22:34:20 INFO - Starting resource monitoring. 22:34:20 INFO - [mozharness: 2016-11-24 06:34:20.893209Z] Finished create-virtualenv step (success) 22:34:20 INFO - [mozharness: 2016-11-24 06:34:20.894194Z] Running install step. 22:34:20 INFO - Running pre-action listener: _resource_record_pre_action 22:34:20 INFO - Running main action method: install 22:34:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:34:21 INFO - Reading from file tmpfile_stdout 22:34:21 INFO - Detecting whether we're running mozinstall >=1.0... 22:34:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:34:21 INFO - Reading from file tmpfile_stdout 22:34:21 INFO - Output received: 22:34:21 INFO - Usage: mozinstall [options] installer 22:34:21 INFO - Options: 22:34:21 INFO - -h, --help show this help message and exit 22:34:21 INFO - -d DEST, --destination=DEST 22:34:21 INFO - Directory to install application into. [default: 22:34:21 INFO - "/builds/slave/test"] 22:34:21 INFO - --app=APP Application being installed. [default: firefox] 22:34:21 INFO - mkdir: /builds/slave/test/build/application 22:34:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:34:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:34:53 INFO - Reading from file tmpfile_stdout 22:34:53 INFO - Output received: 22:34:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:34:53 INFO - Running post-action listener: _resource_record_post_action 22:34:53 INFO - [mozharness: 2016-11-24 06:34:53.916492Z] Finished install step (success) 22:34:53 INFO - [mozharness: 2016-11-24 06:34:53.916624Z] Running run-tests step. 22:34:53 INFO - Running pre-action listener: _resource_record_pre_action 22:34:53 INFO - Running main action method: run_tests 22:34:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:34:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:34:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:34:53 INFO - 2016-11-23 22:34:53.931 screenresolution[1622:7519] starting screenresolution argv=screenresolution get 22:34:53 INFO - 2016-11-23 22:34:53.934 screenresolution[1622:7519] Display 0: 1600x1200x32@60 22:34:53 INFO - 2016-11-23 22:34:53.939 screenresolution[1623:7521] starting screenresolution argv=screenresolution list 22:34:53 INFO - Available Modes on Display 0 22:34:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:34:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:34:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:34:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:34:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:34:54 INFO - Intel Iris: 22:34:54 INFO - Chipset Model: Intel Iris 22:34:54 INFO - Type: GPU 22:34:54 INFO - Bus: Built-In 22:34:54 INFO - VRAM (Dynamic, Max): 1536 MB 22:34:54 INFO - Vendor: Intel (0x8086) 22:34:54 INFO - Device ID: 0x0a2e 22:34:54 INFO - Revision ID: 0x0009 22:34:54 INFO - Displays: 22:34:54 INFO - Display: 22:34:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:34:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:34:54 INFO - Display Serial Number: 1600x1200 60 22:34:54 INFO - Main Display: Yes 22:34:54 INFO - Mirror: Off 22:34:54 INFO - Online: Yes 22:34:54 INFO - Rotation: Supported 22:34:54 INFO - Return code: 0 22:34:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:34:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:34:54 INFO - Python 2.7.3 22:34:54 INFO - Return code: 0 22:34:54 INFO - grabbing minidump binary from tooltool 22:34:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:34:54 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 0x10abe2d30>, '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 0x10aa49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abdab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:34:54 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 22:34:54 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 22:34:54 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 22:34:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:34:54 INFO - Return code: 0 22:34:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:34:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:34:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:34:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:34:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:34:54 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/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 22:34:54 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/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --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 22:34:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DNTQFJIYom/Render', 22:34:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:34:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:34:54 INFO - 'HOME': '/Users/cltbld', 22:34:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:34:54 INFO - 'LOGNAME': 'cltbld', 22:34:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:34:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:34:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:34:54 INFO - 'MOZ_NO_REMOTE': '1', 22:34:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:34:54 INFO - 'NO_EM_RESTART': '1', 22:34:54 INFO - 'PAGER': '/bin/cat', 22:34:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:34:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:34:54 INFO - 'PWD': '/builds/slave/test', 22:34:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:34:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:34:54 INFO - 'SHELL': '/bin/bash', 22:34:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.idt7R3vjrL/Listeners', 22:34:54 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 22:34:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:34:54 INFO - 'USER': 'cltbld', 22:34:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:34:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:34:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:34:54 INFO - 'XPC_FLAGS': '0x0', 22:34:54 INFO - 'XPC_SERVICE_NAME': '0', 22:34:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:34:54 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/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 22:34:54 INFO - mozversion application_buildid: 20161123185735 22:34:54 INFO - mozversion application_changeset: 50f234c53a7a97548a655efd992d04c2792a1954 22:34:54 INFO - mozversion application_display_name: Nightly 22:34:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:34:54 INFO - mozversion application_name: Firefox 22:34:54 INFO - mozversion application_remotingname: firefox 22:34:54 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 22:34:54 INFO - mozversion application_vendor: Mozilla 22:34:54 INFO - mozversion application_version: 53.0a1 22:34:54 INFO - mozversion platform_buildid: 20161123185735 22:34:54 INFO - mozversion platform_changeset: 50f234c53a7a97548a655efd992d04c2792a1954 22:34:54 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 22:34:54 INFO - mozversion platform_version: 53.0a1 22:34:54 INFO - using testdate: 1479969294 22:34:54 INFO - actual date: 1479969294 22:34:54 INFO - starting webserver on 'localhost:49236' 22:34:54 INFO - SUITE-START | Running 2 tests 22:34:54 INFO - TEST-START | dromaeo_css 22:34:54 INFO - operating with platform_type : mac_ 22:34:54 INFO - Initialising browser for dromaeo_css test... 22:34:54 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpEyxaSE/profile http://localhost:49236/getInfo.html) 22:34:57 INFO - PROCESS | 1633 | 2016-11-23 22:34:57.873 plugin-container[1635:7692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 22:34:57 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:34:57 INFO - PROCESS | 1633 | 2016-11-23 22:34:57.875 plugin-container[1635:7692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 22:34:57 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:34:57 INFO - PROCESS | 1633 | 2016-11-23 22:34:57.875 plugin-container[1635:7692] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:34:57 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 22:34:57 INFO - PROCESS | 1633 | colorDepth:24 22:34:57 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 22:34:57 INFO - PROCESS | 1633 | __metrics 22:34:58 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 22:34:58 INFO - TEST-INFO | 1633: exit 0 22:34:58 INFO - Browser initialized. 22:34:58 INFO - Running cycle 1/1 for dromaeo_css test... 22:34:58 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpEyxaSE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:35:01 INFO - PROCESS | 1636 | 2016-11-23 22:35:01.450 plugin-container[1637:7816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 22:35:01 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:35:01 INFO - PROCESS | 1636 | 2016-11-23 22:35:01.451 plugin-container[1637:7816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 22:35:01 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:35:01 INFO - PROCESS | 1636 | 2016-11-23 22:35:01.451 plugin-container[1637:7816] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:35:11 INFO - PROCESS | 1636 | RSS: Main: 241729536 22:35:11 INFO - PROCESS | 1636 | 22:36:00 INFO - PROCESS | 1636 | 1479969360766 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] 22:38:14 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 22:38:15 INFO - PROCESS | 1636 | RSS: Main: 249995264 22:38:15 INFO - PROCESS | 1636 | 22:41:18 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 22:41:18 INFO - PROCESS | 1636 | RSS: Main: 259940352 22:41:18 INFO - PROCESS | 1636 | 22:44:20 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 22:44:21 INFO - PROCESS | 1636 | RSS: Main: 265281536 22:44:21 INFO - PROCESS | 1636 | 22:47:23 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 22:47:23 INFO - PROCESS | 1636 | RSS: Main: 264814592 22:47:23 INFO - PROCESS | 1636 | 22:50:26 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 22:50:27 INFO - PROCESS | 1636 | RSS: Main: 271003648 22:50:27 INFO - PROCESS | 1636 | 22:53:29 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 22:53:30 INFO - PROCESS | 1636 | RSS: Main: 273903616 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | __start_tp_report 22:53:30 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 22:53:30 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 22:53:30 INFO - PROCESS | 1636 | |i|pagename|runs| 22:53:30 INFO - PROCESS | 1636 | |0;dojo.html;2875.784316176286;2907.2877145099446;3415.6242813290532;3425.862965481381;3483.5297234873283;5723.456271654197;5777.855553611162;5850.180974663545;5886.058230683092;5902.2622172228475;5073.7209453480045;5707.65754054757;5907.970460147704;5910.970445147768;5963.284405871291;5260.079486089919;5293.100172970585;5295.020421222067;5363.195520671891;5419.187121931703;4657.796478459463;5376.542993845528;5423.2136340230645;5441.945580544183;5493.450654934494;4575.771211439414;5224.772178538041;5227.973860130713;5246.239295302198;5408.783648654049;5201.817936372247;5258.684478931276;5282.3925248596515;5355.919661205085;5379.462053794628;5211.1922651989;5238.476152384731;5260.28986086881;5267.5259226669405;5300.0459917214885;5662.122371032456;5846.619969701998;5854.7072646367515;5881.794137205177;5886.175935369051;6148.78479253224;6191.288001879751;6211.285702144266;6214.5649804513705;6238.282597501255;6314.810555683337;6316.115743795826;6318.557700960934;6319.968400158015;6321.620702757849;5311.336082989626;5944.583879128462;5976.432238937294;6031.276246850406;6043.546733994924;6262.968685156545;6301.960176570906;6312.779052733133;6350.396712312323;6362.650054247061;6231.158793562811;6243.313235544087;6260.530460215502;6267.968660156761;6273.027680709497;6293.087502312231;6299.5275354348605;6300.086487459293;6300.779472718433;6305.054241863757;6119.53301968977;6128.533679014861;6188.412100850412;6198.690065496798;6223.439890409885;6176.752929882754;6231.189945307081;6240.875182496325;6267.091271765659;6319.842003949846;5003.399592048904;5025.698458092526;5028.421731500851;5029.924551131736;5055.317532133188;3525.347810655037;3785.6782173515558;3801.9734671638503;4424.093060922503;4548.545145485425;4398.73607583546;5390.894866552426;5761.88476230473;6095.299040610298;6115.724792384353;5664.85837854057;5954.8809023819285;6237.844053898598;6293.402126797947;6295.685215739286;6040.909386359208;6042.728077236535;6046.2139921809885;6060.060690592965;6071;5428.945710542923;5482.945170548243;5501.064818980783;5517.586181036439;5542;6396.680165991775;6448.097266382711;6485.7405703772265;6509.772157974448;6509.772157974448;6247.312795592572;6318.715657795502;6324.430801227912;6337.714802833976;6350.936490635035;6258.311585725658;6485.351464853666;6487.708053137526;6493.350664933469;6508.576942498722;6173.629582225081;6186.5360097993735;6193.38066193377;6226.62640241587;6250.156228909039;6043.909341359971;6253.280872699698;6270.0908368287255;6277.403646653561;6299.275583307978;6199.845003874939;6216.627002379872;6229.626222426669;6245.0632405139595;6269.4984401246875;6073.635581865102;6105.945000794801;6121.940772763084;6137.048757442603;6143.174139895698;5340.679559226459;5623.746931388179;5969.432903874125;6052.334243233329;6143.846403839761;5231.895362092813;6060.575764695205;6104.216637118914;6144.941444615925;6185.84535386619;5532.391436942014;6091.177691011657;6101.60339577926;6125.1424800527075;6146.754129834935 22:53:30 INFO - PROCESS | 1636 | |1;ext.html;481.708566317378;511.0111933408854;521.5618880140681;530.4377359998402;532.4941305759529;31477;33684.83157584209;37849;38399.80800095996;38778.22443551131;39288.41067383991;39748.608798692054;39757.60242397575;39870.401943970865;40165.799171004175;8257.174282571756;8492.66029358825;9155.771105722377;9387.155156035955;9392.577334019952;10823.350598964087;11107.944460277688;11127.165462590297;11265.324080555152;11271.943640281786;11068.557257709683;11178;11186.944065279662;11237.662870113909;11240.718982025433;11103.38931358775;11146.331220126818;11156.665300041012;11177.273477223955;11180.608678696206;10949.726256843564;10997.230193886431;11035.117190624811;11042.447877606168;11076.889231107667;36588;36928;37293;37313.440298395544;37313.81343093294;2859.4852926473227;3270.9672903270903;3349.748768842322;3649.7262705297208;3744.8127593620484;29095;29676.851615741998;29720.40559188826;29774.40451190964;29819;2785.331520435101;3175.4760464523324;3196.6643502432375;3219.4205043092234;3227.0480208338595;382854;404460.95539044525;430250.8487457574;442687;460178.69910650235;48234.27648585274;59339.406605933385;59601.105983410285;59792.40207597955;59815.70092149598;61113.38886611166;61802.69098654478;62155.06767398493;62241.75516489735;62253.37746622566;13021.804672929913;15765;15986.880918335837;16017.919910400373;16067;11015.228933974706;11181.720456988478;12178.695532611579;12259.325737084546;12267.815982760265;15455.690886182214;18287.359942401952;18822.153003114894;18855.434336969913;18870;6758.42553382968;7011.684474198673;7705.499142555716;7843.607819608997;7887.290143887078;866.3978534918172;984.6356847966298;985.8866230383454;993.5876611206269;998.1415982255255;28129.859350703526;29160.56259156114;29246.415071698873;29445.411091778475;29688;914.6844338703137;987.53585814667;1078.164422572497;1102.7849569333903;1137.766757814646;14066.3665364778;14621;14970.850291497163;15281.694366112615;15300.387984480496;553225.2338738281;594990;597830;605943.9702801546;608405;40695.38956915648;50884;51001.48998509967;51488.45534633967;51645;1314.0998416085013;1320.610419926124;1371.5199680111882;1374.3883971632526;1375.4910683047538;26031.739682602933;26080.73919260783;26219.868900655376;28243.43513129767;28726;46946;47204;47307.290390644856;47319.76340118415;47383.52616473791;402215;403083;405862;406362;406841;21174.682379764006;25603.847826847465;25658.358541036625;25993.66057054874;26018.48750418669;45638.54361456343;46339.536604633526;47379;47526.76236618795;47633.76183119062;36005.45991810178;36633.45049824201;37489.625103748614;37898.62101379061;38362.6163738359;33562.83218583892;35059.64940350564;36902.8154859224;36935.63064369429;38175.42736859005;35522.644773552965;35559.11102222465;36156.63843361533;36727.81636091803;36956;32071;33264.501032485015;33300.66699333073;33429.49855752117;34059;22572.322830315115;24253.51492970166;24673.753262467144;25042.499150016534;25203.369915752253 22:53:30 INFO - PROCESS | 1636 | |2;jquery.html;327.28978117485053;368.37744212281257;371.396480718832;382.75886191699226;398.4262661767055;15033.248337583118;19147.234110635574;20164.59670806583;20335.186592536316;20587.485312867186;14417.990740648158;15297.158656273901;16836.326546938137;19368.934708591023;19651.60696786067;5445.074337362648;5819.30168379795;5823.796167134151;5824.592278540504;5842.620229685057;5694.715264236792;5705.144228365758;5746.798862039829;5768.625039372449;5866;4836.6856154350035;5760.251167348239;5774.335951365584;5774.538036957054;5775.653460792366;5471.534919531845;5714.828555143373;5751.683657398841;5782.45066718661;5801.680907550083;5019.698818070891;5057.645964782467;5058.4435712071645;5058.710028942596;5072.036313100499;23826.285211443683;24608.630870536956;24842.875785621138;24857.627135592982;25012.874420651115;2146.3668217875766;2226.454518642927;2299.172297972745;2317.154238702878;2468.0744720729726;14711.779323310158;14858.628534286729;15170.089794612248;15876.126813025394;15941.840581594268;1824.4435447188603;1828.890266583996;1840.5459686847375;1879.9530011749816;1885.6134492429019;110418.34372484419;110765.89234107717;111371;112816;115071;10810.891891080988;11074.723131921766;11198.328100314007;11199.496022678999;11384.089272858151;13501.59495215145;13503.324833758274;13621;13663.795043074362;13665.453381864614;7275.708971641075;8250.669973201126;8449.619767110495;8493.575321233913;8501.14988501145;8547.444416112932;8576.656933722707;8600.913990860012;8740.344474164336;8742.431741936882;5661.603687741818;6692.230393504809;6695.933040669628;6704.698288577026;6731.225909020427;1815.2194556340637;1857.071464267866;1858.1823997441084;1858.9814101859079;1860.8418284445704;376.73251991086636;377.88474515273106;389.7525071579511;840.5461051032496;887.5296093070683;9693.951530242304;10817.188710846718;10895.945520272347;11396.94301528487;11597.18819682631;410.8048676878459;413.9027328577782;431.60508135056074;432.8009115806764;439.99560004399547;5797.2173756542325;5953.434423729739;6111.449969502766;6122.938770612237;6124.479419249325;427777;438129;533972;539886;548198;114098;118986;125560;128703.35648321698;132037.33981330032;437.4029449801062;451.12706912124486;892.3040028777434;938.1509733691021;1022.6267412394423;18437.354692585694;19009.809901900804;19138.04309784533;19319.613607728046;19502.219911203392;50701;52362;54317.64205894726;54470.45529544654;55770.442295576526;55431;58379;62608;68958.65520672365;76216;7249.275072492708;7702.229777022477;8020.358371330396;8124.22819832115;8140.267375936194;9502.809943800947;12461.439235234255;12572.434240459019;12909.612711618667;12965;10893.128549715846;10894.291871028357;11287.322760634388;11403.372814495282;11461.885381146416;9251.907480925105;10903.34579925207;11018.39398833072;11176.385298808644;11447.656570302905;11023.338599684044;11448.713782155513;11478.827817582576;11590.768184636092;11684.889935456118;7824.21757824213;7857.214278572097;7971.561564114029;8036.799080023046;8101.35189184852;10711.464426778786;11134.498947547218;11191.944040279746;11391;11488.36813975206 22:53:30 INFO - PROCESS | 1636 | |3;mootools.html;1228.9877101228988;1484.065039025414;1613.9677206455872;1750.1511766793112;1792.9103544822765;7624.69501219952;7883.290503854652;8170.959145204273;8568.157846063103;8603.311735061188;8069.677212911492;8390.706325278601;8441.78895527612;8501.107383724713;8515.489070655765;2306.054517647769;2335.8131349492;2337.801286890616;2339.4268404240984;2339.6139636959883;2325.5930212212793;2330.2659662206433;2354.6468029795496;2372.0986025310444;2376.9049238030543;2320.5938960681897;2331.3006098170567;2346.378209774411;2349.2952114365708;2357.1514254868234;2289.404754763765;2297.6208925527267;2308.90764369425;2316.108298305148;2319.976800231993;2319.826013049028;2347;2351;2353.705786776653;2354.7527509611414;8418.915810841936;8501.022382425976;8548.871766923501;8566.571671416405;8571.057183709787;5055.469175736532;5143.3570803649545;5290.259363689087;5311.814086506954;5348.144296912515;3031.060371284909;3136.200268931431;3437.020449171999;3564.7861128332383;3569;987.8320685483485;1041.6198087697935;1059.7880423915249;1073.549109374057;1087.065123993365;9963;10254.846177307347;11016.834747478713;11036.834447483294;11101.333919964747;4480.865574032784;4578.656600754956;4667.113248482778;4682.859514214579;4705;9375.5312234388;9473.147416732389;9594.184494317922;9620.807583848284;9627.277954153467;4359.346098085249;4508.323751437245;4633.443986721615;5272.367315922113;5276.630635855453;4277.871663850089;4334.696571240046;4492.191405547033;4678.368420263289;4836.975815120902;3587.1390866191928;3710.777353358807;3843.3466310726685;3864.130570621644;3927.1360300734364;1101.8071837428417;1218.9821499048217;1248.6940699528673;1267.3599832084737;1274.9107562470538;723.3200791256203;793.757903839323;805.3275514945086;840.4873027453249;900.1583519409373;4677.64917631179;5428.809991650273;5779.566532510079;5836.970815145897;5846.736896839652;421.21233293740903;430.5178200415492;432.9826806927688;438.2440290498864;456.32236129347893;3829.7702137871815;3864.652181303696;3869.7678139311734;3926.5680775114715;3947.5460322062754;10017.749556261151;10177.592896284214;10324.483775811179;10329;10401.167906567456;10027.896931337547;10262.230332725076;10264.43545604984;10283.78898536818;10320.690379288633;7861.213878612093;7870.44906856526;7969.9601501993275;8000.919990800134;8018.55897925608;5571.4428557143965;5641.4358564144895;5646.85882852932;5704.258446401941;5711.257536520226;9460.763480913032;9508.857367139637;9591.232701384011;9618.374805637612;9684.56419461112;6592.967035164986;9712.611495540383;9740.464274464972;9743.805123897624;9747.074027967332;6707.094542236736;6933.965330173317;7099.787006389816;7244.710211591478;7407.296306850841;7906.446548741533;8179.182081791773;8192.959035204785;8198.344132469507;8202.794930126794;6466.224053113657;7091.255418181223;7129.180144283474;7286.96356518214;7301.415886729155;7089.179100783242;7145.708981294683;7158.534695244793;7263.418926485974;7281.745138920112;6749.966250168718;6750.2237242715755;6802.897956530557;6818.420434263143;6831.795046148623;4834.516548345137;4858.854234372974;4895.290182923527;4916.483769204182;4918.852434426973;4795.112904112751;4832.855014349575;4966.478519755374;5014.172661510956;5041.1430056889685 22:53:30 INFO - PROCESS | 1636 | |4;prototype.html;299.7736708784866;300.87964814074365;309.5017022593624;312.8216916357677;313.57667149348384;5199.740012999354;5255.448177941309;5335.638547714939;5510.173473978905;5517.586181036419;4505.977470112645;4585.449746030473;5356.143017117262;5401.972990135054;5430.91853622196;3297.422950983568;4141.565135660742;4208.494980602317;4211.452511173543;4254.829806807739;3684.9815750921207;4121.498547728065;4191.308434108369;4195.643370313527;4198.97900510497;4060.025593857468;4123.649489793371;4127.731697439672;4128.97935510322;4158.729682570638;3414.8804791832417;4103.958960410402;4129.139254796744;4144.979275103605;4165.770882601456;3023.7732170087334;3592.784432934006;3593.646161764049;3601.3697602919383;3601.657842504958;4310.374995625648;4711.411073615798;4819.301201325823;4879.170541008037;5298.735063246822;4643.674942754009;4652.836790802299;4657.278121891112;4667.836625718118;4697.577218050357;5412.645499207857;5655.886882262373;5706.657600543981;5737.254156959569;5755.870327978633;3319.6762266413607;3668.284684486526;3791.545014598238;3828.272628200633;3896.8636097736726;5186.740662966874;5399.973000134974;5570.412432456771;6005.057521814323;6045.637261764308;3485.320362529333;3767.2482295732616;3906.0625449891822;3933.5869733678123;3941.428492868518;3849.653531182207;3958.7426817256787;3985.621365970228;3995.960040399617;4006.6193711597352;4128.752274863517;4538.797218737037;4549.977250113729;4616.584507394284;4623.127633308515;5334.759935802898;5734.713264336767;5808.332041815161;5810.41895810424;5825.475707186373;1140.589387820384;1173.5423184957874;1207.643745095199;1236.5857437758273;1260.5336025670376;867.3711559119588;872.9345299102466;876.8334016536837;882.4793371910604;885.5527958381106;4639.234526303189;5003.449620541737;5026.115206797451;5036.64743467961;5084.923726144111;5246.921296180561;5452.536534394541;5542.806001789918;5551.916721249185;5575.944240557624;4372.213001659668;4573.794179261941;5013.54878060969;5030.270610761419;5063.5695965843315;2519.533886231053;2551.374913146254;2760.599713041638;2795.3011747063233;2852.8288302701903;5283.815066472655;5620.943790562042;5728.169415434739;5768.288904215012;5779.364269930388;5138.172061813898;5360.812371566976;5406.702631355234;5571.4150014248735;5618.286422641134;4923.719832843486;4951.598474739749;5377.435369286169;5686.175504551899;5779.942200578024;5316.680999140064;5477.397486276586;5916.852078698067;5927.4665280125;5963.73163207648;5335.493128152819;5841.970790146022;5877.617954832922;5879.147523609138;5901.763929442946;5302;5766.798162064475;6170.53720970938;6180.2077397556;6194.566380353332;3860.556036055777;3943.743656662308;3964.1278918637854;3972.781497017692;3995.4006898965386;5092.388913330424;5505.9174112387545;5614.85962850932;5964.642121472725;6062.636241825328;5182.32629758129;5282.524572788468;5492.286002819608;5689.041698316935;5872.148538461814;5106.387233532;5238.449962754008;5653.038983372754;5713.657180569179;5758.827235182951;4991.925121123259;5056.0140772549;5386.087938781676;5435;5719;5002.749862506933;5008.649394542347;5704.743286552032;5749.568782341257;5773.797917072882;4585.19759042173;5369.979703856255;5386.892262154656;5637.774489020558;5639 22:53:30 INFO - PROCESS | 1636 | |5;yui.html;627.9968600156999;664.2493981800565;738.9815254618635;765.8774596064627;791.0704921716981;13923.860761392383;14801.407943682267;14880.47918322859;14882.851171488282;14903.329350179241;13019.60941171764;13591.864081359183;13732.725345493083;13746.175229486213;13752.312384380792;811.8051667599777;820.7209548753423;833.4624167412015;833.791552111972;834.0241916957175;659.2056571830956;681.5433659448157;803.445622520459;815.9428839981204;829.0838623321233;876.2201640539893;876.329607849992;876.548577482597;876.9605367758435;881.2949640287776;872.3326655108847;873.9344549158839;874.108409422392;874.3224001398903;875.0767939823484;644.9290578036412;646.6863571167979;648.0764910003273;648.743746220245;648.9188851393576;13893.305334733324;14134.222617756019;14143.717125657533;14148.504802331972;14227.14637121776;13540.729185416336;13729.588112356545;13865.514706985308;13915.443382264697;13980.650483737887;11497.482613282422;11538.82691759616;11584.478698458506;11737.178397512178;11767.235129716626;3235.9352812943607;3310.8013519188926;3636.4181730922924;3819.2934307153264;3824.8661296854757;90966.54516727374;92662.99999999933;93768;94334.52832735861;94398.5280073602;10590.89280738767;10627.628033018807;10692.411917344622;10711.732206694895;10826.296290741077;17734.379296724554;21038.78961210399;21240.681389779165;21397.78602213958;21467.355979320644;7508.511946723445;7595.772126836204;7623.618819059025;7668.578228197391;7731.7680469586;4172.72877262983;4192.413062171298;4213.957860421357;4224.4508213931995;4266.146770645822;3416.0605833396016;3482.21650128719;3490.2844916791996;3511.473279008118;3524.506569080331;616.1004932798076;624.1137584629746;632.88291666041;633.8732253549308;642.194046471675;516.1973131780118;600.2586805295457;600.9338972712998;607.5139888089511;666.4401902402005;7175.748848790267;7764.417668674872;8785.648574057093;8785.692500762443;8811.647534098693;604.5888795618934;621.1397214857365;626.7687034158411;637.1621317966859;640.2445114764622;4200.201961627342;4217.641500472433;4330.848420305274;4333.761643109659;4474.709143905636;74804.62597686976;108525;117282.41358793151;117811;121922;101692;102269.4659580107;102437.48781256046;103274;105008;89134;91428.08571914329;92385;93005.99999999865;94830.52584737031;7986.680532778856;8279.668813247403;8366.581670916552;8506.489610623383;8589.398742088244;45802;45816.77091614521;51411.228831566805;55815.44184558102;56502.71748641231;54646.360609181786;58906;64194.39514012187;81743;84377.57811210904;9991.300608957303;11457.19799614019;11695.181337306307;11710.414479276173;11963.282203067844;12149.392530373269;12239.449224785085;12304.630861074182;12330.568430104904;12728.23630582168;11217.943910280397;11249.49377278008;11304.604338848101;11442.771144576895;11969.982551483221;8395.70615028471;11070.723231919266;11331.206815523165;11386.94306528462;11594.246373985665;9059.501727405055;10572.259941803999;10962.232643714864;11142.832857507306;11197.66407007791;9217.354785164975;9616.08647178517;10044.753853804077;10109.19126469866;10306.948465257627;8972.551372431482;9347.953260233655;9521.666741664008;9525.666601668909;9583.568739406883 22:53:30 INFO - PROCESS | 1636 | __end_tp_report 22:53:30 INFO - PROCESS | 1636 | __start_cc_report 22:53:30 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,373 22:53:30 INFO - PROCESS | 1636 | __end_cc_report 22:53:30 INFO - PROCESS | 1636 | __startTimestamp1479970410180__endTimestamp 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | ------- Summary: start ------- 22:53:30 INFO - PROCESS | 1636 | Number of tests: 6 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5774.99 Median:6071.00 stddev:718.09 (11.8%) stddev-sans-first:683.33 22:53:30 INFO - PROCESS | 1636 | Values: 2875.8 2907.3 3415.6 3425.9 3483.5 5723.5 5777.9 5850.2 5886.1 5902.3 5073.7 5707.7 5908.0 5911.0 5963.3 5260.1 5293.1 5295.0 5363.2 5419.2 4657.8 5376.5 5423.2 5441.9 5493.5 4575.8 5224.8 5228.0 5246.2 5408.8 5201.8 5258.7 5282.4 5355.9 5379.5 5211.2 5238.5 5260.3 5267.5 5300.0 5662.1 5846.6 5854.7 5881.8 5886.2 6148.8 6191.3 6211.3 6214.6 6238.3 6314.8 6316.1 6318.6 6320.0 6321.6 5311.3 5944.6 5976.4 6031.3 6043.5 6263.0 6302.0 6312.8 6350.4 6362.7 6231.2 6243.3 6260.5 6268.0 6273.0 6293.1 6299.5 6300.1 6300.8 6305.1 6119.5 6128.5 6188.4 6198.7 6223.4 6176.8 6231.2 6240.9 6267.1 6319.8 5003.4 5025.7 5028.4 5029.9 5055.3 3525.3 3785.7 3802.0 4424.1 4548.5 4398.7 5390.9 5761.9 6095.3 6115.7 5664.9 5954.9 6237.8 6293.4 6295.7 6040.9 6042.7 6046.2 6060.1 6071.0 5428.9 5482.9 5501.1 5517.6 5542.0 6396.7 6448.1 6485.7 6509.8 6509.8 6247.3 6318.7 6324.4 6337.7 6350.9 6258.3 6485.4 6487.7 6493.4 6508.6 6173.6 6186.5 6193.4 6226.6 6250.2 6043.9 6253.3 6270.1 6277.4 6299.3 6199.8 6216.6 6229.6 6245.1 6269.5 6073.6 6105.9 6121.9 6137.0 6143.2 5340.7 5623.7 5969.4 6052.3 6143.8 5231.9 6060.6 6104.2 6144.9 6185.8 5532.4 6091.2 6101.6 6125.1 6146.8 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:61267.39 Median:35540.88 stddev:128267.10 (360.9%) stddev-sans-first:128545.73 22:53:30 INFO - PROCESS | 1636 | Values: 481.7 511.0 521.6 530.4 532.5 31477.0 33684.8 37849.0 38399.8 38778.2 39288.4 39748.6 39757.6 39870.4 40165.8 8257.2 8492.7 9155.8 9387.2 9392.6 10823.4 11107.9 11127.2 11265.3 11271.9 11068.6 11178.0 11186.9 11237.7 11240.7 11103.4 11146.3 11156.7 11177.3 11180.6 10949.7 10997.2 11035.1 11042.4 11076.9 36588.0 36928.0 37293.0 37313.4 37313.8 2859.5 3271.0 3349.7 3649.7 3744.8 29095.0 29676.9 29720.4 29774.4 29819.0 2785.3 3175.5 3196.7 3219.4 3227.0 382854.0 404461.0 430250.8 442687.0 460178.7 48234.3 59339.4 59601.1 59792.4 59815.7 61113.4 61802.7 62155.1 62241.8 62253.4 13021.8 15765.0 15986.9 16017.9 16067.0 11015.2 11181.7 12178.7 12259.3 12267.8 15455.7 18287.4 18822.2 18855.4 18870.0 6758.4 7011.7 7705.5 7843.6 7887.3 866.4 984.6 985.9 993.6 998.1 28129.9 29160.6 29246.4 29445.4 29688.0 914.7 987.5 1078.2 1102.8 1137.8 14066.4 14621.0 14970.9 15281.7 15300.4 553225.2 594990.0 597830.0 605944.0 608405.0 40695.4 50884.0 51001.5 51488.5 51645.0 1314.1 1320.6 1371.5 1374.4 1375.5 26031.7 26080.7 26219.9 28243.4 28726.0 46946.0 47204.0 47307.3 47319.8 47383.5 402215.0 403083.0 405862.0 406362.0 406841.0 21174.7 25603.8 25658.4 25993.7 26018.5 45638.5 46339.5 47379.0 47526.8 47633.8 36005.5 36633.5 37489.6 37898.6 38362.6 33562.8 35059.6 36902.8 36935.6 38175.4 35522.6 35559.1 36156.6 36727.8 36956.0 32071.0 33264.5 33300.7 33429.5 34059.0 22572.3 24253.5 24673.8 25042.5 25203.4 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:31110.44 Median:374.06 stddev:84239.24 (22520.0%) stddev-sans-first:84443.85 22:53:30 INFO - PROCESS | 1636 | Values: 327.3 368.4 371.4 382.8 398.4 15033.2 19147.2 20164.6 20335.2 20587.5 14418.0 15297.2 16836.3 19368.9 19651.6 5445.1 5819.3 5823.8 5824.6 5842.6 5694.7 5705.1 5746.8 5768.6 5866.0 4836.7 5760.3 5774.3 5774.5 5775.7 5471.5 5714.8 5751.7 5782.5 5801.7 5019.7 5057.6 5058.4 5058.7 5072.0 23826.3 24608.6 24842.9 24857.6 25012.9 2146.4 2226.5 2299.2 2317.2 2468.1 14711.8 14858.6 15170.1 15876.1 15941.8 1824.4 1828.9 1840.5 1880.0 1885.6 110418.3 110765.9 111371.0 112816.0 115071.0 10810.9 11074.7 11198.3 11199.5 11384.1 13501.6 13503.3 13621.0 13663.8 13665.5 7275.7 8250.7 8449.6 8493.6 8501.1 8547.4 8576.7 8600.9 8740.3 8742.4 5661.6 6692.2 6695.9 6704.7 6731.2 1815.2 1857.1 1858.2 1859.0 1860.8 376.7 377.9 389.8 840.5 887.5 9694.0 10817.2 10895.9 11396.9 11597.2 410.8 413.9 431.6 432.8 440.0 5797.2 5953.4 6111.4 6122.9 6124.5 427777.0 438129.0 533972.0 539886.0 548198.0 114098.0 118986.0 125560.0 128703.4 132037.3 437.4 451.1 892.3 938.2 1022.6 18437.4 19009.8 19138.0 19319.6 19502.2 50701.0 52362.0 54317.6 54470.5 55770.4 55431.0 58379.0 62608.0 68958.7 76216.0 7249.3 7702.2 8020.4 8124.2 8140.3 9502.8 12461.4 12572.4 12909.6 12965.0 10893.1 10894.3 11287.3 11403.4 11461.9 9251.9 10903.3 11018.4 11176.4 11447.7 11023.3 11448.7 11478.8 11590.8 11684.9 7824.2 7857.2 7971.6 8036.8 8101.4 10711.5 11134.5 11191.9 11391.0 11488.4 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5461.26 Median:4877.07 stddev:3043.56 (62.4%) stddev-sans-first:3035.48 22:53:30 INFO - PROCESS | 1636 | Values: 1229.0 1484.1 1614.0 1750.2 1792.9 7624.7 7883.3 8171.0 8568.2 8603.3 8069.7 8390.7 8441.8 8501.1 8515.5 2306.1 2335.8 2337.8 2339.4 2339.6 2325.6 2330.3 2354.6 2372.1 2376.9 2320.6 2331.3 2346.4 2349.3 2357.2 2289.4 2297.6 2308.9 2316.1 2320.0 2319.8 2347.0 2351.0 2353.7 2354.8 8418.9 8501.0 8548.9 8566.6 8571.1 5055.5 5143.4 5290.3 5311.8 5348.1 3031.1 3136.2 3437.0 3564.8 3569.0 987.8 1041.6 1059.8 1073.5 1087.1 9963.0 10254.8 11016.8 11036.8 11101.3 4480.9 4578.7 4667.1 4682.9 4705.0 9375.5 9473.1 9594.2 9620.8 9627.3 4359.3 4508.3 4633.4 5272.4 5276.6 4277.9 4334.7 4492.2 4678.4 4837.0 3587.1 3710.8 3843.3 3864.1 3927.1 1101.8 1219.0 1248.7 1267.4 1274.9 723.3 793.8 805.3 840.5 900.2 4677.6 5428.8 5779.6 5837.0 5846.7 421.2 430.5 433.0 438.2 456.3 3829.8 3864.7 3869.8 3926.6 3947.5 10017.7 10177.6 10324.5 10329.0 10401.2 10027.9 10262.2 10264.4 10283.8 10320.7 7861.2 7870.4 7970.0 8000.9 8018.6 5571.4 5641.4 5646.9 5704.3 5711.3 9460.8 9508.9 9591.2 9618.4 9684.6 6593.0 9712.6 9740.5 9743.8 9747.1 6707.1 6934.0 7099.8 7244.7 7407.3 7906.4 8179.2 8193.0 8198.3 8202.8 6466.2 7091.3 7129.2 7287.0 7301.4 7089.2 7145.7 7158.5 7263.4 7281.7 6750.0 6750.2 6802.9 6818.4 6831.8 4834.5 4858.9 4895.3 4916.5 4918.9 4795.1 4832.9 4966.5 5014.2 5041.1 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4537.72 Median:5059.79 stddev:1402.60 (27.7%) stddev-sans-first:1369.99 22:53:30 INFO - PROCESS | 1636 | Values: 299.8 300.9 309.5 312.8 313.6 5199.7 5255.4 5335.6 5510.2 5517.6 4506.0 4585.4 5356.1 5402.0 5430.9 3297.4 4141.6 4208.5 4211.5 4254.8 3685.0 4121.5 4191.3 4195.6 4199.0 4060.0 4123.6 4127.7 4129.0 4158.7 3414.9 4104.0 4129.1 4145.0 4165.8 3023.8 3592.8 3593.6 3601.4 3601.7 4310.4 4711.4 4819.3 4879.2 5298.7 4643.7 4652.8 4657.3 4667.8 4697.6 5412.6 5655.9 5706.7 5737.3 5755.9 3319.7 3668.3 3791.5 3828.3 3896.9 5186.7 5400.0 5570.4 6005.1 6045.6 3485.3 3767.2 3906.1 3933.6 3941.4 3849.7 3958.7 3985.6 3996.0 4006.6 4128.8 4538.8 4550.0 4616.6 4623.1 5334.8 5734.7 5808.3 5810.4 5825.5 1140.6 1173.5 1207.6 1236.6 1260.5 867.4 872.9 876.8 882.5 885.6 4639.2 5003.4 5026.1 5036.6 5084.9 5246.9 5452.5 5542.8 5551.9 5575.9 4372.2 4573.8 5013.5 5030.3 5063.6 2519.5 2551.4 2760.6 2795.3 2852.8 5283.8 5620.9 5728.2 5768.3 5779.4 5138.2 5360.8 5406.7 5571.4 5618.3 4923.7 4951.6 5377.4 5686.2 5779.9 5316.7 5477.4 5916.9 5927.5 5963.7 5335.5 5842.0 5877.6 5879.1 5901.8 5302.0 5766.8 6170.5 6180.2 6194.6 3860.6 3943.7 3964.1 3972.8 3995.4 5092.4 5505.9 5614.9 5964.6 6062.6 5182.3 5282.5 5492.3 5689.0 5872.1 5106.4 5238.4 5653.0 5713.7 5758.8 4991.9 5056.0 5386.1 5435.0 5719.0 5002.7 5008.6 5704.7 5749.6 5773.8 4585.2 5370.0 5386.9 5637.8 5639.0 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20483.17 Median:48614.00 stddev:31141.09 (64.1%) stddev-sans-first:31192.77 22:53:30 INFO - PROCESS | 1636 | Values: 628.0 664.2 739.0 765.9 791.1 13923.9 14801.4 14880.5 14882.9 14903.3 13019.6 13591.9 13732.7 13746.2 13752.3 811.8 820.7 833.5 833.8 834.0 659.2 681.5 803.4 815.9 829.1 876.2 876.3 876.5 877.0 881.3 872.3 873.9 874.1 874.3 875.1 644.9 646.7 648.1 648.7 648.9 13893.3 14134.2 14143.7 14148.5 14227.1 13540.7 13729.6 13865.5 13915.4 13980.7 11497.5 11538.8 11584.5 11737.2 11767.2 3235.9 3310.8 3636.4 3819.3 3824.9 90966.5 92663.0 93768.0 94334.5 94398.5 10590.9 10627.6 10692.4 10711.7 10826.3 17734.4 21038.8 21240.7 21397.8 21467.4 7508.5 7595.8 7623.6 7668.6 7731.8 4172.7 4192.4 4214.0 4224.5 4266.1 3416.1 3482.2 3490.3 3511.5 3524.5 616.1 624.1 632.9 633.9 642.2 516.2 600.3 600.9 607.5 666.4 7175.7 7764.4 8785.6 8785.7 8811.6 604.6 621.1 626.8 637.2 640.2 4200.2 4217.6 4330.8 4333.8 4474.7 74804.6 108525.0 117282.4 117811.0 121922.0 101692.0 102269.5 102437.5 103274.0 105008.0 89134.0 91428.1 92385.0 93006.0 94830.5 7986.7 8279.7 8366.6 8506.5 8589.4 45802.0 45816.8 51411.2 55815.4 56502.7 54646.4 58906.0 64194.4 81743.0 84377.6 9991.3 11457.2 11695.2 11710.4 11963.3 12149.4 12239.4 12304.6 12330.6 12728.2 11217.9 11249.5 11304.6 11442.8 11970.0 8395.7 11070.7 11331.2 11386.9 11594.2 9059.5 10572.3 10962.2 11142.8 11197.7 9217.4 9616.1 10044.8 10109.2 10306.9 8972.6 9348.0 9521.7 9525.7 9583.6 22:53:30 INFO - PROCESS | 1636 | -------- Summary: end -------- 22:53:30 INFO - PROCESS | 1636 | 22:53:30 INFO - TEST-INFO | 1636: exit 0 22:53:30 INFO - TEST-OK | dromaeo_css | took 1116296ms 22:53:30 INFO - TEST-START | kraken 22:53:30 INFO - operating with platform_type : mac_ 22:53:30 INFO - Initialising browser for kraken test... 22:53:30 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpoYW4AC/profile http://localhost:49236/getInfo.html) 22:53:33 INFO - PROCESS | 1648 | 2016-11-23 22:53:33.795 plugin-container[1649:10099] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 22:53:33 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:53:33 INFO - PROCESS | 1648 | 2016-11-23 22:53:33.798 plugin-container[1649:10099] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 22:53:33 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:53:33 INFO - PROCESS | 1648 | 2016-11-23 22:53:33.798 plugin-container[1649:10099] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:53:33 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 22:53:33 INFO - PROCESS | 1648 | colorDepth:24 22:53:33 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 22:53:33 INFO - PROCESS | 1648 | __metrics 22:53:33 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 22:53:34 INFO - TEST-INFO | 1648: exit 0 22:53:34 INFO - Browser initialized. 22:53:34 INFO - Running cycle 1/1 for kraken test... 22:53:34 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpoYW4AC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:53:37 INFO - PROCESS | 1650 | 2016-11-23 22:53:37.814 plugin-container[1651:10210] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 22:53:37 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:53:37 INFO - PROCESS | 1650 | 2016-11-23 22:53:37.814 plugin-container[1651:10210] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 22:53:37 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:53:37 INFO - PROCESS | 1650 | 2016-11-23 22:53:37.814 plugin-container[1651:10210] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:53:47 INFO - PROCESS | 1650 | RSS: Main: 241913856 22:53:47 INFO - PROCESS | 1650 | 22:53:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 22:53:51 INFO - PROCESS | 1650 | RSS: Main: 253956096 22:53:51 INFO - PROCESS | 1650 | 22:53:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 22:53:54 INFO - PROCESS | 1650 | RSS: Main: 261582848 22:53:54 INFO - PROCESS | 1650 | 22:53:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 22:53:58 INFO - PROCESS | 1650 | RSS: Main: 264429568 22:53:58 INFO - PROCESS | 1650 | 22:54:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 22:54:00 INFO - PROCESS | 1650 | RSS: Main: 266911744 22:54:00 INFO - PROCESS | 1650 | 22:54:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:54:03 INFO - PROCESS | 1650 | RSS: Main: 267403264 22:54:03 INFO - PROCESS | 1650 | 22:54:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 22:54:07 INFO - PROCESS | 1650 | RSS: Main: 272863232 22:54:07 INFO - PROCESS | 1650 | 22:54:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 22:54:11 INFO - PROCESS | 1650 | RSS: Main: 271716352 22:54:11 INFO - PROCESS | 1650 | 22:54:13 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 22:54:14 INFO - PROCESS | 1650 | RSS: Main: 272322560 22:54:14 INFO - PROCESS | 1650 | 22:54:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:54:16 INFO - PROCESS | 1650 | RSS: Main: 271171584 22:54:16 INFO - PROCESS | 1650 | 22:54:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:54:19 INFO - PROCESS | 1650 | RSS: Main: 271269888 22:54:19 INFO - PROCESS | 1650 | 22:54:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:54:22 INFO - PROCESS | 1650 | RSS: Main: 271273984 22:54:22 INFO - PROCESS | 1650 | 22:54:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:54:26 INFO - PROCESS | 1650 | RSS: Main: 271486976 22:54:26 INFO - PROCESS | 1650 | 22:54:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:54:30 INFO - PROCESS | 1650 | RSS: Main: 268816384 22:54:30 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 22:54:32 INFO - PROCESS | 1650 | RSS: Main: 270807040 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | __start_tp_report 22:54:32 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 22:54:32 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 22:54:32 INFO - PROCESS | 1650 | |i|pagename|runs| 22:54:32 INFO - PROCESS | 1650 | |0;ai-astar;133;174;149;118;118;119;117;120;117;120 22:54:32 INFO - PROCESS | 1650 | |1;audio-beat-detection;139;118;122;182;122;117;118;115;138;106 22:54:32 INFO - PROCESS | 1650 | |2;audio-dft;195;213;224;201;197;198;196;203;195;200 22:54:32 INFO - PROCESS | 1650 | |3;audio-fft;77;58;60;98;73;53;51;51;85;77 22:54:32 INFO - PROCESS | 1650 | |4;audio-oscillator;92;69;83;79;79;57;83;57;81;96 22:54:32 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;90;87;89;87;79;114;137;134;90;88 22:54:32 INFO - PROCESS | 1650 | |6;imaging-darkroom;101;104;128;174;118;174;159;101;101;100 22:54:32 INFO - PROCESS | 1650 | |7;imaging-desaturate;91;91;98;100;93;124;101;93;94;103 22:54:32 INFO - PROCESS | 1650 | |8;json-parse-financial;56;57;56;56;58;58;58;57;56;58 22:54:32 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;71;90;117;110;67;68;68;70;72;72 22:54:32 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;79;102;112;116;87;111;109;113;92;91 22:54:32 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;154;157;151;152;168;166;174;174;214;169 22:54:32 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;175;208;239;210;176;245;243;179;241;238 22:54:32 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;69;70;65;62;86;93;92;90;62 22:54:32 INFO - PROCESS | 1650 | __end_tp_report 22:54:32 INFO - PROCESS | 1650 | __start_cc_report 22:54:32 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,948 22:54:32 INFO - PROCESS | 1650 | __end_cc_report 22:54:32 INFO - PROCESS | 1650 | __startTimestamp1479970472323__endTimestamp 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | ------- Summary: start ------- 22:54:32 INFO - PROCESS | 1650 | Number of tests: 14 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:128.50 Median:120.00 stddev:18.95 (15.8%) stddev-sans-first:20.02 22:54:32 INFO - PROCESS | 1650 | Values: 133.0 174.0 149.0 118.0 118.0 119.0 117.0 120.0 117.0 120.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:122.00 stddev:21.56 (17.7%) stddev-sans-first:22.47 22:54:32 INFO - PROCESS | 1650 | Values: 139.0 118.0 122.0 182.0 122.0 117.0 118.0 115.0 138.0 106.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:202.20 Median:200.50 stddev:9.34 (4.7%) stddev-sans-first:9.54 22:54:32 INFO - PROCESS | 1650 | Values: 195.0 213.0 224.0 201.0 197.0 198.0 196.0 203.0 195.0 200.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:68.30 Median:75.00 stddev:16.13 (21.5%) stddev-sans-first:16.80 22:54:32 INFO - PROCESS | 1650 | Values: 77.0 58.0 60.0 98.0 73.0 53.0 51.0 51.0 85.0 77.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:82.00 stddev:13.09 (16.0%) stddev-sans-first:12.81 22:54:32 INFO - PROCESS | 1650 | Values: 92.0 69.0 83.0 79.0 79.0 57.0 83.0 57.0 81.0 96.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:99.50 Median:87.50 stddev:20.98 (24.0%) stddev-sans-first:21.97 22:54:32 INFO - PROCESS | 1650 | Values: 90.0 87.0 89.0 87.0 79.0 114.0 137.0 134.0 90.0 88.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:126.00 Median:123.00 stddev:31.27 (25.4%) stddev-sans-first:31.83 22:54:32 INFO - PROCESS | 1650 | Values: 101.0 104.0 128.0 174.0 118.0 174.0 159.0 101.0 101.0 100.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:98.80 Median:92.00 stddev:9.84 (10.7%) stddev-sans-first:10.02 22:54:32 INFO - PROCESS | 1650 | Values: 91.0 91.0 98.0 100.0 93.0 124.0 101.0 93.0 94.0 103.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.50 stddev:0.94 (1.6%) stddev-sans-first:0.93 22:54:32 INFO - PROCESS | 1650 | Values: 56.0 57.0 56.0 56.0 58.0 58.0 58.0 57.0 56.0 58.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:70.50 stddev:18.66 (26.5%) stddev-sans-first:19.47 22:54:32 INFO - PROCESS | 1650 | Values: 71.0 90.0 117.0 110.0 67.0 68.0 68.0 70.0 72.0 72.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:101.20 Median:97.50 stddev:12.98 (13.3%) stddev-sans-first:11.00 22:54:32 INFO - PROCESS | 1650 | Values: 79.0 102.0 112.0 116.0 87.0 111.0 109.0 113.0 92.0 91.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:167.90 Median:168.50 stddev:18.42 (10.9%) stddev-sans-first:18.84 22:54:32 INFO - PROCESS | 1650 | Values: 154.0 157.0 151.0 152.0 168.0 166.0 174.0 174.0 214.0 169.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.40 Median:238.50 stddev:29.73 (12.5%) stddev-sans-first:27.71 22:54:32 INFO - PROCESS | 1650 | Values: 175.0 208.0 239.0 210.0 176.0 245.0 243.0 179.0 241.0 238.0 22:54:32 INFO - PROCESS | 1650 | 22:54:32 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:78.00 stddev:12.92 (16.6%) stddev-sans-first:13.40 22:54:32 INFO - PROCESS | 1650 | Values: 68.0 69.0 70.0 65.0 62.0 86.0 93.0 92.0 90.0 62.0 22:54:32 INFO - PROCESS | 1650 | -------- Summary: end -------- 22:54:32 INFO - PROCESS | 1650 | 22:54:33 INFO - TEST-INFO | 1650: exit 0 22:54:33 INFO - TEST-OK | kraken | took 62582ms 22:54:33 INFO - SUITE-END | took 1178s 22:54:33 INFO - Completed test suite (00:19:38) 22:54:33 INFO - javascript benchmark 22:54:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7028.208039845523, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2875.784316176286, 2907.2877145099446, 3415.6242813290532, 3425.862965481381, 3483.5297234873283, 5723.456271654197, 5777.855553611162, 5850.180974663545, 5886.058230683092, 5902.2622172228475, 5073.7209453480045, 5707.65754054757, 5907.970460147704, 5910.970445147768, 5963.284405871291, 5260.079486089919, 5293.100172970585, 5295.020421222067, 5363.195520671891, 5419.187121931703, 4657.796478459463, 5376.542993845528, 5423.2136340230645, 5441.945580544183, 5493.450654934494, 4575.771211439414, 5224.772178538041, 5227.973860130713, 5246.239295302198, 5408.783648654049, 5201.817936372247, 5258.684478931276, 5282.3925248596515, 5355.919661205085, 5379.462053794628, 5211.1922651989, 5238.476152384731, 5260.28986086881, 5267.5259226669405, 5300.0459917214885, 5662.122371032456, 5846.619969701998, 5854.7072646367515, 5881.794137205177, 5886.175935369051, 6148.78479253224, 6191.288001879751, 6211.285702144266, 6214.5649804513705, 6238.282597501255, 6314.810555683337, 6316.115743795826, 6318.557700960934, 6319.968400158015, 6321.620702757849, 5311.336082989626, 5944.583879128462, 5976.432238937294, 6031.276246850406, 6043.546733994924, 6262.968685156545, 6301.960176570906, 6312.779052733133, 6350.396712312323, 6362.650054247061, 6231.158793562811, 6243.313235544087, 6260.530460215502, 6267.968660156761, 6273.027680709497, 6293.087502312231, 6299.5275354348605, 6300.086487459293, 6300.779472718433, 6305.054241863757, 6119.53301968977, 6128.533679014861, 6188.412100850412, 6198.690065496798, 6223.439890409885, 6176.752929882754, 6231.189945307081, 6240.875182496325, 6267.091271765659, 6319.842003949846, 5003.399592048904, 5025.698458092526, 5028.421731500851, 5029.924551131736, 5055.317532133188, 3525.347810655037, 3785.6782173515558, 3801.9734671638503, 4424.093060922503, 4548.545145485425, 4398.73607583546, 5390.894866552426, 5761.88476230473, 6095.299040610298, 6115.724792384353, 5664.85837854057, 5954.8809023819285, 6237.844053898598, 6293.402126797947, 6295.685215739286, 6040.909386359208, 6042.728077236535, 6046.2139921809885, 6060.060690592965, 6071.0, 5428.945710542923, 5482.945170548243, 5501.064818980783, 5517.586181036439, 5542.0, 6396.680165991775, 6448.097266382711, 6485.7405703772265, 6509.772157974448, 6509.772157974448, 6247.312795592572, 6318.715657795502, 6324.430801227912, 6337.714802833976, 6350.936490635035, 6258.311585725658, 6485.351464853666, 6487.708053137526, 6493.350664933469, 6508.576942498722, 6173.629582225081, 6186.5360097993735, 6193.38066193377, 6226.62640241587, 6250.156228909039, 6043.909341359971, 6253.280872699698, 6270.0908368287255, 6277.403646653561, 6299.275583307978, 6199.845003874939, 6216.627002379872, 6229.626222426669, 6245.0632405139595, 6269.4984401246875, 6073.635581865102, 6105.945000794801, 6121.940772763084, 6137.048757442603, 6143.174139895698, 5340.679559226459, 5623.746931388179, 5969.432903874125, 6052.334243233329, 6143.846403839761, 5231.895362092813, 6060.575764695205, 6104.216637118914, 6144.941444615925, 6185.84535386619, 5532.391436942014, 6091.177691011657, 6101.60339577926, 6125.1424800527075, 6146.754129834935], "value": 5724.857659502137, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.708566317378, 511.0111933408854, 521.5618880140681, 530.4377359998402, 532.4941305759529, 31477.0, 33684.83157584209, 37849.0, 38399.80800095996, 38778.22443551131, 39288.41067383991, 39748.608798692054, 39757.60242397575, 39870.401943970865, 40165.799171004175, 8257.174282571756, 8492.66029358825, 9155.771105722377, 9387.155156035955, 9392.577334019952, 10823.350598964087, 11107.944460277688, 11127.165462590297, 11265.324080555152, 11271.943640281786, 11068.557257709683, 11178.0, 11186.944065279662, 11237.662870113909, 11240.718982025433, 11103.38931358775, 11146.331220126818, 11156.665300041012, 11177.273477223955, 11180.608678696206, 10949.726256843564, 10997.230193886431, 11035.117190624811, 11042.447877606168, 11076.889231107667, 36588.0, 36928.0, 37293.0, 37313.440298395544, 37313.81343093294, 2859.4852926473227, 3270.9672903270903, 3349.748768842322, 3649.7262705297208, 3744.8127593620484, 29095.0, 29676.851615741998, 29720.40559188826, 29774.40451190964, 29819.0, 2785.331520435101, 3175.4760464523324, 3196.6643502432375, 3219.4205043092234, 3227.0480208338595, 382854.0, 404460.95539044525, 430250.8487457574, 442687.0, 460178.69910650235, 48234.27648585274, 59339.406605933385, 59601.105983410285, 59792.40207597955, 59815.70092149598, 61113.38886611166, 61802.69098654478, 62155.06767398493, 62241.75516489735, 62253.37746622566, 13021.804672929913, 15765.0, 15986.880918335837, 16017.919910400373, 16067.0, 11015.228933974706, 11181.720456988478, 12178.695532611579, 12259.325737084546, 12267.815982760265, 15455.690886182214, 18287.359942401952, 18822.153003114894, 18855.434336969913, 18870.0, 6758.42553382968, 7011.684474198673, 7705.499142555716, 7843.607819608997, 7887.290143887078, 866.3978534918172, 984.6356847966298, 985.8866230383454, 993.5876611206269, 998.1415982255255, 28129.859350703526, 29160.56259156114, 29246.415071698873, 29445.411091778475, 29688.0, 914.6844338703137, 987.53585814667, 1078.164422572497, 1102.7849569333903, 1137.766757814646, 14066.3665364778, 14621.0, 14970.850291497163, 15281.694366112615, 15300.387984480496, 553225.2338738281, 594990.0, 597830.0, 605943.9702801546, 608405.0, 40695.38956915648, 50884.0, 51001.48998509967, 51488.45534633967, 51645.0, 1314.0998416085013, 1320.610419926124, 1371.5199680111882, 1374.3883971632526, 1375.4910683047538, 26031.739682602933, 26080.73919260783, 26219.868900655376, 28243.43513129767, 28726.0, 46946.0, 47204.0, 47307.290390644856, 47319.76340118415, 47383.52616473791, 402215.0, 403083.0, 405862.0, 406362.0, 406841.0, 21174.682379764006, 25603.847826847465, 25658.358541036625, 25993.66057054874, 26018.48750418669, 45638.54361456343, 46339.536604633526, 47379.0, 47526.76236618795, 47633.76183119062, 36005.45991810178, 36633.45049824201, 37489.625103748614, 37898.62101379061, 38362.6163738359, 33562.83218583892, 35059.64940350564, 36902.8154859224, 36935.63064369429, 38175.42736859005, 35522.644773552965, 35559.11102222465, 36156.63843361533, 36727.81636091803, 36956.0, 32071.0, 33264.501032485015, 33300.66699333073, 33429.49855752117, 34059.0, 22572.322830315115, 24253.51492970166, 24673.753262467144, 25042.499150016534, 25203.369915752253], "value": 19256.00129285693, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.28978117485053, 368.37744212281257, 371.396480718832, 382.75886191699226, 398.4262661767055, 15033.248337583118, 19147.234110635574, 20164.59670806583, 20335.186592536316, 20587.485312867186, 14417.990740648158, 15297.158656273901, 16836.326546938137, 19368.934708591023, 19651.60696786067, 5445.074337362648, 5819.30168379795, 5823.796167134151, 5824.592278540504, 5842.620229685057, 5694.715264236792, 5705.144228365758, 5746.798862039829, 5768.625039372449, 5866.0, 4836.6856154350035, 5760.251167348239, 5774.335951365584, 5774.538036957054, 5775.653460792366, 5471.534919531845, 5714.828555143373, 5751.683657398841, 5782.45066718661, 5801.680907550083, 5019.698818070891, 5057.645964782467, 5058.4435712071645, 5058.710028942596, 5072.036313100499, 23826.285211443683, 24608.630870536956, 24842.875785621138, 24857.627135592982, 25012.874420651115, 2146.3668217875766, 2226.454518642927, 2299.172297972745, 2317.154238702878, 2468.0744720729726, 14711.779323310158, 14858.628534286729, 15170.089794612248, 15876.126813025394, 15941.840581594268, 1824.4435447188603, 1828.890266583996, 1840.5459686847375, 1879.9530011749816, 1885.6134492429019, 110418.34372484419, 110765.89234107717, 111371.0, 112816.0, 115071.0, 10810.891891080988, 11074.723131921766, 11198.328100314007, 11199.496022678999, 11384.089272858151, 13501.59495215145, 13503.324833758274, 13621.0, 13663.795043074362, 13665.453381864614, 7275.708971641075, 8250.669973201126, 8449.619767110495, 8493.575321233913, 8501.14988501145, 8547.444416112932, 8576.656933722707, 8600.913990860012, 8740.344474164336, 8742.431741936882, 5661.603687741818, 6692.230393504809, 6695.933040669628, 6704.698288577026, 6731.225909020427, 1815.2194556340637, 1857.071464267866, 1858.1823997441084, 1858.9814101859079, 1860.8418284445704, 376.73251991086636, 377.88474515273106, 389.7525071579511, 840.5461051032496, 887.5296093070683, 9693.951530242304, 10817.188710846718, 10895.945520272347, 11396.94301528487, 11597.18819682631, 410.8048676878459, 413.9027328577782, 431.60508135056074, 432.8009115806764, 439.99560004399547, 5797.2173756542325, 5953.434423729739, 6111.449969502766, 6122.938770612237, 6124.479419249325, 427777.0, 438129.0, 533972.0, 539886.0, 548198.0, 114098.0, 118986.0, 125560.0, 128703.35648321698, 132037.33981330032, 437.4029449801062, 451.12706912124486, 892.3040028777434, 938.1509733691021, 1022.6267412394423, 18437.354692585694, 19009.809901900804, 19138.04309784533, 19319.613607728046, 19502.219911203392, 50701.0, 52362.0, 54317.64205894726, 54470.45529544654, 55770.442295576526, 55431.0, 58379.0, 62608.0, 68958.65520672365, 76216.0, 7249.275072492708, 7702.229777022477, 8020.358371330396, 8124.22819832115, 8140.267375936194, 9502.809943800947, 12461.439235234255, 12572.434240459019, 12909.612711618667, 12965.0, 10893.128549715846, 10894.291871028357, 11287.322760634388, 11403.372814495282, 11461.885381146416, 9251.907480925105, 10903.34579925207, 11018.39398833072, 11176.385298808644, 11447.656570302905, 11023.338599684044, 11448.713782155513, 11478.827817582576, 11590.768184636092, 11684.889935456118, 7824.21757824213, 7857.214278572097, 7971.561564114029, 8036.799080023046, 8101.35189184852, 10711.464426778786, 11134.498947547218, 11191.944040279746, 11391.0, 11488.36813975206], "value": 8758.113634490855, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.9877101228988, 1484.065039025414, 1613.9677206455872, 1750.1511766793112, 1792.9103544822765, 7624.69501219952, 7883.290503854652, 8170.959145204273, 8568.157846063103, 8603.311735061188, 8069.677212911492, 8390.706325278601, 8441.78895527612, 8501.107383724713, 8515.489070655765, 2306.054517647769, 2335.8131349492, 2337.801286890616, 2339.4268404240984, 2339.6139636959883, 2325.5930212212793, 2330.2659662206433, 2354.6468029795496, 2372.0986025310444, 2376.9049238030543, 2320.5938960681897, 2331.3006098170567, 2346.378209774411, 2349.2952114365708, 2357.1514254868234, 2289.404754763765, 2297.6208925527267, 2308.90764369425, 2316.108298305148, 2319.976800231993, 2319.826013049028, 2347.0, 2351.0, 2353.705786776653, 2354.7527509611414, 8418.915810841936, 8501.022382425976, 8548.871766923501, 8566.571671416405, 8571.057183709787, 5055.469175736532, 5143.3570803649545, 5290.259363689087, 5311.814086506954, 5348.144296912515, 3031.060371284909, 3136.200268931431, 3437.020449171999, 3564.7861128332383, 3569.0, 987.8320685483485, 1041.6198087697935, 1059.7880423915249, 1073.549109374057, 1087.065123993365, 9963.0, 10254.846177307347, 11016.834747478713, 11036.834447483294, 11101.333919964747, 4480.865574032784, 4578.656600754956, 4667.113248482778, 4682.859514214579, 4705.0, 9375.5312234388, 9473.147416732389, 9594.184494317922, 9620.807583848284, 9627.277954153467, 4359.346098085249, 4508.323751437245, 4633.443986721615, 5272.367315922113, 5276.630635855453, 4277.871663850089, 4334.696571240046, 4492.191405547033, 4678.368420263289, 4836.975815120902, 3587.1390866191928, 3710.777353358807, 3843.3466310726685, 3864.130570621644, 3927.1360300734364, 1101.8071837428417, 1218.9821499048217, 1248.6940699528673, 1267.3599832084737, 1274.9107562470538, 723.3200791256203, 793.757903839323, 805.3275514945086, 840.4873027453249, 900.1583519409373, 4677.64917631179, 5428.809991650273, 5779.566532510079, 5836.970815145897, 5846.736896839652, 421.21233293740903, 430.5178200415492, 432.9826806927688, 438.2440290498864, 456.32236129347893, 3829.7702137871815, 3864.652181303696, 3869.7678139311734, 3926.5680775114715, 3947.5460322062754, 10017.749556261151, 10177.592896284214, 10324.483775811179, 10329.0, 10401.167906567456, 10027.896931337547, 10262.230332725076, 10264.43545604984, 10283.78898536818, 10320.690379288633, 7861.213878612093, 7870.44906856526, 7969.9601501993275, 8000.919990800134, 8018.55897925608, 5571.4428557143965, 5641.4358564144895, 5646.85882852932, 5704.258446401941, 5711.257536520226, 9460.763480913032, 9508.857367139637, 9591.232701384011, 9618.374805637612, 9684.56419461112, 6592.967035164986, 9712.611495540383, 9740.464274464972, 9743.805123897624, 9747.074027967332, 6707.094542236736, 6933.965330173317, 7099.787006389816, 7244.710211591478, 7407.296306850841, 7906.446548741533, 8179.182081791773, 8192.959035204785, 8198.344132469507, 8202.794930126794, 6466.224053113657, 7091.255418181223, 7129.180144283474, 7286.96356518214, 7301.415886729155, 7089.179100783242, 7145.708981294683, 7158.534695244793, 7263.418926485974, 7281.745138920112, 6749.966250168718, 6750.2237242715755, 6802.897956530557, 6818.420434263143, 6831.795046148623, 4834.516548345137, 4858.854234372974, 4895.290182923527, 4916.483769204182, 4918.852434426973, 4795.112904112751, 4832.855014349575, 4966.478519755374, 5014.172661510956, 5041.1430056889685], "value": 4331.718292399556, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.7736708784866, 300.87964814074365, 309.5017022593624, 312.8216916357677, 313.57667149348384, 5199.740012999354, 5255.448177941309, 5335.638547714939, 5510.173473978905, 5517.586181036419, 4505.977470112645, 4585.449746030473, 5356.143017117262, 5401.972990135054, 5430.91853622196, 3297.422950983568, 4141.565135660742, 4208.494980602317, 4211.452511173543, 4254.829806807739, 3684.9815750921207, 4121.498547728065, 4191.308434108369, 4195.643370313527, 4198.97900510497, 4060.025593857468, 4123.649489793371, 4127.731697439672, 4128.97935510322, 4158.729682570638, 3414.8804791832417, 4103.958960410402, 4129.139254796744, 4144.979275103605, 4165.770882601456, 3023.7732170087334, 3592.784432934006, 3593.646161764049, 3601.3697602919383, 3601.657842504958, 4310.374995625648, 4711.411073615798, 4819.301201325823, 4879.170541008037, 5298.735063246822, 4643.674942754009, 4652.836790802299, 4657.278121891112, 4667.836625718118, 4697.577218050357, 5412.645499207857, 5655.886882262373, 5706.657600543981, 5737.254156959569, 5755.870327978633, 3319.6762266413607, 3668.284684486526, 3791.545014598238, 3828.272628200633, 3896.8636097736726, 5186.740662966874, 5399.973000134974, 5570.412432456771, 6005.057521814323, 6045.637261764308, 3485.320362529333, 3767.2482295732616, 3906.0625449891822, 3933.5869733678123, 3941.428492868518, 3849.653531182207, 3958.7426817256787, 3985.621365970228, 3995.960040399617, 4006.6193711597352, 4128.752274863517, 4538.797218737037, 4549.977250113729, 4616.584507394284, 4623.127633308515, 5334.759935802898, 5734.713264336767, 5808.332041815161, 5810.41895810424, 5825.475707186373, 1140.589387820384, 1173.5423184957874, 1207.643745095199, 1236.5857437758273, 1260.5336025670376, 867.3711559119588, 872.9345299102466, 876.8334016536837, 882.4793371910604, 885.5527958381106, 4639.234526303189, 5003.449620541737, 5026.115206797451, 5036.64743467961, 5084.923726144111, 5246.921296180561, 5452.536534394541, 5542.806001789918, 5551.916721249185, 5575.944240557624, 4372.213001659668, 4573.794179261941, 5013.54878060969, 5030.270610761419, 5063.5695965843315, 2519.533886231053, 2551.374913146254, 2760.599713041638, 2795.3011747063233, 2852.8288302701903, 5283.815066472655, 5620.943790562042, 5728.169415434739, 5768.288904215012, 5779.364269930388, 5138.172061813898, 5360.812371566976, 5406.702631355234, 5571.4150014248735, 5618.286422641134, 4923.719832843486, 4951.598474739749, 5377.435369286169, 5686.175504551899, 5779.942200578024, 5316.680999140064, 5477.397486276586, 5916.852078698067, 5927.4665280125, 5963.73163207648, 5335.493128152819, 5841.970790146022, 5877.617954832922, 5879.147523609138, 5901.763929442946, 5302.0, 5766.798162064475, 6170.53720970938, 6180.2077397556, 6194.566380353332, 3860.556036055777, 3943.743656662308, 3964.1278918637854, 3972.781497017692, 3995.4006898965386, 5092.388913330424, 5505.9174112387545, 5614.85962850932, 5964.642121472725, 6062.636241825328, 5182.32629758129, 5282.524572788468, 5492.286002819608, 5689.041698316935, 5872.148538461814, 5106.387233532, 5238.449962754008, 5653.038983372754, 5713.657180569179, 5758.827235182951, 4991.925121123259, 5056.0140772549, 5386.087938781676, 5435.0, 5719.0, 5002.749862506933, 5008.649394542347, 5704.743286552032, 5749.568782341257, 5773.797917072882, 4585.19759042173, 5369.979703856255, 5386.892262154656, 5637.774489020558, 5639.0], "value": 4082.9349252523093, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.9968600156999, 664.2493981800565, 738.9815254618635, 765.8774596064627, 791.0704921716981, 13923.860761392383, 14801.407943682267, 14880.47918322859, 14882.851171488282, 14903.329350179241, 13019.60941171764, 13591.864081359183, 13732.725345493083, 13746.175229486213, 13752.312384380792, 811.8051667599777, 820.7209548753423, 833.4624167412015, 833.791552111972, 834.0241916957175, 659.2056571830956, 681.5433659448157, 803.445622520459, 815.9428839981204, 829.0838623321233, 876.2201640539893, 876.329607849992, 876.548577482597, 876.9605367758435, 881.2949640287776, 872.3326655108847, 873.9344549158839, 874.108409422392, 874.3224001398903, 875.0767939823484, 644.9290578036412, 646.6863571167979, 648.0764910003273, 648.743746220245, 648.9188851393576, 13893.305334733324, 14134.222617756019, 14143.717125657533, 14148.504802331972, 14227.14637121776, 13540.729185416336, 13729.588112356545, 13865.514706985308, 13915.443382264697, 13980.650483737887, 11497.482613282422, 11538.82691759616, 11584.478698458506, 11737.178397512178, 11767.235129716626, 3235.9352812943607, 3310.8013519188926, 3636.4181730922924, 3819.2934307153264, 3824.8661296854757, 90966.54516727374, 92662.99999999933, 93768.0, 94334.52832735861, 94398.5280073602, 10590.89280738767, 10627.628033018807, 10692.411917344622, 10711.732206694895, 10826.296290741077, 17734.379296724554, 21038.78961210399, 21240.681389779165, 21397.78602213958, 21467.355979320644, 7508.511946723445, 7595.772126836204, 7623.618819059025, 7668.578228197391, 7731.7680469586, 4172.72877262983, 4192.413062171298, 4213.957860421357, 4224.4508213931995, 4266.146770645822, 3416.0605833396016, 3482.21650128719, 3490.2844916791996, 3511.473279008118, 3524.506569080331, 616.1004932798076, 624.1137584629746, 632.88291666041, 633.8732253549308, 642.194046471675, 516.1973131780118, 600.2586805295457, 600.9338972712998, 607.5139888089511, 666.4401902402005, 7175.748848790267, 7764.417668674872, 8785.648574057093, 8785.692500762443, 8811.647534098693, 604.5888795618934, 621.1397214857365, 626.7687034158411, 637.1621317966859, 640.2445114764622, 4200.201961627342, 4217.641500472433, 4330.848420305274, 4333.761643109659, 4474.709143905636, 74804.62597686976, 108525.0, 117282.41358793151, 117811.0, 121922.0, 101692.0, 102269.4659580107, 102437.48781256046, 103274.0, 105008.0, 89134.0, 91428.08571914329, 92385.0, 93005.99999999865, 94830.52584737031, 7986.680532778856, 8279.668813247403, 8366.581670916552, 8506.489610623383, 8589.398742088244, 45802.0, 45816.77091614521, 51411.228831566805, 55815.44184558102, 56502.71748641231, 54646.360609181786, 58906.0, 64194.39514012187, 81743.0, 84377.57811210904, 9991.300608957303, 11457.19799614019, 11695.181337306307, 11710.414479276173, 11963.282203067844, 12149.392530373269, 12239.449224785085, 12304.630861074182, 12330.568430104904, 12728.23630582168, 11217.943910280397, 11249.49377278008, 11304.604338848101, 11442.771144576895, 11969.982551483221, 8395.70615028471, 11070.723231919266, 11331.206815523165, 11386.94306528462, 11594.246373985665, 9059.501727405055, 10572.259941803999, 10962.232643714864, 11142.832857507306, 11197.66407007791, 9217.354785164975, 9616.08647178517, 10044.753853804077, 10109.19126469866, 10306.948465257627, 8972.551372431482, 9347.953260233655, 9521.666741664008, 9525.666601668909, 9583.568739406883], "value": 7057.456601842804, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1626.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 174.0, 149.0, 118.0, 118.0, 119.0, 117.0, 120.0, 117.0, 120.0], "value": 128.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 118.0, 122.0, 182.0, 122.0, 117.0, 118.0, 115.0, 138.0, 106.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 213.0, 224.0, 201.0, 197.0, 198.0, 196.0, 203.0, 195.0, 200.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 60.0, 98.0, 73.0, 53.0, 51.0, 51.0, 85.0, 77.0], "value": 68.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 69.0, 83.0, 79.0, 79.0, 57.0, 83.0, 57.0, 81.0, 96.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 79.0, 114.0, 137.0, 134.0, 90.0, 88.0], "value": 99.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 104.0, 128.0, 174.0, 118.0, 174.0, 159.0, 101.0, 101.0, 100.0], "value": 126.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 98.0, 100.0, 93.0, 124.0, 101.0, 93.0, 94.0, 103.0], "value": 98.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 56.0, 58.0, 58.0, 58.0, 57.0, 56.0, 58.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 90.0, 117.0, 110.0, 67.0, 68.0, 68.0, 70.0, 72.0, 72.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 102.0, 112.0, 116.0, 87.0, 111.0, 109.0, 113.0, 92.0, 91.0], "value": 101.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 157.0, 151.0, 152.0, 168.0, 166.0, 174.0, 174.0, 214.0, 169.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 208.0, 239.0, 210.0, 176.0, 245.0, 243.0, 179.0, 241.0, 238.0], "value": 215.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 65.0, 62.0, 86.0, 93.0, 92.0, 90.0, 62.0], "value": 75.7, "unit": "score"}]}]} 22:54:33 INFO - Return code: 0 22:54:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:54:33 ERROR - # TBPL SUCCESS # 22:54:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:54:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:54:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:54:33 INFO - 2016-11-23 22:54:33.649 screenresolution[1655:10597] starting screenresolution argv=screenresolution get 22:54:33 INFO - 2016-11-23 22:54:33.651 screenresolution[1655:10597] Display 0: 1600x1200x32@60 22:54:33 INFO - 2016-11-23 22:54:33.658 screenresolution[1656:10600] starting screenresolution argv=screenresolution list 22:54:33 INFO - Available Modes on Display 0 22:54:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:54:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:54:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:54:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:54:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:54:33 INFO - Intel Iris: 22:54:33 INFO - Chipset Model: Intel Iris 22:54:33 INFO - Type: GPU 22:54:33 INFO - Bus: Built-In 22:54:33 INFO - VRAM (Dynamic, Max): 1536 MB 22:54:33 INFO - Vendor: Intel (0x8086) 22:54:33 INFO - Device ID: 0x0a2e 22:54:33 INFO - Revision ID: 0x0009 22:54:33 INFO - Displays: 22:54:33 INFO - Display: 22:54:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:54:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:54:33 INFO - Display Serial Number: 1600x1200 60 22:54:33 INFO - Main Display: Yes 22:54:33 INFO - Mirror: Off 22:54:33 INFO - Online: Yes 22:54:33 INFO - Rotation: Supported 22:54:33 INFO - Return code: 0 22:54:33 INFO - Running post-action listener: _resource_record_post_action 22:54:33 INFO - [mozharness: 2016-11-24 06:54:33.903255Z] Finished run-tests step (success) 22:54:33 INFO - Running post-run listener: _resource_record_post_run 22:54:33 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 39254528; Write bytes: 696176640; Read time: 385; Write time: 2004 22:54:33 INFO - TinderboxPrint: CPU usage
54.6% 22:54:33 INFO - TinderboxPrint: I/O read bytes / time
39,254,528 / 385 22:54:33 INFO - TinderboxPrint: I/O write bytes / time
696,176,640 / 2,004 22:54:33 INFO - TinderboxPrint: CPU idle
2,200.5 (45.4%) 22:54:33 INFO - TinderboxPrint: CPU system
984.0 (20.3%) 22:54:33 INFO - TinderboxPrint: CPU user
1,665.3 (34.3%) 22:54:33 INFO - TinderboxPrint: Swap in / out
422,359,040 / 0 22:54:33 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 294912; Write bytes: 386701312; Read time: 8; Write time: 771 22:54:33 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35301888; Write bytes: 307914752; Read time: 352; Write time: 1227 22:54:34 INFO - Running post-run listener: _upload_blobber_files 22:54:34 INFO - Blob upload gear active. 22:54:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:54:34 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 22:54:34 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'] 22:54:34 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 22:54:34 INFO - (blobuploader) - INFO - Open directory for files ... 22:54:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:54:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:54:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:35 INFO - (blobuploader) - INFO - Done attempting. 22:54:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:54:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:54:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:36 INFO - (blobuploader) - INFO - Done attempting. 22:54:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:54:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:54:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:54:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:54:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:54:39 INFO - (blobuploader) - INFO - Done attempting. 22:54:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:54:39 INFO - Return code: 0 22:54:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:54:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:54:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cd4330cf3fe508038027aa2059c74450c15884e72fa420b7a19c78bae5123cba902f5457942da2edf07f41d64d1502c876b05d1ecb9f858e024593240fe91858", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e07cedd777646dd141c74760bca0a088e9aee6d24e1b10680ede552c84ffda6e4656d1f08ca0f6734fd11c7546c977d52db535e47d7535a072e409743e3a3794", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c067abe0d6b3c17f4cf2257db2cb663d528d616278c1f8a064a069cdfd33f00ef24cca23e980551f69cb4872b635374038d8e0eac3e29adbae6bf0468b129ff8"} 22:54:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:54:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:54:39 INFO - Contents: 22:54:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cd4330cf3fe508038027aa2059c74450c15884e72fa420b7a19c78bae5123cba902f5457942da2edf07f41d64d1502c876b05d1ecb9f858e024593240fe91858", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e07cedd777646dd141c74760bca0a088e9aee6d24e1b10680ede552c84ffda6e4656d1f08ca0f6734fd11c7546c977d52db535e47d7535a072e409743e3a3794", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c067abe0d6b3c17f4cf2257db2cb663d528d616278c1f8a064a069cdfd33f00ef24cca23e980551f69cb4872b635374038d8e0eac3e29adbae6bf0468b129ff8"} 22:54:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:54:39 INFO - Copying logs to upload dir... 22:54:39 INFO - mkdir: /builds/slave/test/build/upload/logs 22:54:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.566534 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-23 22:54:39.114801) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 22:54:39.118115) ========= 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.DNTQFJIYom/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.idt7R3vjrL/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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/cd4330cf3fe508038027aa2059c74450c15884e72fa420b7a19c78bae5123cba902f5457942da2edf07f41d64d1502c876b05d1ecb9f858e024593240fe91858", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e07cedd777646dd141c74760bca0a088e9aee6d24e1b10680ede552c84ffda6e4656d1f08ca0f6734fd11c7546c977d52db535e47d7535a072e409743e3a3794", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c067abe0d6b3c17f4cf2257db2cb663d528d616278c1f8a064a069cdfd33f00ef24cca23e980551f69cb4872b635374038d8e0eac3e29adbae6bf0468b129ff8"} build_url:https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010992 build_url: 'https://queue.taskcluster.net/v1/task/eBNUeX3QTcib8DEwVDDzJA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cd4330cf3fe508038027aa2059c74450c15884e72fa420b7a19c78bae5123cba902f5457942da2edf07f41d64d1502c876b05d1ecb9f858e024593240fe91858", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e07cedd777646dd141c74760bca0a088e9aee6d24e1b10680ede552c84ffda6e4656d1f08ca0f6734fd11c7546c977d52db535e47d7535a072e409743e3a3794", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c067abe0d6b3c17f4cf2257db2cb663d528d616278c1f8a064a069cdfd33f00ef24cca23e980551f69cb4872b635374038d8e0eac3e29adbae6bf0468b129ff8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 22:54:39.152195) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 22:54:39.152539) ========= 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.DNTQFJIYom/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.idt7R3vjrL/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005154 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 22:54:39.201520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 22:54:39.201878) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 22:54:39.202228) ========= ========= Total master_lag: 0.18 =========