builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0278 starttime: 1481158903.82 results: success (0) buildid: 20161207150238 builduid: a3f892fa62794f3f93a4e75bfad0faf2 revision: b5e0e2b971bd9cc74e8671f89035902e821251e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:43.817150) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:43.818051) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:43.818364) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:43.885556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:43.885916) ========= 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.X5RkLbnLUD/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.dynSn6c4GI/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-07 17:01:43-- 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% 28.7M=0s 2016-12-07 17:01:44 (28.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.546843 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:44.457183) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:44.457478) ========= 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.X5RkLbnLUD/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.dynSn6c4GI/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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.025364 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:44.505651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-07 17:01:44.505974) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev b5e0e2b971bd9cc74e8671f89035902e821251e6 --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 b5e0e2b971bd9cc74e8671f89035902e821251e6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X5RkLbnLUD/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.dynSn6c4GI/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-07 17:01:44,602 truncating revision to first 12 chars 2016-12-07 17:01:44,602 Setting DEBUG logging. 2016-12-07 17:01:44,602 attempt 1/10 2016-12-07 17:01:44,602 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/b5e0e2b971bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-07 17:01:45,676 unpacking tar archive at: graphics-b5e0e2b971bd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.351717 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-07 17:01:45.938625) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:45.938896) ========= script_repo_revision: b5e0e2b971bd9cc74e8671f89035902e821251e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:45.939255) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:45.939518) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-07 17:01:45.971036) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-07 17:01:45.971289) ========= /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.X5RkLbnLUD/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.dynSn6c4GI/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:01:46 INFO - MultiFileLogger online at 20161207 17:01:46 in /builds/slave/test 17:01:46 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 17:01:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:01:46 INFO - {'append_to_log': False, 17:01:46 INFO - 'base_work_dir': '/builds/slave/test', 17:01:46 INFO - 'blob_upload_branch': 'Graphics', 17:01:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:01:46 INFO - 'branch': 'Graphics', 17:01:46 INFO - 'buildbot_json_path': 'buildprops.json', 17:01:46 INFO - 'config_files': ('talos/mac_config.py',), 17:01:46 INFO - 'default_actions': ('clobber', 17:01:46 INFO - 'read-buildbot-config', 17:01:46 INFO - 'download-and-extract', 17:01:46 INFO - 'populate-webroot', 17:01:46 INFO - 'create-virtualenv', 17:01:46 INFO - 'install', 17:01:46 INFO - 'run-tests'), 17:01:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:01:46 INFO - 'download_minidump_stackwalk': True, 17:01:46 INFO - 'download_symbols': 'ondemand', 17:01:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:01:46 INFO - 'tooltool.py': '/tools/tooltool.py', 17:01:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:01:46 INFO - '/tools/misc-python/virtualenv.py')}, 17:01:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:01:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:01:46 INFO - 'installer_path': 'installer.exe', 17:01:46 INFO - 'log_level': 'info', 17:01:46 INFO - 'log_name': 'talos', 17:01:46 INFO - 'log_to_console': True, 17:01:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:01:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:01:46 INFO - 'opt_config_files': (), 17:01:46 INFO - 'pip_index': False, 17:01:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:46 INFO - 'cmd': ('bash', 17:01:46 INFO - '-c', 17:01:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:46 INFO - 'enabled': True, 17:01:46 INFO - 'halt_on_failure': False, 17:01:46 INFO - 'name': 'check_screen_resolution'},), 17:01:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:01:46 INFO - 'cmd': ('bash', 17:01:46 INFO - '-c', 17:01:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:01:46 INFO - 'enabled': True, 17:01:46 INFO - 'halt_on_failure': False, 17:01:46 INFO - 'name': 'check_screen_resolution'},), 17:01:46 INFO - 'run_cmd_checks_enabled': True, 17:01:46 INFO - 'sps_profile': False, 17:01:46 INFO - 'sps_profile_interval': 0, 17:01:46 INFO - 'suite': 'dromaeojs-e10s', 17:01:46 INFO - 'system_bits': '32', 17:01:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:01:46 INFO - 'title': 't-yosemite-r7-0278', 17:01:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:01:46 INFO - 'use_talos_json': True, 17:01:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:01:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:01:46 INFO - 'work_dir': 'build'} 17:01:46 INFO - [mozharness: 2016-12-08 01:01:46.170963Z] Running clobber step. 17:01:46 INFO - Running pre-action listener: _resource_record_pre_action 17:01:46 INFO - Running main action method: clobber 17:01:46 INFO - rmtree: /builds/slave/test/build 17:01:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:01:48 INFO - Running post-action listener: _resource_record_post_action 17:01:48 INFO - [mozharness: 2016-12-08 01:01:48.554629Z] Finished clobber step (success) 17:01:48 INFO - [mozharness: 2016-12-08 01:01:48.554752Z] Running read-buildbot-config step. 17:01:48 INFO - Running pre-action listener: _resource_record_pre_action 17:01:48 INFO - Running main action method: read_buildbot_config 17:01:48 INFO - Using buildbot properties: 17:01:48 INFO - { 17:01:48 INFO - "project": "", 17:01:48 INFO - "product": "firefox", 17:01:48 INFO - "script_repo_revision": "default", 17:01:48 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 17:01:48 INFO - "repository": "", 17:01:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 17:01:48 INFO - "buildid": "20161207150238", 17:01:48 INFO - "pgo_build": "False", 17:01:48 INFO - "basedir": "/builds/slave/test", 17:01:48 INFO - "buildnumber": 1, 17:01:48 INFO - "slavename": "t-yosemite-r7-0278", 17:01:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:01:48 INFO - "platform": "yosemite_r7", 17:01:48 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 17:01:48 INFO - "branch": "graphics", 17:01:48 INFO - "repo_path": "projects/graphics", 17:01:48 INFO - "revision": "b5e0e2b971bd9cc74e8671f89035902e821251e6", 17:01:48 INFO - "stage_platform": "macosx64", 17:01:48 INFO - "builduid": "a3f892fa62794f3f93a4e75bfad0faf2", 17:01:48 INFO - "slavebuilddir": "test" 17:01:48 INFO - } 17:01:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 17:01:48 INFO - Running post-action listener: _resource_record_post_action 17:01:48 INFO - [mozharness: 2016-12-08 01:01:48.557997Z] Finished read-buildbot-config step (success) 17:01:48 INFO - [mozharness: 2016-12-08 01:01:48.558125Z] Running download-and-extract step. 17:01:48 INFO - Running pre-action listener: _resource_record_pre_action 17:01:48 INFO - Running main action method: download_and_extract 17:01:48 INFO - mkdir: /builds/slave/test/build/tests 17:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:48 INFO - trying https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 17:01:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/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 17:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/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 17:01:49 INFO - Downloaded 1199 bytes. 17:01:49 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 17:01:49 INFO - Using the following test package requirements: 17:01:49 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 17:01:49 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 17:01:49 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 17:01:49 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'jsshell-mac.zip'], 17:01:49 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 17:01:49 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 17:01:49 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 17:01:49 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 17:01:49 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 17:01:49 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:01:49 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 17:01:49 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:01:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 17:01:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:01:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 17:01:50 INFO - Expected file size: 16284190 17:01:50 INFO - Obtained file size: 16284190 17:01:57 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 17:01:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 17:01:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:01:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 17:01:58 INFO - Expected file size: 16284190 17:01:58 INFO - Obtained file size: 16284190 17:02:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 17:02:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:02:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 17:02:05 INFO - Expected file size: 14409734 17:02:05 INFO - Obtained file size: 14409734 17:02:05 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')]} 17:02:05 INFO - trying https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:02:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 17:02:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/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 17:02:08 INFO - Downloaded 107687813 bytes. 17:02:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:02:08 INFO - mkdir: /builds/slave/test/properties 17:02:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:02:08 INFO - Writing to file /builds/slave/test/properties/build_url 17:02:08 INFO - Contents: 17:02:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:02:09 INFO - Running post-action listener: _resource_record_post_action 17:02:09 INFO - Running post-action listener: set_extra_try_arguments 17:02:09 INFO - [mozharness: 2016-12-08 01:02:09.204728Z] Finished download-and-extract step (success) 17:02:09 INFO - [mozharness: 2016-12-08 01:02:09.204844Z] Running populate-webroot step. 17:02:09 INFO - Running pre-action listener: _resource_record_pre_action 17:02:09 INFO - Running main action method: populate_webroot 17:02:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:02:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:02:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:02:09 INFO - '--tppagecycles', 17:02:09 INFO - '7'], 17:02:09 INFO - 'tests': ['tsvgm']}}, 17:02:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:02:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:02:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:02:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:02:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:02:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:02:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['damp', 'tps']}, 17:02:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:02:09 INFO - 'tests': ['damp', 'tps']}, 17:02:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['dromaeo_dom']}, 17:02:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:02:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['basic_compositor_video']}, 17:02:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:02:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other-e10s': {'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['a11yr', 17:02:09 INFO - 'ts_paint', 17:02:09 INFO - 'tpaint', 17:02:09 INFO - 'sessionrestore', 17:02:09 INFO - 'sessionrestore_no_auto_restore', 17:02:09 INFO - 'tabpaint']}, 17:02:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tsvgx', 17:02:09 INFO - 'tsvgr_opacity', 17:02:09 INFO - 'tart', 17:02:09 INFO - 'tscrollx', 17:02:09 INFO - 'cart', 17:02:09 INFO - 'tsvg_static']}, 17:02:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:02:09 INFO - 'tsvgr_opacity', 17:02:09 INFO - 'tart', 17:02:09 INFO - 'tscrollx', 17:02:09 INFO - 'cart', 17:02:09 INFO - 'tsvg_static']}, 17:02:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s'], 17:02:09 INFO - 'tests': ['tp5o']}, 17:02:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:02:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:02:09 INFO - 'talos_options': ['--disable-e10s', 17:02:09 INFO - '--xperf_path', 17:02:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:09 INFO - 'tests': ['tp5n']}, 17:02:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:02:09 INFO - 'talos_options': ['--xperf_path', 17:02:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:09 INFO - 'tests': ['tp5n']}}, 17:02:09 INFO - 'talos.zip': {'path': '', 17:02:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:02:09 INFO - Running post-action listener: _resource_record_post_action 17:02:09 INFO - [mozharness: 2016-12-08 01:02:09.223205Z] Finished populate-webroot step (success) 17:02:09 INFO - [mozharness: 2016-12-08 01:02:09.223308Z] Running create-virtualenv step. 17:02:09 INFO - Running pre-action listener: _resource_record_pre_action 17:02:09 INFO - Running main action method: create_virtualenv 17:02:09 INFO - Creating virtualenv /builds/slave/test/build/venv 17:02:09 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 17:02:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:02:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:02:09 INFO - Using real prefix '/tools/python27' 17:02:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:02:10 INFO - Installing distribute.............................................................................................................................................................................................done. 17:02:12 INFO - Installing pip.................done. 17:02:12 INFO - Return code: 0 17:02:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:02:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:02:13 INFO - Reading from file tmpfile_stdout 17:02:13 INFO - Output received: 17:02:13 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) 17:02:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:13 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 17:02:13 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 17:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:13 INFO - 'HOME': '/Users/cltbld', 17:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:13 INFO - 'LOGNAME': 'cltbld', 17:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:13 INFO - 'MOZ_NO_REMOTE': '1', 17:02:13 INFO - 'NO_EM_RESTART': '1', 17:02:13 INFO - 'PAGER': '/bin/cat', 17:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:13 INFO - 'PWD': '/builds/slave/test', 17:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:13 INFO - 'SHELL': '/bin/bash', 17:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:13 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:13 INFO - 'USER': 'cltbld', 17:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:13 INFO - 'XPC_FLAGS': '0x0', 17:02:13 INFO - 'XPC_SERVICE_NAME': '0', 17:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:13 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 17:02:13 INFO - Cleaning up... 17:02:13 INFO - Return code: 0 17:02:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:13 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:13 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 17:02:13 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 17:02:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:13 INFO - 'HOME': '/Users/cltbld', 17:02:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:13 INFO - 'LOGNAME': 'cltbld', 17:02:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:13 INFO - 'MOZ_NO_REMOTE': '1', 17:02:13 INFO - 'NO_EM_RESTART': '1', 17:02:13 INFO - 'PAGER': '/bin/cat', 17:02:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:13 INFO - 'PWD': '/builds/slave/test', 17:02:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:13 INFO - 'SHELL': '/bin/bash', 17:02:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:13 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:13 INFO - 'USER': 'cltbld', 17:02:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:13 INFO - 'XPC_FLAGS': '0x0', 17:02:13 INFO - 'XPC_SERVICE_NAME': '0', 17:02:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:13 INFO - Downloading/unpacking psutil>=3.1.1 17:02:13 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 17:02:13 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 17:02:13 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 17:02:13 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 17:02:13 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 17:02:13 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 17:02:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:02:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:17 INFO - Installing collected packages: psutil 17:02:17 INFO - Running setup.py install for psutil 17:02:17 INFO - building 'psutil._psutil_osx' extension 17:02:17 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 17:02:18 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 17:02:18 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 17:02:18 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 17:02:18 INFO - building 'psutil._psutil_posix' extension 17:02:18 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 17:02:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:02:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:02:18 INFO - ^ 17:02:18 INFO - 1 warning generated. 17:02:18 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 17:02:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:02:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:02:18 INFO - Successfully installed psutil 17:02:18 INFO - Cleaning up... 17:02:18 INFO - Return code: 0 17:02:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:02:18 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')]} 17:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:18 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')]} 17:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:18 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:18 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 17:02:18 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 17:02:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:18 INFO - 'HOME': '/Users/cltbld', 17:02:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:18 INFO - 'LOGNAME': 'cltbld', 17:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:18 INFO - 'MOZ_NO_REMOTE': '1', 17:02:18 INFO - 'NO_EM_RESTART': '1', 17:02:18 INFO - 'PAGER': '/bin/cat', 17:02:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:18 INFO - 'PWD': '/builds/slave/test', 17:02:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:18 INFO - 'SHELL': '/bin/bash', 17:02:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:18 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:18 INFO - 'USER': 'cltbld', 17:02:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:18 INFO - 'XPC_FLAGS': '0x0', 17:02:18 INFO - 'XPC_SERVICE_NAME': '0', 17:02:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:02:19 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 17:02:19 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 17:02:19 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 17:02:19 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 17:02:19 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 17:02:19 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 17:02:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:02:21 INFO - Installing collected packages: mozsystemmonitor 17:02:21 INFO - Running setup.py install for mozsystemmonitor 17:02:21 INFO - Successfully installed mozsystemmonitor 17:02:21 INFO - Cleaning up... 17:02:21 INFO - Return code: 0 17:02:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:21 INFO - 'HOME': '/Users/cltbld', 17:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:21 INFO - 'LOGNAME': 'cltbld', 17:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:21 INFO - 'MOZ_NO_REMOTE': '1', 17:02:21 INFO - 'NO_EM_RESTART': '1', 17:02:21 INFO - 'PAGER': '/bin/cat', 17:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:21 INFO - 'PWD': '/builds/slave/test', 17:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:21 INFO - 'SHELL': '/bin/bash', 17:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:21 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:21 INFO - 'USER': 'cltbld', 17:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:21 INFO - 'XPC_FLAGS': '0x0', 17:02:21 INFO - 'XPC_SERVICE_NAME': '0', 17:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:22 INFO - Downloading/unpacking jsonschema==2.5.1 17:02:22 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 17:02:22 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 17:02:22 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 17:02:22 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 17:02:22 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 17:02:22 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 17:02:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:02:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:25 INFO - Downloading functools32-3.2.3-2.tar.gz 17:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:02:25 INFO - warning: no files found matching '*.txt' 17:02:25 INFO - no previously-included directories found matching 'build' 17:02:25 INFO - no previously-included directories found matching 'dist' 17:02:25 INFO - no previously-included directories found matching '.git*' 17:02:25 INFO - Installing collected packages: jsonschema, functools32 17:02:25 INFO - Running setup.py install for functools32 17:02:25 INFO - warning: no files found matching '*.txt' 17:02:25 INFO - no previously-included directories found matching 'build' 17:02:25 INFO - no previously-included directories found matching 'dist' 17:02:25 INFO - no previously-included directories found matching '.git*' 17:02:25 INFO - Successfully installed jsonschema functools32 17:02:25 INFO - Cleaning up... 17:02:25 INFO - Return code: 0 17:02:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:02:25 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')]} 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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')]} 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:25 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 17:02:25 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 17:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:25 INFO - 'HOME': '/Users/cltbld', 17:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:25 INFO - 'LOGNAME': 'cltbld', 17:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:25 INFO - 'MOZ_NO_REMOTE': '1', 17:02:25 INFO - 'NO_EM_RESTART': '1', 17:02:25 INFO - 'PAGER': '/bin/cat', 17:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:25 INFO - 'PWD': '/builds/slave/test', 17:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:25 INFO - 'SHELL': '/bin/bash', 17:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:25 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:25 INFO - 'USER': 'cltbld', 17:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:25 INFO - 'XPC_FLAGS': '0x0', 17:02:25 INFO - 'XPC_SERVICE_NAME': '0', 17:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:02:25 INFO - Cleaning up... 17:02:25 INFO - Return code: 0 17:02:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:02:25 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')]} 17:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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')]} 17:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:25 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:25 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 17:02:25 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 17:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:25 INFO - 'HOME': '/Users/cltbld', 17:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:25 INFO - 'LOGNAME': 'cltbld', 17:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:25 INFO - 'MOZ_NO_REMOTE': '1', 17:02:25 INFO - 'NO_EM_RESTART': '1', 17:02:25 INFO - 'PAGER': '/bin/cat', 17:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:25 INFO - 'PWD': '/builds/slave/test', 17:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:25 INFO - 'SHELL': '/bin/bash', 17:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:25 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:25 INFO - 'USER': 'cltbld', 17:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:25 INFO - 'XPC_FLAGS': '0x0', 17:02:25 INFO - 'XPC_SERVICE_NAME': '0', 17:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:02:25 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 17:02:25 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 17:02:25 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 17:02:25 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 17:02:25 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 17:02:25 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 17:02:28 INFO - Downloading blobuploader-1.2.4.tar.gz 17:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:02:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:02:28 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 17:02:28 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 17:02:28 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 17:02:28 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 17:02:28 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 17:02:28 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 17:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:02:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:02:29 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 17:02:29 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 17:02:29 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 17:02:29 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 17:02:29 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 17:02:29 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 17:02:29 INFO - Downloading docopt-0.6.1.tar.gz 17:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:02:29 INFO - Installing collected packages: blobuploader, requests, docopt 17:02:29 INFO - Running setup.py install for blobuploader 17:02:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:02:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:02:29 INFO - Running setup.py install for requests 17:02:30 INFO - Running setup.py install for docopt 17:02:30 INFO - Successfully installed blobuploader requests docopt 17:02:30 INFO - Cleaning up... 17:02:30 INFO - Return code: 0 17:02:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:30 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:30 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 17:02:30 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 17:02:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:30 INFO - 'HOME': '/Users/cltbld', 17:02:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:30 INFO - 'LOGNAME': 'cltbld', 17:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:30 INFO - 'MOZ_NO_REMOTE': '1', 17:02:30 INFO - 'NO_EM_RESTART': '1', 17:02:30 INFO - 'PAGER': '/bin/cat', 17:02:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:30 INFO - 'PWD': '/builds/slave/test', 17:02:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:30 INFO - 'SHELL': '/bin/bash', 17:02:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:30 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:30 INFO - 'USER': 'cltbld', 17:02:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:30 INFO - 'XPC_FLAGS': '0x0', 17:02:30 INFO - 'XPC_SERVICE_NAME': '0', 17:02:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:30 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-I2Bn9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-c0HIMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-cqHrjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-FQgbp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-NEYf00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-jBlHkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-blhYPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-JnKVys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-3JK6LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:31 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-ZGbtqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:32 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-isafHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:32 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-EyIXuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:32 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-EShm2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:32 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-_KkBq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:32 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-zCfLEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:32 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-Etlwmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:32 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-3kBgW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:02:32 INFO - Running setup.py install for manifestparser 17:02:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:02:32 INFO - Running setup.py install for mozcrash 17:02:33 INFO - Running setup.py install for mozdebug 17:02:33 INFO - Running setup.py install for mozdevice 17:02:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozfile 17:02:33 INFO - Running setup.py install for mozhttpd 17:02:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozinfo 17:02:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozInstall 17:02:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:02:33 INFO - Running setup.py install for mozleak 17:02:34 INFO - Running setup.py install for mozlog 17:02:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for moznetwork 17:02:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozprocess 17:02:34 INFO - Running setup.py install for mozprofile 17:02:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozrunner 17:02:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:02:34 INFO - Running setup.py install for mozscreenshot 17:02:34 INFO - Running setup.py install for moztest 17:02:35 INFO - Running setup.py install for mozversion 17:02:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:02:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:02:35 INFO - Cleaning up... 17:02:35 INFO - Return code: 0 17:02:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:35 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:35 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 17:02:35 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 17:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:35 INFO - 'HOME': '/Users/cltbld', 17:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:35 INFO - 'LOGNAME': 'cltbld', 17:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:35 INFO - 'MOZ_NO_REMOTE': '1', 17:02:35 INFO - 'NO_EM_RESTART': '1', 17:02:35 INFO - 'PAGER': '/bin/cat', 17:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:35 INFO - 'PWD': '/builds/slave/test', 17:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:35 INFO - 'SHELL': '/bin/bash', 17:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:35 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:35 INFO - 'USER': 'cltbld', 17:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:35 INFO - 'XPC_FLAGS': '0x0', 17:02:35 INFO - 'XPC_SERVICE_NAME': '0', 17:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:02:35 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-pMjyLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:02:35 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-QhS9Yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:02:35 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-5VRdh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:02:35 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-R9QEar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:02:35 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)) 17:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:02:35 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-Bi2agb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-TJjkrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-piC5R7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-RnkUWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-jPLS7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-Ftl61h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-PDPTcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-afg573-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-T1x_E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:02:36 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)) 17:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:02:36 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-kuoESX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:02:37 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)) 17:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:37 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-TDFRXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:02:37 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)) 17:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:02:37 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-suutQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:02:37 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)) 17:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:02:37 INFO - Running setup.py (path:/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/pip-XygZ0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:02:37 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)) 17:02:37 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)) 17:02:37 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)) 17:02:37 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)) 17:02:37 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)) 17:02:37 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)) 17:02:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:02:39 INFO - Downloading blessings-1.6.tar.gz 17:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:02:40 INFO - Installing collected packages: blessings 17:02:40 INFO - Running setup.py install for blessings 17:02:40 INFO - Successfully installed blessings 17:02:40 INFO - Cleaning up... 17:02:40 INFO - Return code: 0 17:02:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:40 INFO - Reading from file tmpfile_stdout 17:02:40 INFO - Current package versions: 17:02:40 INFO - blessings == 1.6 17:02:40 INFO - blobuploader == 1.2.4 17:02:40 INFO - docopt == 0.6.1 17:02:40 INFO - functools32 == 3.2.3-2 17:02:40 INFO - jsonschema == 2.5.1 17:02:40 INFO - manifestparser == 1.1 17:02:40 INFO - mozInstall == 1.12 17:02:40 INFO - mozcrash == 1.0 17:02:40 INFO - mozdebug == 0.1 17:02:40 INFO - mozdevice == 0.49 17:02:40 INFO - mozfile == 1.2 17:02:40 INFO - mozhttpd == 0.7 17:02:40 INFO - mozinfo == 0.9 17:02:40 INFO - mozleak == 0.1 17:02:40 INFO - mozlog == 3.4 17:02:40 INFO - moznetwork == 0.27 17:02:40 INFO - mozprocess == 0.23 17:02:40 INFO - mozprofile == 0.28 17:02:40 INFO - mozrunner == 6.13 17:02:40 INFO - mozscreenshot == 0.1 17:02:40 INFO - mozsystemmonitor == 0.3 17:02:40 INFO - moztest == 0.7 17:02:40 INFO - mozversion == 1.4 17:02:40 INFO - psutil == 3.1.1 17:02:40 INFO - requests == 1.2.3 17:02:40 INFO - wsgiref == 0.1.2 17:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:02:40 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')]} 17:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:40 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')]} 17:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:40 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:40 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 17:02:40 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 17:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:40 INFO - 'HOME': '/Users/cltbld', 17:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:40 INFO - 'LOGNAME': 'cltbld', 17:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:40 INFO - 'MOZ_NO_REMOTE': '1', 17:02:40 INFO - 'NO_EM_RESTART': '1', 17:02:40 INFO - 'PAGER': '/bin/cat', 17:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:40 INFO - 'PWD': '/builds/slave/test', 17:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:40 INFO - 'SHELL': '/bin/bash', 17:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:40 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:40 INFO - 'USER': 'cltbld', 17:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:40 INFO - 'XPC_FLAGS': '0x0', 17:02:40 INFO - 'XPC_SERVICE_NAME': '0', 17:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 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)) 17:02:40 INFO - Cleaning up... 17:02:40 INFO - Return code: 0 17:02:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:02:40 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')]} 17:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:40 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')]} 17:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:02:40 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 0x10ccc03f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc36570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd742ca6920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, '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.X5RkLbnLUD/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.dynSn6c4GI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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 17:02:40 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 17:02:40 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 17:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:02:40 INFO - 'HOME': '/Users/cltbld', 17:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:02:40 INFO - 'LOGNAME': 'cltbld', 17:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:02:40 INFO - 'MOZ_NO_REMOTE': '1', 17:02:40 INFO - 'NO_EM_RESTART': '1', 17:02:40 INFO - 'PAGER': '/bin/cat', 17:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:02:40 INFO - 'PWD': '/builds/slave/test', 17:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:02:40 INFO - 'SHELL': '/bin/bash', 17:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:02:40 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:02:40 INFO - 'USER': 'cltbld', 17:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:02:40 INFO - 'XPC_FLAGS': '0x0', 17:02:40 INFO - 'XPC_SERVICE_NAME': '0', 17:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:02:41 INFO - Cleaning up... 17:02:41 INFO - Return code: 0 17:02:41 INFO - Running post-action listener: _resource_record_post_action 17:02:41 INFO - Running post-action listener: _start_resource_monitoring 17:02:41 INFO - Starting resource monitoring. 17:02:41 INFO - [mozharness: 2016-12-08 01:02:41.132956Z] Finished create-virtualenv step (success) 17:02:41 INFO - [mozharness: 2016-12-08 01:02:41.133936Z] Running install step. 17:02:41 INFO - Running pre-action listener: _resource_record_pre_action 17:02:41 INFO - Running main action method: install 17:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:02:41 INFO - Reading from file tmpfile_stdout 17:02:41 INFO - Detecting whether we're running mozinstall >=1.0... 17:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:02:41 INFO - Reading from file tmpfile_stdout 17:02:41 INFO - Output received: 17:02:41 INFO - Usage: mozinstall [options] installer 17:02:41 INFO - Options: 17:02:41 INFO - -h, --help show this help message and exit 17:02:41 INFO - -d DEST, --destination=DEST 17:02:41 INFO - Directory to install application into. [default: 17:02:41 INFO - "/builds/slave/test"] 17:02:41 INFO - --app=APP Application being installed. [default: firefox] 17:02:41 INFO - mkdir: /builds/slave/test/build/application 17:02:41 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'] 17:02:41 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 17:03:15 INFO - Reading from file tmpfile_stdout 17:03:15 INFO - Output received: 17:03:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:03:15 INFO - Running post-action listener: _resource_record_post_action 17:03:15 INFO - [mozharness: 2016-12-08 01:03:15.374777Z] Finished install step (success) 17:03:15 INFO - [mozharness: 2016-12-08 01:03:15.374902Z] Running run-tests step. 17:03:15 INFO - Running pre-action listener: _resource_record_pre_action 17:03:15 INFO - Running main action method: run_tests 17:03:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:15 INFO - 2016-12-07 17:03:15.389 screenresolution[1670:7870] starting screenresolution argv=screenresolution get 17:03:15 INFO - 2016-12-07 17:03:15.391 screenresolution[1670:7870] Display 0: 1600x1200x32@60 17:03:15 INFO - 2016-12-07 17:03:15.397 screenresolution[1671:7872] starting screenresolution argv=screenresolution list 17:03:15 INFO - Available Modes on Display 0 17:03:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:15 INFO - Intel Iris: 17:03:15 INFO - Chipset Model: Intel Iris 17:03:15 INFO - Type: GPU 17:03:15 INFO - Bus: Built-In 17:03:15 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:15 INFO - Vendor: Intel (0x8086) 17:03:15 INFO - Device ID: 0x0a2e 17:03:15 INFO - Revision ID: 0x0009 17:03:15 INFO - Displays: 17:03:15 INFO - Display: 17:03:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:15 INFO - Display Serial Number: 1600x1200 60 17:03:15 INFO - Main Display: Yes 17:03:15 INFO - Mirror: Off 17:03:15 INFO - Online: Yes 17:03:15 INFO - Rotation: Supported 17:03:15 INFO - Return code: 0 17:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:03:15 INFO - Python 2.7.3 17:03:15 INFO - Return code: 0 17:03:15 INFO - grabbing minidump binary from tooltool 17:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:15 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 0x10cad5d30>, '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 0x10c93cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cacdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:03:15 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 17:03:15 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 17:03:15 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 17:03:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:03:15 INFO - Return code: 0 17:03:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:03:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:03:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:03:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:03:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:03:15 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/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0278', '--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 17:03:15 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/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0278 --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 17:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X5RkLbnLUD/Render', 17:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:15 INFO - 'HOME': '/Users/cltbld', 17:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:15 INFO - 'LOGNAME': 'cltbld', 17:03:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:03:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:15 INFO - 'MOZ_NO_REMOTE': '1', 17:03:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:03:15 INFO - 'NO_EM_RESTART': '1', 17:03:15 INFO - 'PAGER': '/bin/cat', 17:03:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:15 INFO - 'PWD': '/builds/slave/test', 17:03:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:15 INFO - 'SHELL': '/bin/bash', 17:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dynSn6c4GI/Listeners', 17:03:15 INFO - 'TMPDIR': '/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/', 17:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:15 INFO - 'USER': 'cltbld', 17:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:15 INFO - 'XPC_FLAGS': '0x0', 17:03:15 INFO - 'XPC_SERVICE_NAME': '0', 17:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:15 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/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0278', '--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 17:03:15 INFO - mozversion application_buildid: 20161207150238 17:03:15 INFO - mozversion application_changeset: b5e0e2b971bd9cc74e8671f89035902e821251e6 17:03:15 INFO - mozversion application_display_name: Nightly 17:03:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:03:15 INFO - mozversion application_name: Firefox 17:03:15 INFO - mozversion application_remotingname: firefox 17:03:15 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 17:03:15 INFO - mozversion application_vendor: Mozilla 17:03:15 INFO - mozversion application_version: 53.0a1 17:03:15 INFO - mozversion platform_buildid: 20161207150238 17:03:15 INFO - mozversion platform_changeset: b5e0e2b971bd9cc74e8671f89035902e821251e6 17:03:15 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 17:03:15 INFO - mozversion platform_version: 53.0a1 17:03:15 INFO - using testdate: 1481158995 17:03:15 INFO - actual date: 1481158995 17:03:15 INFO - starting webserver on 'localhost:49249' 17:03:15 INFO - SUITE-START | Running 2 tests 17:03:15 INFO - TEST-START | dromaeo_css 17:03:15 INFO - operating with platform_type : mac_ 17:03:15 INFO - Initialising browser for dromaeo_css test... 17:03:15 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpVeHfdD/profile http://localhost:49249/getInfo.html) 17:03:19 INFO - PROCESS | 1681 | 2016-12-07 17:03:19.227 plugin-container[1684:8047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 17:03:19 INFO - PROCESS | 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:03:19 INFO - PROCESS | 1681 | 2016-12-07 17:03:19.230 plugin-container[1684:8047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1b, name = 'com.apple.CFPasteboardClient' 17:03:19 INFO - PROCESS | 1681 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:03:19 INFO - PROCESS | 1681 | 2016-12-07 17:03:19.231 plugin-container[1684:8047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:03:19 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 17:03:19 INFO - PROCESS | 1681 | colorDepth:24 17:03:19 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 17:03:19 INFO - PROCESS | 1681 | __metrics 17:03:19 ERROR - PROCESS | 1681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 17:03:20 INFO - TEST-INFO | 1681: exit 0 17:03:20 INFO - Browser initialized. 17:03:20 INFO - Running cycle 1/1 for dromaeo_css test... 17:03:20 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmpVeHfdD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:03:23 INFO - PROCESS | 1685 | 2016-12-07 17:03:23.035 plugin-container[1686:8168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 17:03:23 INFO - PROCESS | 1685 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:03:23 INFO - PROCESS | 1685 | 2016-12-07 17:03:23.037 plugin-container[1686:8168] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 17:03:23 INFO - PROCESS | 1685 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:03:23 INFO - PROCESS | 1685 | 2016-12-07 17:03:23.037 plugin-container[1686:8168] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:03:32 INFO - PROCESS | 1685 | RSS: Main: 241659904 17:03:32 INFO - PROCESS | 1685 | 17:04:22 INFO - PROCESS | 1685 | 1481159062057 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] 17:06:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 17:06:37 INFO - PROCESS | 1685 | RSS: Main: 250462208 17:06:37 INFO - PROCESS | 1685 | 17:09:40 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 17:09:40 INFO - PROCESS | 1685 | RSS: Main: 263077888 17:09:40 INFO - PROCESS | 1685 | 17:12:43 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 17:12:43 INFO - PROCESS | 1685 | RSS: Main: 267431936 17:12:43 INFO - PROCESS | 1685 | 17:15:45 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 17:15:46 INFO - PROCESS | 1685 | RSS: Main: 266977280 17:15:46 INFO - PROCESS | 1685 | 17:18:49 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 17:18:49 INFO - PROCESS | 1685 | RSS: Main: 271417344 17:18:49 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 17:21:52 INFO - PROCESS | 1685 | RSS: Main: 274030592 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | __start_tp_report 17:21:52 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 17:21:52 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 17:21:52 INFO - PROCESS | 1685 | |i|pagename|runs| 17:21:52 INFO - PROCESS | 1685 | |0;dojo.html;2868.8995885144022;3375.848086836092;3382.6109997350295;3392.7964322140674;3422.640622734613;4618.538146185379;5616.466435688613;5781.4507621775965;5787.56593255506;5807.796727114552;5631.239782629344;5706.28671416073;5721.427857214277;5744.569157313197;5796.188533605298;5235.267062611228;5258.421573626917;5296.92054619181;5314.521693047625;5357.544608708265;5199.558037566811;5322.680639161644;5325.440828712987;5334.706591137486;5372.086745253297;5213.635045546795;5229.86925326868;5243.423223445437;5271.551918086967;5299.205119232127;5151.304573882516;5169.147090730025;5170.095233334151;5177.456367081459;5203.1414816555225;5111.616628752821;5122;5137.38351397831;5250.107481728115;5280.841574752763;4473.932891006638;5558.638688485235;5680;5734.913976290359;5743.770249190027;5188.8183913563225;6256.217972753406;6283.968580157116;6298;6343.397377249154;6200.565960382775;6201.906971395432;6202.162708034402;6232.9376706232815;6265.310815810257;4883.9511604883855;5848.152017957373;5871.236739223874;5897.823065308047;5929.258842644669;6267.905981410283;6276.403741644536;6292.622442653455;6306.274778400542;6309.905351419732;5462.371827239839;6087.269527656709;6110.266767987781;6114.236541168587;6142.846428839315;6146.631202127886;6150.846228844314;6237.968810156011;6259.342769009279;6292.402221788922;6235.158253635792;6265.592736472115;6274.780382686584;6279.120923070774;6299.748010079637;6116.214037636738;6147.016477363601;6188.44304012641;6199.721012554446;6310.305866354697;4948.554630083309;4982.7259500727805;4991.625628077836;5010.874728131753;5011.323471331397;4427.22523558376;4450.06548624786;4465.866024019285;4473.418455600751;4506.504284528699;4694.967107236403;5588.888222235614;5846.766129354778;5893.616914900518;5986.5809393342915;6193.256809182927;6237.688115594202;6269.905951410732;6275.3724627537795;6298.960671489244;5977;6011.398860114041;6017.578769486094;6025.608335458269;6026.457618814327;5439;5441.075017247077;5458.317710286215;5483.177523371526;5490.972545137329;6154.353792851865;6190.359554717913;6212.689365531615;6244.250689917239;6265.749370025148;6231.906521402092;6252.656103914328;6261.3112557617715;6272.529560282906;6288.937110628835;6228.532860035606;6250.49996000328;6273.74905003813;6282.089097080989;6298.464630506275;6092.177556029879;6125.082842094198;6182.350853160354;6250.812475625738;6270.2475702915945;6220.191375121386;6240.843978900564;6254.749810007549;6261.906071408841;6286.622802631857;6182.443580077815;6193.969030154821;6198.690065496798;6217.906731399123;6251.031090181029;5842.766289348378;5899.351071382231;6167.352427994996;6200.255969283715;6214.5960512566535;4472.508024117279;4907.165781817171;5685.175649530877;5688.544916406595;5867.501262392744;6104.511639068775;6160.291566469913;6163.599366041193;6176.969115154396;6201.720922558406;5672.319321681424;5886.823395298148;5938.109283607493;5997.004483323181;5999.850003749942 17:21:52 INFO - PROCESS | 1685 | |1;ext.html;507.46969416959274;513.9588832893368;518.8910328830946;548.1284757235999;556.4157634483792;36006.81996590017;38563.61436385639;38615.80692096543;38679.80660096703;39184.21631567367;38793.224135517274;39301.80349098258;39354.8032259839;39432;39514.8024259879;9227.215686666641;9429.575669094873;10999.395033273167;11381.430928453545;11399.886001139965;11204.831927521094;11304.773904521904;11320.660380188607;11330.660080197566;11341.489632966495;11262.831057534144;11291.266067705574;11308.830367544453;11320.943395283053;11345.205835591512;11087.113030957544;11117.332960022424;11199.104071674246;11224.831627525513;11229.494672739665;11129.165312601588;11160.386178760165;11180.27328223671;11183.273087249303;11242.437878106144;37921.241575168344;38234.617653823385;38271.61728382709;38288.61711382878;38351.616483835365;3243.821589812583;3306.470964645669;3428.9142771430916;3477.1480987158307;3672.320620685155;29631.85184074066;29923.25191870199;30170.396592068035;30475.780968761224;30521;3162.193640621627;3205.2788122672214;3207.182168547029;3306.685864842836;3347.297067615804;449902.7504862455;454098;455428;455678.9999999967;456551;45556.31665524953;54839.45160548423;58389.83220335654;58523.12215316774;58614;61645.38354616396;61787.69106154441;61858;61924.38075619186;62061.689691551255;14035;15494.612634684223;15713.528594142193;15732.60668483297;15772;10840.891591083988;11774.882251177378;11857.644270671894;11997.820032699516;12284.508619655113;18091.819081809277;18289.07964663718;18311.816881831277;18322.450326490227;18360.54098647518;7423.9190735845095;7570.356519695888;7576.810579735551;7632.427567932427;7647.273509016746;828.5815663090222;955.6225291010012;999.480270259461;1017.5421060522795;1036.8237399642078;28224;29023.12930612085;29144.562831557545;29215.7078429213;29248.853755731085;819.7745619954441;1028.7016765138176;1028.8559601655775;1090.7327704712395;1123.8370436286693;14056.508022219174;14515.637109072357;14605.415783368546;14836.10983341003;14951;561594;640953;642497;644123;644877;52066.698332541986;52216;52405.7379713099;52482;52666.21000684993;1046.220565678559;1297.9026573007063;1310.748235435157;1335.0721248732232;1385.279654579613;25023;27056.864715676296;27730.306742331602;28194;28204;46125.53874461347;46948.7652561735;46983.53016469791;46996.7650161747;47130;557395;566075;567889;568459;568512;25209.49581008333;25488;26257.73742262553;26281.342966425993;27136.592951105355;44591.55408446004;44613.776931115135;45043;45740.771296143306;45933.540664594264;30858.228544286572;30886.845565772026;31128.066158015296;31658.683413166495;31936.68063319337;28471.857640711663;30235.395292094476;36496.45255321226;37704.43443348297;37840.053998650255;35742.285154297286;36029.63970360263;36717;36823;36873.81563092167;30554.08337749871;33208.33583328368;33445.498317525744;33538;33581.66418335883;23418.53162936766;24385.75614243906;24404.75595244096;25030.249092526527;25092.62361064622 17:21:52 INFO - PROCESS | 1685 | |2;jquery.html;348.280800147695;353.7099578345757;370.5201763715989;383.75631474013994;401.71478250442186;17766.933983960957;18231.908840455795;19007.83967343843;19404.90297548512;19569.51076223095;19138.808611913875;19562.60874782507;19670.901645491787;19861;19982.700259496083;5725.370209276965;5992.191054207691;6002.939970600303;6004.039353703409;6023.789167379143;5553.583481238903;5821.6507009579345;5891.145783861338;5910.645361278315;5935.643861368331;5901.996660567714;5923.763049478016;5929.644221346712;5932.495737862287;6080.908786368208;6374.426301632852;6443;6456.354364563506;6486.513511486657;6493.123428337185;4884.413870335582;5015.924761128586;5281.947180528223;5327.387350454708;5678.006348888969;12701.618951431472;12952.740945181138;12972.286524241163;12990.545330913372;13194.472221111144;2124.7875212478784;2178.6949827024227;2254.985256634505;2325.383773300077;2835.8865645374162;9806.509674516246;9863.161631261351;9965.20278377728;10414.3751374917;10430.478476076241;1480.755675313572;1703.4293511673604;1712.2808420463434;1780.1277374086733;1816.1645643004233;89048.55475722645;98691.50654246754;99327.50336248345;104425;116479;10943.671689849318;11220.877912208714;11480.368579728196;11704.47329870158;11887.762244755057;11873.525058997544;14437.422503099968;14476.565703028926;14498.565043048726;14510.347034383478;7220.736371135135;7322.853542929111;7911.920880791234;8041.155678653658;8076.515409075357;6907.447404207652;8089.514629122272;8200.302974247137;8285.917140828515;8340.124286949904;3771.981564977441;3774.094217387862;4392.407025051641;4639.724075879093;4640.350350950869;1810.3482746211353;1894.3748562974185;1946.2506934830192;1958.2754380879167;1958.872673276261;777.3198451355064;933.3233405780754;960.7790208252002;965.7006328038331;967.0909345215475;19328.033598320308;20181.39455816328;20646.212761277035;20659.79340206579;20661.276855309992;882.1531329923346;905.5743800413795;921.9723408297762;927.7634203278255;945.3004776465366;8479.618417171243;8939.776505587282;9203.585838637277;10882.836757448644;10922.672319830259;402033;538912;545533;549413.2529337328;558069;101687;102230;123967;127757;133721.29647683454;844.1811442900377;911.3438324406417;928.8049509603059;973.8344481438172;974.1524873360222;18757.624847502702;19023.334183304072;19191.424257272305;19220.519487012938;19249.903750481157;44862;52939.470605293456;54614.18078728743;55132.72433637806;56923.22292872655;75556.62221688856;79601.60199198966;80809;81056;83096;7110.5022648414115;7776.794596837499;8030.759077227693;8123.4313598047565;8166.550839703636;10100.393976361442;12443.564475243324;12579.182353147016;12741.362931853555;12912.16070955385;10646.041856232976;10765.977232162932;10836.349819010884;11010.119190464904;11096.667099987013;8771.429857059271;10297.867234604038;10661.09380702649;10766.515506802056;10978.615748448767;9020.86468702983;10646.988536089058;10954.671359859218;11199.552017919192;11426.94286528562;5790.826275211751;6001.849953751192;7058.71765129389;7133.85732285341;7969.481983671043;6441.935580644134;10635.20235982289;10850.620228291971;10882.455877206266;10969.50637221343 17:21:52 INFO - PROCESS | 1685 | |3;mootools.html;1121.1871393239899;1185.7509922916188;1380.503018913191;1472.2050092949808;1756.1921516102593;6839.726410943563;7193.712251509946;7508.023956885597;7742.612869356538;8346.248837604615;7910.723124690637;8168.550729709864;8170.673173073084;8177.4684645498;8195.91804081959;2235.1282999630157;2295.816334693229;2300.643400272952;2303.6314189729646;2304.0438218139534;2042.5404284036122;2117.4812171018043;2275.1695631094613;2302.435903203709;2324.500232450023;2221.178633520514;2286.531261091472;2289.381866895937;2308.6537019447132;2312.398776318161;1901.8003109673493;2279.8290128240283;2302.6085565453745;2303.7350704668925;2315.6873822033976;2257.9209727659454;2296.150424342987;2326.755690652474;2331.1724337859987;2337.3805941425535;8172.591370431454;8175.877361839577;8258.917410825876;8263.462874913112;8288.958555207246;5032.672876263031;5203.583713302926;5205.843824685266;5209.921851172236;5221.503957124067;3419.897403077912;3481.651834816523;3498.6676265754713;3510.4909788080586;3563.3051554946796;1029.217794476196;1040.859483969662;1071.0628200324716;1079.1690398393193;1081.2701426537055;9125;10593.099586535154;10613.9469302653;10674.359538427796;10706.571737130505;4366.71616344943;4555.681102322806;4615;4636.049609829976;4667.8366257181515;9351.906480935104;9371.812563748823;9415.529223538795;9452.8582071269;9458.337916345927;5178.223266509978;5182.24857395683;5197.29836472079;5211.530962213419;5214.270002199695;3403.9149021274666;4562.950521380101;4677.017826256524;4712.31671407644;4742.099001189763;3056.617922759655;3116.205367631285;3130.7808453408497;3648.7263455240964;3707.295613833363;1044.8641676581997;1162.959296424638;1173.2960223865612;1186.8991135753386;1209.4013463335705;739.988900166498;792.8731402975611;822.3092602214169;875.2429148786381;909.7998440343114;5394.514493695508;5436.483534064337;5470.823772888848;5484.149956756709;5512.6692398456225;551.923748690056;934.6868798952427;977.6920270114891;1014.3305418423805;1015.1624909449733;3114.844257787147;3733.010752150628;3738.2710371477847;3824.617538246209;3850.9037274068373;9715.70852874415;9920.355176913477;10011.64959226438;10028.899711002943;10065.949670251603;9430.575624096931;9981.750456238653;10018.3488073275;10036.698899033041;10043.598256069676;7400.407967362598;7682.423818213656;7694.961525192338;7706.383489320953;7714.6914123434435;5581.720913954205;5640.435956404327;5672.1440804485255;5860.501857342174;5917.230760001173;9212.30907681913;9286;9305.488198149167;9334.579943902676;9343.906560934576;9178.449293042439;9279.489628070522;9400.952995235253;9446.669366572138;9485.336026478357;6386.90419643715;6855.965720171367;7045.929540704732;7120.928790712027;7216.386607138452;7664.195259497673;7767.6116194188935;7774.766756997299;7778.533288002738;7779.260970207822;6914.204866440223;7043.436525078084;7087;7136.250693677089;7138.964305178441;6713.697883595152;6803.387695107464;6878.3465570770695;6935.757248496278;7000.649967501503;6319.178506794087;6522.804315870531;6583.374579414948;6602.636854973022;6664.600123992576;4749.976250118727;4805.8077676892535;4815.927761083516;4864.829730959399;4905.9018819624125;4721.622270218443;4747.572718455217;4826.227803551415;4910.8281210157475;4977.875553111201 17:21:52 INFO - PROCESS | 1685 | |4;prototype.html;295.08670664294016;297.0568445186533;303.92857678445574;306.3077444974358;310.0357886971518;4811.927821082678;5426.728663566825;5549.420623697718;5633.661980281186;5653.547716182706;4993.475685053071;5008.874778130539;5376.220448035033;5501.231656315426;5536.640118392302;3113.2216945763557;3725.8077415227003;4207.726497777635;4216.093539888923;4243.72415792974;3511.789292642437;3609.5668519777664;3868.845246190149;4015.15302960637;4190.455240818689;3897.5517815451303;4095.324271495215;4111.280525907969;4111.650509706678;4115.567865374138;3458.965410345902;3563.8773786257375;3773;4114.506259248909;4140.358244472111;3358.5390355195323;3563.572371315433;3566.2154326048226;3590.1922067534856;3592.964070359289;4682.391289132425;5003.599712023066;5310.265213415056;5371;5386.757595908193;3937.598065180238;4616.653750968657;4707.38803955487;4709.529047095298;4821;5750.65496070233;5812.9709351452975;5822.563307751935;5875.353711091777;5881.6471011739;3763.9435408468894;3839.2513459875336;3864.246471937973;3880.185161116169;3891.3384724596885;6022.578419510636;6060.848478788022;6063.9090413643835;6076.756929722806;6085.178500902366;3648.835802388846;3937.7046721496004;3949.3681011038097;3955.861544845917;3965.0285680008583;3828.9617103828605;3844.6539811417106;3967.4247234150885;3991.7226487523712;4044.2315959967514;4618.261078227468;4643.512431194744;4655.6973796703105;4660.366892705611;4672.532746725368;5100.744962751847;5864.9413505864395;5867.941320586825;5886.587938844325;5887.411258874078;1186.068935885343;1225.6139316115396;1267.5563552756646;1287.5557932513268;1294.6569159172639;722.1154086244309;744.5309455043397;821.9013718353835;876.5529579914289;881.7927786970057;4368.204349216504;4566.346922462017;4609.884752881205;5089.9745501273;5158.458361872024;4520.50274469808;4646.558576935186;4960.767654555464;5475.44096337376;5762.135679648086;4017.035911381306;4054.722762329857;4846.636502262344;5050.217216331474;5069.188929771292;2735.1657744387753;2756.043209321364;2979.1956171833917;2990.4617168909804;2994.7903646744517;5827.242458480455;5850.9414905851245;5888.146218798251;5913.852153696192;5927.73325200367;4706.435227772689;5326.573874090063;5518.91721624176;5657.745401456944;5808.448197421239;5051.848444546596;5184.611154163453;5353.812616558402;5577.9163312550345;5851.9173952818865;4646.141543382539;5680.71596420169;5694.6367257587635;5742.626729262584;5746.195532625519;4495.685302028826;5121.385433747825;5179.378474583074;5367.865803354791;5646.491815736439;5025.9644843838305;5151.82702011372;5513.724313784214;5747.885042299047;5854.282530593019;3754.5494540655295;3764.924701506009;3768.679662228741;3770.245950809794;3795.2599243148425;5381.13901775714;5539.41836107219;5636.2245892543715;5984.431479009487;6051;5565.30433695788;5630.155476678531;5754.798582049776;5786.652800831964;5925.4370834770625;5165.656929198361;5776.942230577641;5874.735636896436;5899.200743773019;5964.821055368346;4219.97890010548;4746.311784791117;5274.89450210986;5704.744956109649;5800.883982320245;5052.924206136838;5330.973345133405;5591.281797604547;5837.532997360286;5878.529717622666;5019.619604608762;5202.531772140525;5456.3977587120025;5664.235328230636;5719.799807006735 17:21:52 INFO - PROCESS | 1685 | |5;yui.html;622.5051084387911;676.8680107379063;688.6728803818183;747.6971826410304;767.5816679909449;13926.373313200906;14327.856721432783;14540.836733061356;16724.080175590338;17560.297588096462;15766.054036757803;15964.441244556443;16132.354705811782;16308.83691163088;16340.937839040454;795.0777098565665;817.648411183191;819.2258315891485;820.7783898347443;823.5141266652673;641.2241188162305;660.1747815230962;785.2657764989752;808.7250334886138;813.3005615170978;852.2031900173326;852.3990586636437;852.6674596907211;856.546030603781;857.6783706110209;845.4208866926144;846.9237768600825;848.6053984897021;849.9575021248975;851.5486792000188;615.7813976038486;619.2383368456778;621.6674079367561;632.2349956552531;632.7216024949008;12105.576304829288;14952.102873827605;15114.924425377803;15117.848821511863;15146.697066058727;13047.412866421033;13657.931710341483;13826.446942122304;14145.85854141466;14161.716765664733;10223.744406389826;11292.040176585;11824.940875295568;11934.761304773943;11959.162858599902;3396.643352447982;3574.8758792402914;3585.103724068983;3674.3018826423163;3775.9811200944096;114141;118765.13976885987;119243;121527;121657;9667.371620844622;10184.796304073878;10413.219008574388;10442.373457592568;10592.947035264928;15897.761533577006;17167;17658.381956631714;21451.78548214529;21813.67279490809;6796.626185559743;7717.807054823562;7738;7770.53376797394;7824.647890844925;4288.935665965013;4354.441999810386;4400.22995975703;4406.779661016936;4497.527759585262;3079.938401231963;3410.84651190697;3491.790492570403;3513.0690367052753;3528.435450327935;606.3269770554681;620.6059152438235;624.5815303746501;637.9744810207633;639.1850390751829;578.6528083150076;600.3126420248791;629.8756719256106;652.5595223224304;663.2472144116366;9211.124943130391;9307.06929307064;9436.386634868712;9529.571169297398;9773.383021790682;638.2596188421409;648.5427773419727;650.9032280607192;662.9337066293332;672.5090683800853;4463.977680111579;4595.632349412039;4599.494055653875;4629.745364005012;4632.72203667781;93421.53289233646;94416;95660.52169739107;98579;117703;88826.55586722025;90000.09999899917;90926.09073909308;91844.54077729702;94998;79553.60223198963;92184.53907730419;93894.5305273483;94435.11129777366;95591;8150.347972162332;9786.1192492676;9797;9913.058259465628;10074.798504029732;51537.7423112882;51748.74125629348;56325.155122672375;58415.70792146012;58675.4132458687;72060.63969680118;82066;82067.17932820834;83036.58481707553;83915;11425.942870285595;11505.539778409106;12811.743765124831;12915.143850145105;13580.320983950565;13971.441142354191;14620;14767.778483322974;15054.548363549111;15183.392664293304;11180.720481988015;13679.589612311647;14146.027050928864;14216.289185540474;14277.214753188258;13946;14158.221297828306;14182.290885455892;14231.430742770588;14270.500532481314;13451.394687238902;13899.72200555963;14087.718245635235;14339.426422942965;14444.638884027983;10661.788464310524;11256.887431125911;11346.546138154381;11420.717231038361;11563.479643415898;9181.21959633412;10674.412907290174;11487.48306326234;11555.537778489102;11768.764624707286 17:21:52 INFO - PROCESS | 1685 | __end_tp_report 17:21:52 INFO - PROCESS | 1685 | __start_cc_report 17:21:52 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,362 17:21:52 INFO - PROCESS | 1685 | __end_cc_report 17:21:52 INFO - PROCESS | 1685 | __startTimestamp1481160112758__endTimestamp 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | ------- Summary: start ------- 17:21:52 INFO - PROCESS | 1685 | Number of tests: 6 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | [#0] dojo.html Cycles:165 Average:5719.27 Median:5986.58 stddev:673.56 (11.3%) stddev-sans-first:637.43 17:21:52 INFO - PROCESS | 1685 | Values: 2868.9 3375.8 3382.6 3392.8 3422.6 4618.5 5616.5 5781.5 5787.6 5807.8 5631.2 5706.3 5721.4 5744.6 5796.2 5235.3 5258.4 5296.9 5314.5 5357.5 5199.6 5322.7 5325.4 5334.7 5372.1 5213.6 5229.9 5243.4 5271.6 5299.2 5151.3 5169.1 5170.1 5177.5 5203.1 5111.6 5122.0 5137.4 5250.1 5280.8 4473.9 5558.6 5680.0 5734.9 5743.8 5188.8 6256.2 6284.0 6298.0 6343.4 6200.6 6201.9 6202.2 6232.9 6265.3 4884.0 5848.2 5871.2 5897.8 5929.3 6267.9 6276.4 6292.6 6306.3 6309.9 5462.4 6087.3 6110.3 6114.2 6142.8 6146.6 6150.8 6238.0 6259.3 6292.4 6235.2 6265.6 6274.8 6279.1 6299.7 6116.2 6147.0 6188.4 6199.7 6310.3 4948.6 4982.7 4991.6 5010.9 5011.3 4427.2 4450.1 4465.9 4473.4 4506.5 4695.0 5588.9 5846.8 5893.6 5986.6 6193.3 6237.7 6269.9 6275.4 6299.0 5977.0 6011.4 6017.6 6025.6 6026.5 5439.0 5441.1 5458.3 5483.2 5491.0 6154.4 6190.4 6212.7 6244.3 6265.7 6231.9 6252.7 6261.3 6272.5 6288.9 6228.5 6250.5 6273.7 6282.1 6298.5 6092.2 6125.1 6182.4 6250.8 6270.2 6220.2 6240.8 6254.7 6261.9 6286.6 6182.4 6194.0 6198.7 6217.9 6251.0 5842.8 5899.4 6167.4 6200.3 6214.6 4472.5 4907.2 5685.2 5688.5 5867.5 6104.5 6160.3 6163.6 6177.0 6201.7 5672.3 5886.8 5938.1 5997.0 5999.9 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | [#1] ext.html Cycles:180 Average:67472.99 Median:3275.15 stddev:148058.37 (4520.7%) stddev-sans-first:148388.34 17:21:52 INFO - PROCESS | 1685 | Values: 507.5 514.0 518.9 548.1 556.4 36006.8 38563.6 38615.8 38679.8 39184.2 38793.2 39301.8 39354.8 39432.0 39514.8 9227.2 9429.6 10999.4 11381.4 11399.9 11204.8 11304.8 11320.7 11330.7 11341.5 11262.8 11291.3 11308.8 11320.9 11345.2 11087.1 11117.3 11199.1 11224.8 11229.5 11129.2 11160.4 11180.3 11183.3 11242.4 37921.2 38234.6 38271.6 38288.6 38351.6 3243.8 3306.5 3428.9 3477.1 3672.3 29631.9 29923.3 30170.4 30475.8 30521.0 3162.2 3205.3 3207.2 3306.7 3347.3 449902.8 454098.0 455428.0 455679.0 456551.0 45556.3 54839.5 58389.8 58523.1 58614.0 61645.4 61787.7 61858.0 61924.4 62061.7 14035.0 15494.6 15713.5 15732.6 15772.0 10840.9 11774.9 11857.6 11997.8 12284.5 18091.8 18289.1 18311.8 18322.5 18360.5 7423.9 7570.4 7576.8 7632.4 7647.3 828.6 955.6 999.5 1017.5 1036.8 28224.0 29023.1 29144.6 29215.7 29248.9 819.8 1028.7 1028.9 1090.7 1123.8 14056.5 14515.6 14605.4 14836.1 14951.0 561594.0 640953.0 642497.0 644123.0 644877.0 52066.7 52216.0 52405.7 52482.0 52666.2 1046.2 1297.9 1310.7 1335.1 1385.3 25023.0 27056.9 27730.3 28194.0 28204.0 46125.5 46948.8 46983.5 46996.8 47130.0 557395.0 566075.0 567889.0 568459.0 568512.0 25209.5 25488.0 26257.7 26281.3 27136.6 44591.6 44613.8 45043.0 45740.8 45933.5 30858.2 30886.8 31128.1 31658.7 31936.7 28471.9 30235.4 36496.5 37704.4 37840.1 35742.3 36029.6 36717.0 36823.0 36873.8 30554.1 33208.3 33445.5 33538.0 33581.7 23418.5 24385.8 24404.8 25030.2 25092.6 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | [#2] jquery.html Cycles:180 Average:31503.88 Median:203212.70 stddev:87452.87 (43.0%) stddev-sans-first:87666.91 17:21:52 INFO - PROCESS | 1685 | Values: 348.3 353.7 370.5 383.8 401.7 17766.9 18231.9 19007.8 19404.9 19569.5 19138.8 19562.6 19670.9 19861.0 19982.7 5725.4 5992.2 6002.9 6004.0 6023.8 5553.6 5821.7 5891.1 5910.6 5935.6 5902.0 5923.8 5929.6 5932.5 6080.9 6374.4 6443.0 6456.4 6486.5 6493.1 4884.4 5015.9 5281.9 5327.4 5678.0 12701.6 12952.7 12972.3 12990.5 13194.5 2124.8 2178.7 2255.0 2325.4 2835.9 9806.5 9863.2 9965.2 10414.4 10430.5 1480.8 1703.4 1712.3 1780.1 1816.2 89048.6 98691.5 99327.5 104425.0 116479.0 10943.7 11220.9 11480.4 11704.5 11887.8 11873.5 14437.4 14476.6 14498.6 14510.3 7220.7 7322.9 7911.9 8041.2 8076.5 6907.4 8089.5 8200.3 8285.9 8340.1 3772.0 3774.1 4392.4 4639.7 4640.4 1810.3 1894.4 1946.3 1958.3 1958.9 777.3 933.3 960.8 965.7 967.1 19328.0 20181.4 20646.2 20659.8 20661.3 882.2 905.6 922.0 927.8 945.3 8479.6 8939.8 9203.6 10882.8 10922.7 402033.0 538912.0 545533.0 549413.3 558069.0 101687.0 102230.0 123967.0 127757.0 133721.3 844.2 911.3 928.8 973.8 974.2 18757.6 19023.3 19191.4 19220.5 19249.9 44862.0 52939.5 54614.2 55132.7 56923.2 75556.6 79601.6 80809.0 81056.0 83096.0 7110.5 7776.8 8030.8 8123.4 8166.6 10100.4 12443.6 12579.2 12741.4 12912.2 10646.0 10766.0 10836.3 11010.1 11096.7 8771.4 10297.9 10661.1 10766.5 10978.6 9020.9 10647.0 10954.7 11199.6 11426.9 5790.8 6001.8 7058.7 7133.9 7969.5 6441.9 10635.2 10850.6 10882.5 10969.5 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | [#3] mootools.html Cycles:180 Average:5340.71 Median:5200.44 stddev:2940.48 (56.5%) stddev-sans-first:2931.63 17:21:52 INFO - PROCESS | 1685 | Values: 1121.2 1185.8 1380.5 1472.2 1756.2 6839.7 7193.7 7508.0 7742.6 8346.2 7910.7 8168.6 8170.7 8177.5 8195.9 2235.1 2295.8 2300.6 2303.6 2304.0 2042.5 2117.5 2275.2 2302.4 2324.5 2221.2 2286.5 2289.4 2308.7 2312.4 1901.8 2279.8 2302.6 2303.7 2315.7 2257.9 2296.2 2326.8 2331.2 2337.4 8172.6 8175.9 8258.9 8263.5 8289.0 5032.7 5203.6 5205.8 5209.9 5221.5 3419.9 3481.7 3498.7 3510.5 3563.3 1029.2 1040.9 1071.1 1079.2 1081.3 9125.0 10593.1 10613.9 10674.4 10706.6 4366.7 4555.7 4615.0 4636.0 4667.8 9351.9 9371.8 9415.5 9452.9 9458.3 5178.2 5182.2 5197.3 5211.5 5214.3 3403.9 4563.0 4677.0 4712.3 4742.1 3056.6 3116.2 3130.8 3648.7 3707.3 1044.9 1163.0 1173.3 1186.9 1209.4 740.0 792.9 822.3 875.2 909.8 5394.5 5436.5 5470.8 5484.1 5512.7 551.9 934.7 977.7 1014.3 1015.2 3114.8 3733.0 3738.3 3824.6 3850.9 9715.7 9920.4 10011.6 10028.9 10065.9 9430.6 9981.8 10018.3 10036.7 10043.6 7400.4 7682.4 7695.0 7706.4 7714.7 5581.7 5640.4 5672.1 5860.5 5917.2 9212.3 9286.0 9305.5 9334.6 9343.9 9178.4 9279.5 9401.0 9446.7 9485.3 6386.9 6856.0 7045.9 7120.9 7216.4 7664.2 7767.6 7774.8 7778.5 7779.3 6914.2 7043.4 7087.0 7136.3 7139.0 6713.7 6803.4 6878.3 6935.8 7000.6 6319.2 6522.8 6583.4 6602.6 6664.6 4750.0 4805.8 4815.9 4864.8 4905.9 4721.6 4747.6 4826.2 4910.8 4977.9 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | [#4] prototype.html Cycles:180 Average:4529.45 Median:5038.09 stddev:1416.95 (28.1%) stddev-sans-first:1384.82 17:21:52 INFO - PROCESS | 1685 | Values: 295.1 297.1 303.9 306.3 310.0 4811.9 5426.7 5549.4 5633.7 5653.5 4993.5 5008.9 5376.2 5501.2 5536.6 3113.2 3725.8 4207.7 4216.1 4243.7 3511.8 3609.6 3868.8 4015.2 4190.5 3897.6 4095.3 4111.3 4111.7 4115.6 3459.0 3563.9 3773.0 4114.5 4140.4 3358.5 3563.6 3566.2 3590.2 3593.0 4682.4 5003.6 5310.3 5371.0 5386.8 3937.6 4616.7 4707.4 4709.5 4821.0 5750.7 5813.0 5822.6 5875.4 5881.6 3763.9 3839.3 3864.2 3880.2 3891.3 6022.6 6060.8 6063.9 6076.8 6085.2 3648.8 3937.7 3949.4 3955.9 3965.0 3829.0 3844.7 3967.4 3991.7 4044.2 4618.3 4643.5 4655.7 4660.4 4672.5 5100.7 5864.9 5867.9 5886.6 5887.4 1186.1 1225.6 1267.6 1287.6 1294.7 722.1 744.5 821.9 876.6 881.8 4368.2 4566.3 4609.9 5090.0 5158.5 4520.5 4646.6 4960.8 5475.4 5762.1 4017.0 4054.7 4846.6 5050.2 5069.2 2735.2 2756.0 2979.2 2990.5 2994.8 5827.2 5850.9 5888.1 5913.9 5927.7 4706.4 5326.6 5518.9 5657.7 5808.4 5051.8 5184.6 5353.8 5577.9 5851.9 4646.1 5680.7 5694.6 5742.6 5746.2 4495.7 5121.4 5179.4 5367.9 5646.5 5026.0 5151.8 5513.7 5747.9 5854.3 3754.5 3764.9 3768.7 3770.2 3795.3 5381.1 5539.4 5636.2 5984.4 6051.0 5565.3 5630.2 5754.8 5786.7 5925.4 5165.7 5776.9 5874.7 5899.2 5964.8 4220.0 4746.3 5274.9 5704.7 5800.9 5052.9 5331.0 5591.3 5837.5 5878.5 5019.6 5202.5 5456.4 5664.2 5719.8 17:21:52 INFO - PROCESS | 1685 | 17:21:52 INFO - PROCESS | 1685 | [#5] yui.html Cycles:180 Average:21605.83 Median:4546.58 stddev:32032.05 (704.5%) stddev-sans-first:32083.16 17:21:52 INFO - PROCESS | 1685 | Values: 622.5 676.9 688.7 747.7 767.6 13926.4 14327.9 14540.8 16724.1 17560.3 15766.1 15964.4 16132.4 16308.8 16340.9 795.1 817.6 819.2 820.8 823.5 641.2 660.2 785.3 808.7 813.3 852.2 852.4 852.7 856.5 857.7 845.4 846.9 848.6 850.0 851.5 615.8 619.2 621.7 632.2 632.7 12105.6 14952.1 15114.9 15117.8 15146.7 13047.4 13657.9 13826.4 14145.9 14161.7 10223.7 11292.0 11824.9 11934.8 11959.2 3396.6 3574.9 3585.1 3674.3 3776.0 114141.0 118765.1 119243.0 121527.0 121657.0 9667.4 10184.8 10413.2 10442.4 10592.9 15897.8 17167.0 17658.4 21451.8 21813.7 6796.6 7717.8 7738.0 7770.5 7824.6 4288.9 4354.4 4400.2 4406.8 4497.5 3079.9 3410.8 3491.8 3513.1 3528.4 606.3 620.6 624.6 638.0 639.2 578.7 600.3 629.9 652.6 663.2 9211.1 9307.1 9436.4 9529.6 9773.4 638.3 648.5 650.9 662.9 672.5 4464.0 4595.6 4599.5 4629.7 4632.7 93421.5 94416.0 95660.5 98579.0 117703.0 88826.6 90000.1 90926.1 91844.5 94998.0 79553.6 92184.5 93894.5 94435.1 95591.0 8150.3 9786.1 9797.0 9913.1 10074.8 51537.7 51748.7 56325.2 58415.7 58675.4 72060.6 82066.0 82067.2 83036.6 83915.0 11425.9 11505.5 12811.7 12915.1 13580.3 13971.4 14620.0 14767.8 15054.5 15183.4 11180.7 13679.6 14146.0 14216.3 14277.2 13946.0 14158.2 14182.3 14231.4 14270.5 13451.4 13899.7 14087.7 14339.4 14444.6 10661.8 11256.9 11346.5 11420.7 11563.5 9181.2 10674.4 11487.5 11555.5 11768.8 17:21:52 INFO - PROCESS | 1685 | -------- Summary: end -------- 17:21:52 INFO - PROCESS | 1685 | 17:21:53 INFO - TEST-INFO | 1685: exit 0 17:21:53 INFO - TEST-OK | dromaeo_css | took 1117543ms 17:21:53 INFO - TEST-START | kraken 17:21:53 INFO - operating with platform_type : mac_ 17:21:53 INFO - Initialising browser for kraken test... 17:21:53 INFO - TEST-INFO | started process 1803 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmp0AgmYQ/profile http://localhost:49249/getInfo.html) 17:21:56 INFO - PROCESS | 1803 | __metrics Screen width/height:1600/1200 17:21:56 INFO - PROCESS | 1803 | colorDepth:24 17:21:56 INFO - PROCESS | 1803 | Browser inner width/height: 1024/689 17:21:56 INFO - PROCESS | 1803 | __metrics 17:21:56 INFO - PROCESS | 1803 | 2016-12-07 17:21:56.603 plugin-container[1804:10513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 17:21:56 INFO - PROCESS | 1803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:56 INFO - PROCESS | 1803 | 2016-12-07 17:21:56.607 plugin-container[1804:10513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 17:21:56 INFO - PROCESS | 1803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:21:56 INFO - PROCESS | 1803 | 2016-12-07 17:21:56.608 plugin-container[1804:10513] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:21:56 ERROR - PROCESS | 1803 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 17:21:57 INFO - TEST-INFO | 1803: exit 0 17:21:57 INFO - Browser initialized. 17:21:57 INFO - Running cycle 1/1 for kraken test... 17:21:57 INFO - TEST-INFO | started process 1806 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/T/tmp0AgmYQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:22:00 INFO - PROCESS | 1806 | 2016-12-07 17:22:00.308 plugin-container[1807:10626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 17:22:00 INFO - PROCESS | 1806 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:22:00 INFO - PROCESS | 1806 | 2016-12-07 17:22:00.309 plugin-container[1807:10626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 17:22:00 INFO - PROCESS | 1806 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:22:00 INFO - PROCESS | 1806 | 2016-12-07 17:22:00.310 plugin-container[1807:10626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:22:09 INFO - PROCESS | 1806 | RSS: Main: 241672192 17:22:09 INFO - PROCESS | 1806 | 17:22:12 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 17:22:13 INFO - PROCESS | 1806 | RSS: Main: 254259200 17:22:13 INFO - PROCESS | 1806 | 17:22:16 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 17:22:17 INFO - PROCESS | 1806 | RSS: Main: 261726208 17:22:17 INFO - PROCESS | 1806 | 17:22:20 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 17:22:20 INFO - PROCESS | 1806 | RSS: Main: 264318976 17:22:20 INFO - PROCESS | 1806 | 17:22:22 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 17:22:23 INFO - PROCESS | 1806 | RSS: Main: 265773056 17:22:23 INFO - PROCESS | 1806 | 17:22:25 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:22:26 INFO - PROCESS | 1806 | RSS: Main: 267210752 17:22:26 INFO - PROCESS | 1806 | 17:22:28 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 17:22:29 INFO - PROCESS | 1806 | RSS: Main: 272973824 17:22:29 INFO - PROCESS | 1806 | 17:22:32 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 17:22:33 INFO - PROCESS | 1806 | RSS: Main: 272367616 17:22:33 INFO - PROCESS | 1806 | 17:22:36 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 17:22:36 INFO - PROCESS | 1806 | RSS: Main: 272879616 17:22:36 INFO - PROCESS | 1806 | 17:22:38 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:22:39 INFO - PROCESS | 1806 | RSS: Main: 271880192 17:22:39 INFO - PROCESS | 1806 | 17:22:40 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:22:41 INFO - PROCESS | 1806 | RSS: Main: 271998976 17:22:41 INFO - PROCESS | 1806 | 17:22:43 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:22:44 INFO - PROCESS | 1806 | RSS: Main: 271982592 17:22:44 INFO - PROCESS | 1806 | 17:22:47 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:22:48 INFO - PROCESS | 1806 | RSS: Main: 272179200 17:22:48 INFO - PROCESS | 1806 | 17:22:51 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:22:51 INFO - PROCESS | 1806 | RSS: Main: 272240640 17:22:51 INFO - PROCESS | 1806 | 17:22:53 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 17:22:54 INFO - PROCESS | 1806 | RSS: Main: 273129472 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | __start_tp_report 17:22:54 INFO - PROCESS | 1806 | _x_x_mozilla_page_load 17:22:54 INFO - PROCESS | 1806 | _x_x_mozilla_page_load_details 17:22:54 INFO - PROCESS | 1806 | |i|pagename|runs| 17:22:54 INFO - PROCESS | 1806 | |0;ai-astar;107;117;117;117;117;117;117;119;118;117 17:22:54 INFO - PROCESS | 1806 | |1;audio-beat-detection;141;117;117;155;122;122;118;117;141;111 17:22:54 INFO - PROCESS | 1806 | |2;audio-dft;199;214;207;199;200;199;195;196;201;191 17:22:54 INFO - PROCESS | 1806 | |3;audio-fft;84;57;62;82;62;60;61;60;69;61 17:22:54 INFO - PROCESS | 1806 | |4;audio-oscillator;90;71;85;73;70;77;109;99;88;81 17:22:54 INFO - PROCESS | 1806 | |5;imaging-gaussian-blur;92;89;91;89;91;87;100;104;79;115 17:22:54 INFO - PROCESS | 1806 | |6;imaging-darkroom;153;119;96;97;97;96;117;151;106;110 17:22:54 INFO - PROCESS | 1806 | |7;imaging-desaturate;94;95;92;92;102;93;93;92;92;110 17:22:54 INFO - PROCESS | 1806 | |8;json-parse-financial;55;56;57;59;84;64;58;59;59;58 17:22:54 INFO - PROCESS | 1806 | |9;json-stringify-tinderbox;71;74;67;86;78;72;70;70;70;71 17:22:54 INFO - PROCESS | 1806 | |10;stanford-crypto-aes;89;96;90;87;87;87;89;89;89;88 17:22:54 INFO - PROCESS | 1806 | |11;stanford-crypto-ccm;168;159;150;143;148;176;167;162;221;195 17:22:54 INFO - PROCESS | 1806 | |12;stanford-crypto-pbkdf2;176;175;175;227;234;175;177;174;174;176 17:22:54 INFO - PROCESS | 1806 | |13;stanford-crypto-sha256-iterative;70;70;67;65;66;85;65;84;80;91 17:22:54 INFO - PROCESS | 1806 | __end_tp_report 17:22:54 INFO - PROCESS | 1806 | __start_cc_report 17:22:54 INFO - PROCESS | 1806 | _x_x_mozilla_cycle_collect,925 17:22:54 INFO - PROCESS | 1806 | __end_cc_report 17:22:54 INFO - PROCESS | 1806 | __startTimestamp1481160174021__endTimestamp 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | ------- Summary: start ------- 17:22:54 INFO - PROCESS | 1806 | Number of tests: 14 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#0] ai-astar Cycles:10 Average:116.30 Median:117.00 stddev:3.33 (2.9%) stddev-sans-first:0.71 17:22:54 INFO - PROCESS | 1806 | Values: 107.0 117.0 117.0 117.0 117.0 117.0 117.0 119.0 118.0 117.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:122.00 stddev:14.36 (11.8%) stddev-sans-first:14.18 17:22:54 INFO - PROCESS | 1806 | Values: 141.0 117.0 117.0 155.0 122.0 122.0 118.0 117.0 141.0 111.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#2] audio-dft Cycles:10 Average:200.10 Median:199.50 stddev:6.42 (3.2%) stddev-sans-first:6.80 17:22:54 INFO - PROCESS | 1806 | Values: 199.0 214.0 207.0 199.0 200.0 199.0 195.0 196.0 201.0 191.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#3] audio-fft Cycles:10 Average:65.80 Median:62.00 stddev:9.57 (15.4%) stddev-sans-first:7.55 17:22:54 INFO - PROCESS | 1806 | Values: 84.0 57.0 62.0 82.0 62.0 60.0 61.0 60.0 69.0 61.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:83.00 stddev:12.68 (15.3%) stddev-sans-first:13.28 17:22:54 INFO - PROCESS | 1806 | Values: 90.0 71.0 85.0 73.0 70.0 77.0 109.0 99.0 88.0 81.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:89.00 stddev:10.12 (11.4%) stddev-sans-first:10.72 17:22:54 INFO - PROCESS | 1806 | Values: 92.0 89.0 91.0 89.0 91.0 87.0 100.0 104.0 79.0 115.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#6] imaging-darkroom Cycles:10 Average:114.20 Median:124.50 stddev:21.68 (17.4%) stddev-sans-first:17.88 17:22:54 INFO - PROCESS | 1806 | Values: 153.0 119.0 96.0 97.0 97.0 96.0 117.0 151.0 106.0 110.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:92.50 stddev:5.93 (6.4%) stddev-sans-first:6.26 17:22:54 INFO - PROCESS | 1806 | Values: 94.0 95.0 92.0 92.0 102.0 93.0 93.0 92.0 92.0 110.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:59.00 stddev:8.46 (14.3%) stddev-sans-first:8.71 17:22:54 INFO - PROCESS | 1806 | Values: 55.0 56.0 57.0 59.0 84.0 64.0 58.0 59.0 59.0 58.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:71.50 stddev:5.45 (7.6%) stddev-sans-first:5.73 17:22:54 INFO - PROCESS | 1806 | Values: 71.0 74.0 67.0 86.0 78.0 72.0 70.0 70.0 70.0 71.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:89.00 stddev:2.64 (3.0%) stddev-sans-first:2.80 17:22:54 INFO - PROCESS | 1806 | Values: 89.0 96.0 90.0 87.0 87.0 87.0 89.0 89.0 89.0 88.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#11] stanford-crypto-ccm Cycles:10 Average:168.90 Median:167.50 stddev:23.71 (14.2%) stddev-sans-first:25.15 17:22:54 INFO - PROCESS | 1806 | Values: 168.0 159.0 150.0 143.0 148.0 176.0 167.0 162.0 221.0 195.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.30 Median:176.00 stddev:23.37 (13.3%) stddev-sans-first:24.49 17:22:54 INFO - PROCESS | 1806 | Values: 176.0 175.0 175.0 227.0 234.0 175.0 177.0 174.0 174.0 176.0 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - PROCESS | 1806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:75.00 stddev:9.73 (13.0%) stddev-sans-first:10.20 17:22:54 INFO - PROCESS | 1806 | Values: 70.0 70.0 67.0 65.0 66.0 85.0 65.0 84.0 80.0 91.0 17:22:54 INFO - PROCESS | 1806 | -------- Summary: end -------- 17:22:54 INFO - PROCESS | 1806 | 17:22:54 INFO - TEST-INFO | 1806: exit 0 17:22:55 INFO - TEST-OK | kraken | took 61521ms 17:22:55 INFO - SUITE-END | took 1179s 17:22:55 INFO - Completed test suite (00:19:39) 17:22:55 INFO - javascript benchmark 17:22:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7123.636794338221, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2868.8995885144022, 3375.848086836092, 3382.6109997350295, 3392.7964322140674, 3422.640622734613, 4618.538146185379, 5616.466435688613, 5781.4507621775965, 5787.56593255506, 5807.796727114552, 5631.239782629344, 5706.28671416073, 5721.427857214277, 5744.569157313197, 5796.188533605298, 5235.267062611228, 5258.421573626917, 5296.92054619181, 5314.521693047625, 5357.544608708265, 5199.558037566811, 5322.680639161644, 5325.440828712987, 5334.706591137486, 5372.086745253297, 5213.635045546795, 5229.86925326868, 5243.423223445437, 5271.551918086967, 5299.205119232127, 5151.304573882516, 5169.147090730025, 5170.095233334151, 5177.456367081459, 5203.1414816555225, 5111.616628752821, 5122.0, 5137.38351397831, 5250.107481728115, 5280.841574752763, 4473.932891006638, 5558.638688485235, 5680.0, 5734.913976290359, 5743.770249190027, 5188.8183913563225, 6256.217972753406, 6283.968580157116, 6298.0, 6343.397377249154, 6200.565960382775, 6201.906971395432, 6202.162708034402, 6232.9376706232815, 6265.310815810257, 4883.9511604883855, 5848.152017957373, 5871.236739223874, 5897.823065308047, 5929.258842644669, 6267.905981410283, 6276.403741644536, 6292.622442653455, 6306.274778400542, 6309.905351419732, 5462.371827239839, 6087.269527656709, 6110.266767987781, 6114.236541168587, 6142.846428839315, 6146.631202127886, 6150.846228844314, 6237.968810156011, 6259.342769009279, 6292.402221788922, 6235.158253635792, 6265.592736472115, 6274.780382686584, 6279.120923070774, 6299.748010079637, 6116.214037636738, 6147.016477363601, 6188.44304012641, 6199.721012554446, 6310.305866354697, 4948.554630083309, 4982.7259500727805, 4991.625628077836, 5010.874728131753, 5011.323471331397, 4427.22523558376, 4450.06548624786, 4465.866024019285, 4473.418455600751, 4506.504284528699, 4694.967107236403, 5588.888222235614, 5846.766129354778, 5893.616914900518, 5986.5809393342915, 6193.256809182927, 6237.688115594202, 6269.905951410732, 6275.3724627537795, 6298.960671489244, 5977.0, 6011.398860114041, 6017.578769486094, 6025.608335458269, 6026.457618814327, 5439.0, 5441.075017247077, 5458.317710286215, 5483.177523371526, 5490.972545137329, 6154.353792851865, 6190.359554717913, 6212.689365531615, 6244.250689917239, 6265.749370025148, 6231.906521402092, 6252.656103914328, 6261.3112557617715, 6272.529560282906, 6288.937110628835, 6228.532860035606, 6250.49996000328, 6273.74905003813, 6282.089097080989, 6298.464630506275, 6092.177556029879, 6125.082842094198, 6182.350853160354, 6250.812475625738, 6270.2475702915945, 6220.191375121386, 6240.843978900564, 6254.749810007549, 6261.906071408841, 6286.622802631857, 6182.443580077815, 6193.969030154821, 6198.690065496798, 6217.906731399123, 6251.031090181029, 5842.766289348378, 5899.351071382231, 6167.352427994996, 6200.255969283715, 6214.5960512566535, 4472.508024117279, 4907.165781817171, 5685.175649530877, 5688.544916406595, 5867.501262392744, 6104.511639068775, 6160.291566469913, 6163.599366041193, 6176.969115154396, 6201.720922558406, 5672.319321681424, 5886.823395298148, 5938.109283607493, 5997.004483323181, 5999.850003749942], "value": 5676.86907855321, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.46969416959274, 513.9588832893368, 518.8910328830946, 548.1284757235999, 556.4157634483792, 36006.81996590017, 38563.61436385639, 38615.80692096543, 38679.80660096703, 39184.21631567367, 38793.224135517274, 39301.80349098258, 39354.8032259839, 39432.0, 39514.8024259879, 9227.215686666641, 9429.575669094873, 10999.395033273167, 11381.430928453545, 11399.886001139965, 11204.831927521094, 11304.773904521904, 11320.660380188607, 11330.660080197566, 11341.489632966495, 11262.831057534144, 11291.266067705574, 11308.830367544453, 11320.943395283053, 11345.205835591512, 11087.113030957544, 11117.332960022424, 11199.104071674246, 11224.831627525513, 11229.494672739665, 11129.165312601588, 11160.386178760165, 11180.27328223671, 11183.273087249303, 11242.437878106144, 37921.241575168344, 38234.617653823385, 38271.61728382709, 38288.61711382878, 38351.616483835365, 3243.821589812583, 3306.470964645669, 3428.9142771430916, 3477.1480987158307, 3672.320620685155, 29631.85184074066, 29923.25191870199, 30170.396592068035, 30475.780968761224, 30521.0, 3162.193640621627, 3205.2788122672214, 3207.182168547029, 3306.685864842836, 3347.297067615804, 449902.7504862455, 454098.0, 455428.0, 455678.9999999967, 456551.0, 45556.31665524953, 54839.45160548423, 58389.83220335654, 58523.12215316774, 58614.0, 61645.38354616396, 61787.69106154441, 61858.0, 61924.38075619186, 62061.689691551255, 14035.0, 15494.612634684223, 15713.528594142193, 15732.60668483297, 15772.0, 10840.891591083988, 11774.882251177378, 11857.644270671894, 11997.820032699516, 12284.508619655113, 18091.819081809277, 18289.07964663718, 18311.816881831277, 18322.450326490227, 18360.54098647518, 7423.9190735845095, 7570.356519695888, 7576.810579735551, 7632.427567932427, 7647.273509016746, 828.5815663090222, 955.6225291010012, 999.480270259461, 1017.5421060522795, 1036.8237399642078, 28224.0, 29023.12930612085, 29144.562831557545, 29215.7078429213, 29248.853755731085, 819.7745619954441, 1028.7016765138176, 1028.8559601655775, 1090.7327704712395, 1123.8370436286693, 14056.508022219174, 14515.637109072357, 14605.415783368546, 14836.10983341003, 14951.0, 561594.0, 640953.0, 642497.0, 644123.0, 644877.0, 52066.698332541986, 52216.0, 52405.7379713099, 52482.0, 52666.21000684993, 1046.220565678559, 1297.9026573007063, 1310.748235435157, 1335.0721248732232, 1385.279654579613, 25023.0, 27056.864715676296, 27730.306742331602, 28194.0, 28204.0, 46125.53874461347, 46948.7652561735, 46983.53016469791, 46996.7650161747, 47130.0, 557395.0, 566075.0, 567889.0, 568459.0, 568512.0, 25209.49581008333, 25488.0, 26257.73742262553, 26281.342966425993, 27136.592951105355, 44591.55408446004, 44613.776931115135, 45043.0, 45740.771296143306, 45933.540664594264, 30858.228544286572, 30886.845565772026, 31128.066158015296, 31658.683413166495, 31936.68063319337, 28471.857640711663, 30235.395292094476, 36496.45255321226, 37704.43443348297, 37840.053998650255, 35742.285154297286, 36029.63970360263, 36717.0, 36823.0, 36873.81563092167, 30554.08337749871, 33208.33583328368, 33445.498317525744, 33538.0, 33581.66418335883, 23418.53162936766, 24385.75614243906, 24404.75595244096, 25030.249092526527, 25092.62361064622], "value": 19557.618520871132, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.280800147695, 353.7099578345757, 370.5201763715989, 383.75631474013994, 401.71478250442186, 17766.933983960957, 18231.908840455795, 19007.83967343843, 19404.90297548512, 19569.51076223095, 19138.808611913875, 19562.60874782507, 19670.901645491787, 19861.0, 19982.700259496083, 5725.370209276965, 5992.191054207691, 6002.939970600303, 6004.039353703409, 6023.789167379143, 5553.583481238903, 5821.6507009579345, 5891.145783861338, 5910.645361278315, 5935.643861368331, 5901.996660567714, 5923.763049478016, 5929.644221346712, 5932.495737862287, 6080.908786368208, 6374.426301632852, 6443.0, 6456.354364563506, 6486.513511486657, 6493.123428337185, 4884.413870335582, 5015.924761128586, 5281.947180528223, 5327.387350454708, 5678.006348888969, 12701.618951431472, 12952.740945181138, 12972.286524241163, 12990.545330913372, 13194.472221111144, 2124.7875212478784, 2178.6949827024227, 2254.985256634505, 2325.383773300077, 2835.8865645374162, 9806.509674516246, 9863.161631261351, 9965.20278377728, 10414.3751374917, 10430.478476076241, 1480.755675313572, 1703.4293511673604, 1712.2808420463434, 1780.1277374086733, 1816.1645643004233, 89048.55475722645, 98691.50654246754, 99327.50336248345, 104425.0, 116479.0, 10943.671689849318, 11220.877912208714, 11480.368579728196, 11704.47329870158, 11887.762244755057, 11873.525058997544, 14437.422503099968, 14476.565703028926, 14498.565043048726, 14510.347034383478, 7220.736371135135, 7322.853542929111, 7911.920880791234, 8041.155678653658, 8076.515409075357, 6907.447404207652, 8089.514629122272, 8200.302974247137, 8285.917140828515, 8340.124286949904, 3771.981564977441, 3774.094217387862, 4392.407025051641, 4639.724075879093, 4640.350350950869, 1810.3482746211353, 1894.3748562974185, 1946.2506934830192, 1958.2754380879167, 1958.872673276261, 777.3198451355064, 933.3233405780754, 960.7790208252002, 965.7006328038331, 967.0909345215475, 19328.033598320308, 20181.39455816328, 20646.212761277035, 20659.79340206579, 20661.276855309992, 882.1531329923346, 905.5743800413795, 921.9723408297762, 927.7634203278255, 945.3004776465366, 8479.618417171243, 8939.776505587282, 9203.585838637277, 10882.836757448644, 10922.672319830259, 402033.0, 538912.0, 545533.0, 549413.2529337328, 558069.0, 101687.0, 102230.0, 123967.0, 127757.0, 133721.29647683454, 844.1811442900377, 911.3438324406417, 928.8049509603059, 973.8344481438172, 974.1524873360222, 18757.624847502702, 19023.334183304072, 19191.424257272305, 19220.519487012938, 19249.903750481157, 44862.0, 52939.470605293456, 54614.18078728743, 55132.72433637806, 56923.22292872655, 75556.62221688856, 79601.60199198966, 80809.0, 81056.0, 83096.0, 7110.5022648414115, 7776.794596837499, 8030.759077227693, 8123.4313598047565, 8166.550839703636, 10100.393976361442, 12443.564475243324, 12579.182353147016, 12741.362931853555, 12912.16070955385, 10646.041856232976, 10765.977232162932, 10836.349819010884, 11010.119190464904, 11096.667099987013, 8771.429857059271, 10297.867234604038, 10661.09380702649, 10766.515506802056, 10978.615748448767, 9020.86468702983, 10646.988536089058, 10954.671359859218, 11199.552017919192, 11426.94286528562, 5790.826275211751, 6001.849953751192, 7058.71765129389, 7133.85732285341, 7969.481983671043, 6441.935580644134, 10635.20235982289, 10850.620228291971, 10882.455877206266, 10969.50637221343], "value": 9012.552377989125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1121.1871393239899, 1185.7509922916188, 1380.503018913191, 1472.2050092949808, 1756.1921516102593, 6839.726410943563, 7193.712251509946, 7508.023956885597, 7742.612869356538, 8346.248837604615, 7910.723124690637, 8168.550729709864, 8170.673173073084, 8177.4684645498, 8195.91804081959, 2235.1282999630157, 2295.816334693229, 2300.643400272952, 2303.6314189729646, 2304.0438218139534, 2042.5404284036122, 2117.4812171018043, 2275.1695631094613, 2302.435903203709, 2324.500232450023, 2221.178633520514, 2286.531261091472, 2289.381866895937, 2308.6537019447132, 2312.398776318161, 1901.8003109673493, 2279.8290128240283, 2302.6085565453745, 2303.7350704668925, 2315.6873822033976, 2257.9209727659454, 2296.150424342987, 2326.755690652474, 2331.1724337859987, 2337.3805941425535, 8172.591370431454, 8175.877361839577, 8258.917410825876, 8263.462874913112, 8288.958555207246, 5032.672876263031, 5203.583713302926, 5205.843824685266, 5209.921851172236, 5221.503957124067, 3419.897403077912, 3481.651834816523, 3498.6676265754713, 3510.4909788080586, 3563.3051554946796, 1029.217794476196, 1040.859483969662, 1071.0628200324716, 1079.1690398393193, 1081.2701426537055, 9125.0, 10593.099586535154, 10613.9469302653, 10674.359538427796, 10706.571737130505, 4366.71616344943, 4555.681102322806, 4615.0, 4636.049609829976, 4667.8366257181515, 9351.906480935104, 9371.812563748823, 9415.529223538795, 9452.8582071269, 9458.337916345927, 5178.223266509978, 5182.24857395683, 5197.29836472079, 5211.530962213419, 5214.270002199695, 3403.9149021274666, 4562.950521380101, 4677.017826256524, 4712.31671407644, 4742.099001189763, 3056.617922759655, 3116.205367631285, 3130.7808453408497, 3648.7263455240964, 3707.295613833363, 1044.8641676581997, 1162.959296424638, 1173.2960223865612, 1186.8991135753386, 1209.4013463335705, 739.988900166498, 792.8731402975611, 822.3092602214169, 875.2429148786381, 909.7998440343114, 5394.514493695508, 5436.483534064337, 5470.823772888848, 5484.149956756709, 5512.6692398456225, 551.923748690056, 934.6868798952427, 977.6920270114891, 1014.3305418423805, 1015.1624909449733, 3114.844257787147, 3733.010752150628, 3738.2710371477847, 3824.617538246209, 3850.9037274068373, 9715.70852874415, 9920.355176913477, 10011.64959226438, 10028.899711002943, 10065.949670251603, 9430.575624096931, 9981.750456238653, 10018.3488073275, 10036.698899033041, 10043.598256069676, 7400.407967362598, 7682.423818213656, 7694.961525192338, 7706.383489320953, 7714.6914123434435, 5581.720913954205, 5640.435956404327, 5672.1440804485255, 5860.501857342174, 5917.230760001173, 9212.30907681913, 9286.0, 9305.488198149167, 9334.579943902676, 9343.906560934576, 9178.449293042439, 9279.489628070522, 9400.952995235253, 9446.669366572138, 9485.336026478357, 6386.90419643715, 6855.965720171367, 7045.929540704732, 7120.928790712027, 7216.386607138452, 7664.195259497673, 7767.6116194188935, 7774.766756997299, 7778.533288002738, 7779.260970207822, 6914.204866440223, 7043.436525078084, 7087.0, 7136.250693677089, 7138.964305178441, 6713.697883595152, 6803.387695107464, 6878.3465570770695, 6935.757248496278, 7000.649967501503, 6319.178506794087, 6522.804315870531, 6583.374579414948, 6602.636854973022, 6664.600123992576, 4749.976250118727, 4805.8077676892535, 4815.927761083516, 4864.829730959399, 4905.9018819624125, 4721.622270218443, 4747.572718455217, 4826.227803551415, 4910.8281210157475, 4977.875553111201], "value": 4309.643309282066, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.08670664294016, 297.0568445186533, 303.92857678445574, 306.3077444974358, 310.0357886971518, 4811.927821082678, 5426.728663566825, 5549.420623697718, 5633.661980281186, 5653.547716182706, 4993.475685053071, 5008.874778130539, 5376.220448035033, 5501.231656315426, 5536.640118392302, 3113.2216945763557, 3725.8077415227003, 4207.726497777635, 4216.093539888923, 4243.72415792974, 3511.789292642437, 3609.5668519777664, 3868.845246190149, 4015.15302960637, 4190.455240818689, 3897.5517815451303, 4095.324271495215, 4111.280525907969, 4111.650509706678, 4115.567865374138, 3458.965410345902, 3563.8773786257375, 3773.0, 4114.506259248909, 4140.358244472111, 3358.5390355195323, 3563.572371315433, 3566.2154326048226, 3590.1922067534856, 3592.964070359289, 4682.391289132425, 5003.599712023066, 5310.265213415056, 5371.0, 5386.757595908193, 3937.598065180238, 4616.653750968657, 4707.38803955487, 4709.529047095298, 4821.0, 5750.65496070233, 5812.9709351452975, 5822.563307751935, 5875.353711091777, 5881.6471011739, 3763.9435408468894, 3839.2513459875336, 3864.246471937973, 3880.185161116169, 3891.3384724596885, 6022.578419510636, 6060.848478788022, 6063.9090413643835, 6076.756929722806, 6085.178500902366, 3648.835802388846, 3937.7046721496004, 3949.3681011038097, 3955.861544845917, 3965.0285680008583, 3828.9617103828605, 3844.6539811417106, 3967.4247234150885, 3991.7226487523712, 4044.2315959967514, 4618.261078227468, 4643.512431194744, 4655.6973796703105, 4660.366892705611, 4672.532746725368, 5100.744962751847, 5864.9413505864395, 5867.941320586825, 5886.587938844325, 5887.411258874078, 1186.068935885343, 1225.6139316115396, 1267.5563552756646, 1287.5557932513268, 1294.6569159172639, 722.1154086244309, 744.5309455043397, 821.9013718353835, 876.5529579914289, 881.7927786970057, 4368.204349216504, 4566.346922462017, 4609.884752881205, 5089.9745501273, 5158.458361872024, 4520.50274469808, 4646.558576935186, 4960.767654555464, 5475.44096337376, 5762.135679648086, 4017.035911381306, 4054.722762329857, 4846.636502262344, 5050.217216331474, 5069.188929771292, 2735.1657744387753, 2756.043209321364, 2979.1956171833917, 2990.4617168909804, 2994.7903646744517, 5827.242458480455, 5850.9414905851245, 5888.146218798251, 5913.852153696192, 5927.73325200367, 4706.435227772689, 5326.573874090063, 5518.91721624176, 5657.745401456944, 5808.448197421239, 5051.848444546596, 5184.611154163453, 5353.812616558402, 5577.9163312550345, 5851.9173952818865, 4646.141543382539, 5680.71596420169, 5694.6367257587635, 5742.626729262584, 5746.195532625519, 4495.685302028826, 5121.385433747825, 5179.378474583074, 5367.865803354791, 5646.491815736439, 5025.9644843838305, 5151.82702011372, 5513.724313784214, 5747.885042299047, 5854.282530593019, 3754.5494540655295, 3764.924701506009, 3768.679662228741, 3770.245950809794, 3795.2599243148425, 5381.13901775714, 5539.41836107219, 5636.2245892543715, 5984.431479009487, 6051.0, 5565.30433695788, 5630.155476678531, 5754.798582049776, 5786.652800831964, 5925.4370834770625, 5165.656929198361, 5776.942230577641, 5874.735636896436, 5899.200743773019, 5964.821055368346, 4219.97890010548, 4746.311784791117, 5274.89450210986, 5704.744956109649, 5800.883982320245, 5052.924206136838, 5330.973345133405, 5591.281797604547, 5837.532997360286, 5878.529717622666, 5019.619604608762, 5202.531772140525, 5456.3977587120025, 5664.235328230636, 5719.799807006735], "value": 4069.7511724014403, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.5051084387911, 676.8680107379063, 688.6728803818183, 747.6971826410304, 767.5816679909449, 13926.373313200906, 14327.856721432783, 14540.836733061356, 16724.080175590338, 17560.297588096462, 15766.054036757803, 15964.441244556443, 16132.354705811782, 16308.83691163088, 16340.937839040454, 795.0777098565665, 817.648411183191, 819.2258315891485, 820.7783898347443, 823.5141266652673, 641.2241188162305, 660.1747815230962, 785.2657764989752, 808.7250334886138, 813.3005615170978, 852.2031900173326, 852.3990586636437, 852.6674596907211, 856.546030603781, 857.6783706110209, 845.4208866926144, 846.9237768600825, 848.6053984897021, 849.9575021248975, 851.5486792000188, 615.7813976038486, 619.2383368456778, 621.6674079367561, 632.2349956552531, 632.7216024949008, 12105.576304829288, 14952.102873827605, 15114.924425377803, 15117.848821511863, 15146.697066058727, 13047.412866421033, 13657.931710341483, 13826.446942122304, 14145.85854141466, 14161.716765664733, 10223.744406389826, 11292.040176585, 11824.940875295568, 11934.761304773943, 11959.162858599902, 3396.643352447982, 3574.8758792402914, 3585.103724068983, 3674.3018826423163, 3775.9811200944096, 114141.0, 118765.13976885987, 119243.0, 121527.0, 121657.0, 9667.371620844622, 10184.796304073878, 10413.219008574388, 10442.373457592568, 10592.947035264928, 15897.761533577006, 17167.0, 17658.381956631714, 21451.78548214529, 21813.67279490809, 6796.626185559743, 7717.807054823562, 7738.0, 7770.53376797394, 7824.647890844925, 4288.935665965013, 4354.441999810386, 4400.22995975703, 4406.779661016936, 4497.527759585262, 3079.938401231963, 3410.84651190697, 3491.790492570403, 3513.0690367052753, 3528.435450327935, 606.3269770554681, 620.6059152438235, 624.5815303746501, 637.9744810207633, 639.1850390751829, 578.6528083150076, 600.3126420248791, 629.8756719256106, 652.5595223224304, 663.2472144116366, 9211.124943130391, 9307.06929307064, 9436.386634868712, 9529.571169297398, 9773.383021790682, 638.2596188421409, 648.5427773419727, 650.9032280607192, 662.9337066293332, 672.5090683800853, 4463.977680111579, 4595.632349412039, 4599.494055653875, 4629.745364005012, 4632.72203667781, 93421.53289233646, 94416.0, 95660.52169739107, 98579.0, 117703.0, 88826.55586722025, 90000.09999899917, 90926.09073909308, 91844.54077729702, 94998.0, 79553.60223198963, 92184.53907730419, 93894.5305273483, 94435.11129777366, 95591.0, 8150.347972162332, 9786.1192492676, 9797.0, 9913.058259465628, 10074.798504029732, 51537.7423112882, 51748.74125629348, 56325.155122672375, 58415.70792146012, 58675.4132458687, 72060.63969680118, 82066.0, 82067.17932820834, 83036.58481707553, 83915.0, 11425.942870285595, 11505.539778409106, 12811.743765124831, 12915.143850145105, 13580.320983950565, 13971.441142354191, 14620.0, 14767.778483322974, 15054.548363549111, 15183.392664293304, 11180.720481988015, 13679.589612311647, 14146.027050928864, 14216.289185540474, 14277.214753188258, 13946.0, 14158.221297828306, 14182.290885455892, 14231.430742770588, 14270.500532481314, 13451.394687238902, 13899.72200555963, 14087.718245635235, 14339.426422942965, 14444.638884027983, 10661.788464310524, 11256.887431125911, 11346.546138154381, 11420.717231038361, 11563.479643415898, 9181.21959633412, 10674.412907290174, 11487.48306326234, 11555.537778489102, 11768.764624707286], "value": 7445.294264128598, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0, 118.0, 117.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 117.0, 155.0, 122.0, 122.0, 118.0, 117.0, 141.0, 111.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 214.0, 207.0, 199.0, 200.0, 199.0, 195.0, 196.0, 201.0, 191.0], "value": 200.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 57.0, 62.0, 82.0, 62.0, 60.0, 61.0, 60.0, 69.0, 61.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 85.0, 73.0, 70.0, 77.0, 109.0, 99.0, 88.0, 81.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 91.0, 89.0, 91.0, 87.0, 100.0, 104.0, 79.0, 115.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 96.0, 97.0, 97.0, 96.0, 117.0, 151.0, 106.0, 110.0], "value": 114.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 92.0, 92.0, 102.0, 93.0, 93.0, 92.0, 92.0, 110.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 59.0, 84.0, 64.0, 58.0, 59.0, 59.0, 58.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 67.0, 86.0, 78.0, 72.0, 70.0, 70.0, 70.0, 71.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 90.0, 87.0, 87.0, 87.0, 89.0, 89.0, 89.0, 88.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 159.0, 150.0, 143.0, 148.0, 176.0, 167.0, 162.0, 221.0, 195.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 175.0, 175.0, 227.0, 234.0, 175.0, 177.0, 174.0, 174.0, 176.0], "value": 186.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 67.0, 65.0, 66.0, 85.0, 65.0, 84.0, 80.0, 91.0], "value": 74.3, "unit": "score"}]}]} 17:22:55 INFO - Return code: 0 17:22:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:22:55 ERROR - # TBPL SUCCESS # 17:22:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:22:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:22:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:22:55 INFO - 2016-12-07 17:22:55.308 screenresolution[1816:11015] starting screenresolution argv=screenresolution get 17:22:55 INFO - 2016-12-07 17:22:55.311 screenresolution[1816:11015] Display 0: 1600x1200x32@60 17:22:55 INFO - 2016-12-07 17:22:55.317 screenresolution[1817:11018] starting screenresolution argv=screenresolution list 17:22:55 INFO - Available Modes on Display 0 17:22:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:22:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:22:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:22:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:22:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:22:55 INFO - Intel Iris: 17:22:55 INFO - Chipset Model: Intel Iris 17:22:55 INFO - Type: GPU 17:22:55 INFO - Bus: Built-In 17:22:55 INFO - VRAM (Dynamic, Max): 1536 MB 17:22:55 INFO - Vendor: Intel (0x8086) 17:22:55 INFO - Device ID: 0x0a2e 17:22:55 INFO - Revision ID: 0x0009 17:22:55 INFO - Displays: 17:22:55 INFO - Display: 17:22:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:22:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:22:55 INFO - Display Serial Number: 1600x1200 60 17:22:55 INFO - Main Display: Yes 17:22:55 INFO - Mirror: Off 17:22:55 INFO - Online: Yes 17:22:55 INFO - Rotation: Supported 17:22:55 INFO - Return code: 0 17:22:55 INFO - Running post-action listener: _resource_record_post_action 17:22:55 INFO - [mozharness: 2016-12-08 01:22:55.559801Z] Finished run-tests step (success) 17:22:55 INFO - Running post-run listener: _resource_record_post_run 17:22:55 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 32190976; Write bytes: 700399616; Read time: 199; Write time: 2110 17:22:55 INFO - TinderboxPrint: CPU usage
54.6% 17:22:55 INFO - TinderboxPrint: I/O read bytes / time
32,190,976 / 199 17:22:55 INFO - TinderboxPrint: I/O write bytes / time
700,399,616 / 2,110 17:22:55 INFO - TinderboxPrint: CPU idle
2,204.8 (45.4%) 17:22:55 INFO - TinderboxPrint: CPU system
986.4 (20.3%) 17:22:55 INFO - TinderboxPrint: CPU user
1,662.5 (34.3%) 17:22:55 INFO - TinderboxPrint: Swap in / out
412,647,424 / 0 17:22:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381751808; Write bytes: 377059328; Read time: 30433; Write time: 835 17:22:55 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 30003712; Write bytes: 310917120; Read time: 176; Write time: 1235 17:22:56 INFO - Running post-run listener: _upload_blobber_files 17:22:56 INFO - Blob upload gear active. 17:22:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:22:56 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 17:22:56 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'] 17:22:56 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 17:22:56 INFO - (blobuploader) - INFO - Open directory for files ... 17:22:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:22:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:22:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:57 INFO - (blobuploader) - INFO - Done attempting. 17:22:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:22:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:22:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:22:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:22:58 INFO - (blobuploader) - INFO - Done attempting. 17:22:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:22:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:22:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:23:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:23:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:23:01 INFO - (blobuploader) - INFO - Done attempting. 17:23:01 INFO - (blobuploader) - INFO - Iteration through files over. 17:23:01 INFO - Return code: 0 17:23:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:23:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:23:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5000b1688e2120b2a1f4cd8e7dba306eb9ce421eb0ad766b8ce4bfa64678c0f0f0166742e3d453db4cc6b3f93414635cd17e9e265efef48e6bb6c4cdc30d1016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77bdafb37521c59c7a2235268c0ddd451e3fe7bcd0b5d4c19b0798789153374a08a306aba903f8dc2a1985fa987ec98952e2cb4cda811f9eee8a4bc64d3c30ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5a33341e95217b7e019f442809d77b29c8db4ae8ee5ce25d7254c71244b3e45b5951127a0b3485fc1e68bcb42457ead50e28fc938bf233bf555610e03c8a15b"} 17:23:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:23:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:23:01 INFO - Contents: 17:23:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5000b1688e2120b2a1f4cd8e7dba306eb9ce421eb0ad766b8ce4bfa64678c0f0f0166742e3d453db4cc6b3f93414635cd17e9e265efef48e6bb6c4cdc30d1016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77bdafb37521c59c7a2235268c0ddd451e3fe7bcd0b5d4c19b0798789153374a08a306aba903f8dc2a1985fa987ec98952e2cb4cda811f9eee8a4bc64d3c30ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5a33341e95217b7e019f442809d77b29c8db4ae8ee5ce25d7254c71244b3e45b5951127a0b3485fc1e68bcb42457ead50e28fc938bf233bf555610e03c8a15b"} 17:23:01 INFO - Running post-run listener: copy_logs_to_upload_dir 17:23:01 INFO - Copying logs to upload dir... 17:23:01 INFO - mkdir: /builds/slave/test/build/upload/logs 17:23:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.103973 ========= master_lag: 3.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-12-07 17:23:04.113817) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-07 17:23:04.117466) ========= 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.X5RkLbnLUD/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.dynSn6c4GI/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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/5000b1688e2120b2a1f4cd8e7dba306eb9ce421eb0ad766b8ce4bfa64678c0f0f0166742e3d453db4cc6b3f93414635cd17e9e265efef48e6bb6c4cdc30d1016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77bdafb37521c59c7a2235268c0ddd451e3fe7bcd0b5d4c19b0798789153374a08a306aba903f8dc2a1985fa987ec98952e2cb4cda811f9eee8a4bc64d3c30ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5a33341e95217b7e019f442809d77b29c8db4ae8ee5ce25d7254c71244b3e45b5951127a0b3485fc1e68bcb42457ead50e28fc938bf233bf555610e03c8a15b"} build_url:https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010964 build_url: 'https://queue.taskcluster.net/v1/task/Z748anm1RvWrom7ZtuAC9w/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/5000b1688e2120b2a1f4cd8e7dba306eb9ce421eb0ad766b8ce4bfa64678c0f0f0166742e3d453db4cc6b3f93414635cd17e9e265efef48e6bb6c4cdc30d1016", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/77bdafb37521c59c7a2235268c0ddd451e3fe7bcd0b5d4c19b0798789153374a08a306aba903f8dc2a1985fa987ec98952e2cb4cda811f9eee8a4bc64d3c30ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f5a33341e95217b7e019f442809d77b29c8db4ae8ee5ce25d7254c71244b3e45b5951127a0b3485fc1e68bcb42457ead50e28fc938bf233bf555610e03c8a15b"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-07 17:23:04.166188) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 17:23:04.166538) ========= 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.X5RkLbnLUD/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.dynSn6c4GI/Listeners TMPDIR=/var/folders/jw/lfbylzg17jd15qryjvmblvym00000w/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.005091 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-07 17:23:04.215272) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-07 17:23:04.215643) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-07 17:23:04.216004) ========= ========= Total master_lag: 3.25 =========