builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0384 starttime: 1491822955.64 results: success (0) buildid: 20170410010648 builduid: bbf6054b59914cacb59438d60949a753 revision: c4bcbbdbf8aaa07d782a5422eac873c5c7a70909 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:55.643795) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:55.644290) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:55.644580) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:55.672463) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:55.673149) ========= 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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 --2017-04-10 04:15:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2017-04-10 04:15:56 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.413676 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:56.115354) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:56.116098) ========= 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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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.020498 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:56.166171) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 04:15:56.166534) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c4bcbbdbf8aaa07d782a5422eac873c5c7a70909 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c4bcbbdbf8aaa07d782a5422eac873c5c7a70909 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 2017-04-10 04:15:56,428 truncating revision to first 12 chars 2017-04-10 04:15:56,428 Setting DEBUG logging. 2017-04-10 04:15:56,428 attempt 1/10 2017-04-10 04:15:56,428 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c4bcbbdbf8aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 04:15:57,868 unpacking tar archive at: mozilla-beta-c4bcbbdbf8aa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.706530 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 04:15:57.900496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:57.901146) ========= script_repo_revision: c4bcbbdbf8aaa07d782a5422eac873c5c7a70909 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:57.902119) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:57.902715) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 04:15:57.924839) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-10 04:15:57.925217) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:15:58 INFO - MultiFileLogger online at 20170410 04:15:58 in /builds/slave/test 04:15:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:15:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:15:58 INFO - {'append_to_log': False, 04:15:58 INFO - 'base_work_dir': '/builds/slave/test', 04:15:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:15:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:15:58 INFO - 'branch': 'Mozilla-Beta', 04:15:58 INFO - 'buildbot_json_path': 'buildprops.json', 04:15:58 INFO - 'config_files': ('talos/mac_config.py',), 04:15:58 INFO - 'default_actions': ('clobber', 04:15:58 INFO - 'read-buildbot-config', 04:15:58 INFO - 'download-and-extract', 04:15:58 INFO - 'populate-webroot', 04:15:58 INFO - 'create-virtualenv', 04:15:58 INFO - 'install', 04:15:58 INFO - 'run-tests'), 04:15:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:15:58 INFO - 'download_minidump_stackwalk': True, 04:15:58 INFO - 'download_symbols': 'ondemand', 04:15:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:15:58 INFO - 'tooltool.py': '/tools/tooltool.py', 04:15:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:15:58 INFO - '/tools/misc-python/virtualenv.py')}, 04:15:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:15:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:15:58 INFO - 'installer_path': 'installer.exe', 04:15:58 INFO - 'log_level': 'info', 04:15:58 INFO - 'log_name': 'talos', 04:15:58 INFO - 'log_to_console': True, 04:15:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:15:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:15:58 INFO - 'opt_config_files': (), 04:15:58 INFO - 'pip_index': False, 04:15:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:58 INFO - 'cmd': ('bash', 04:15:58 INFO - '-c', 04:15:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:58 INFO - 'enabled': True, 04:15:58 INFO - 'halt_on_failure': False, 04:15:58 INFO - 'name': 'check_screen_resolution'},), 04:15:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:58 INFO - 'cmd': ('bash', 04:15:58 INFO - '-c', 04:15:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:58 INFO - 'enabled': True, 04:15:58 INFO - 'halt_on_failure': False, 04:15:58 INFO - 'name': 'check_screen_resolution'},), 04:15:58 INFO - 'run_cmd_checks_enabled': True, 04:15:58 INFO - 'sps_profile': False, 04:15:58 INFO - 'sps_profile_interval': 0, 04:15:58 INFO - 'suite': 'dromaeojs-e10s', 04:15:58 INFO - 'system_bits': '32', 04:15:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:15:58 INFO - 'title': 't-yosemite-r7-0384', 04:15:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:15:58 INFO - 'use_talos_json': True, 04:15:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:15:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:15:58 INFO - 'work_dir': 'build'} 04:15:58 INFO - [mozharness: 2017-04-10 11:15:58.328106Z] Running clobber step. 04:15:58 INFO - Running pre-action listener: _resource_record_pre_action 04:15:58 INFO - Running main action method: clobber 04:15:58 INFO - rmtree: /builds/slave/test/build 04:15:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:15:59 INFO - Running post-action listener: _resource_record_post_action 04:15:59 INFO - [mozharness: 2017-04-10 11:15:59.987968Z] Finished clobber step (success) 04:15:59 INFO - [mozharness: 2017-04-10 11:15:59.988138Z] Running read-buildbot-config step. 04:15:59 INFO - Running pre-action listener: _resource_record_pre_action 04:15:59 INFO - Running main action method: read_buildbot_config 04:15:59 INFO - Using buildbot properties: 04:15:59 INFO - { 04:15:59 INFO - "project": "", 04:15:59 INFO - "product": "firefox", 04:15:59 INFO - "script_repo_revision": "production", 04:15:59 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:15:59 INFO - "repository": "", 04:15:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:15:59 INFO - "buildid": "20170410010648", 04:15:59 INFO - "pgo_build": "False", 04:15:59 INFO - "basedir": "/builds/slave/test", 04:15:59 INFO - "buildnumber": 5, 04:15:59 INFO - "slavename": "t-yosemite-r7-0384", 04:15:59 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:15:59 INFO - "platform": "yosemite_r7", 04:15:59 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:15:59 INFO - "branch": "mozilla-beta", 04:15:59 INFO - "repo_path": "releases/mozilla-beta", 04:15:59 INFO - "revision": "c4bcbbdbf8aaa07d782a5422eac873c5c7a70909", 04:15:59 INFO - "stage_platform": "macosx64", 04:15:59 INFO - "builduid": "bbf6054b59914cacb59438d60949a753", 04:15:59 INFO - "slavebuilddir": "test" 04:15:59 INFO - } 04:15:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 04:15:59 INFO - Running post-action listener: _resource_record_post_action 04:15:59 INFO - [mozharness: 2017-04-10 11:15:59.991597Z] Finished read-buildbot-config step (success) 04:15:59 INFO - [mozharness: 2017-04-10 11:15:59.991743Z] Running download-and-extract step. 04:15:59 INFO - Running pre-action listener: _resource_record_pre_action 04:15:59 INFO - Running main action method: download_and_extract 04:15:59 INFO - mkdir: /builds/slave/test/build/tests 04:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:59 INFO - trying https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 04:15:59 INFO - Downloading https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 04:15:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 04:16:01 INFO - Downloaded 1165 bytes. 04:16:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 04:16:01 INFO - Using the following test package requirements: 04:16:01 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 04:16:01 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:16:01 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 04:16:01 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:16:01 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 04:16:01 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:16:01 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:16:01 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 04:16:01 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 04:16:01 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:16:01 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 04:16:01 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:16:01 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 04:16:01 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:16:01 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 04:16:01 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 04:16:01 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 04:16:01 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 04:16:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 04:16:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 04:16:01 INFO - Fetch https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 04:16:02 INFO - Expected file size: 16116422 04:16:02 INFO - Obtained file size: 16116422 04:16:09 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:16:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 04:16:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 04:16:09 INFO - Fetch https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 04:16:09 INFO - Expected file size: 16116422 04:16:09 INFO - Obtained file size: 16116422 04:16:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 04:16:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 04:16:16 INFO - Fetch https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 04:16:17 INFO - Expected file size: 14403650 04:16:17 INFO - Obtained file size: 14403650 04:16: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')]} 04:16:18 INFO - trying https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 04:16:18 INFO - Downloading https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 04:16:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 04:16:19 INFO - Downloaded 58734727 bytes. 04:16:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 04:16:19 INFO - mkdir: /builds/slave/test/properties 04:16:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:16:19 INFO - Writing to file /builds/slave/test/properties/build_url 04:16:19 INFO - Contents: 04:16:19 INFO - build_url:https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg 04:16:20 INFO - Running post-action listener: _resource_record_post_action 04:16:20 INFO - Running post-action listener: set_extra_try_arguments 04:16:20 INFO - [mozharness: 2017-04-10 11:16:20.397476Z] Finished download-and-extract step (success) 04:16:20 INFO - [mozharness: 2017-04-10 11:16:20.397610Z] Running populate-webroot step. 04:16:20 INFO - Running pre-action listener: _resource_record_pre_action 04:16:20 INFO - Running main action method: populate_webroot 04:16:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:16:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:16:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:16:20 INFO - '--tppagecycles', 04:16:20 INFO - '7'], 04:16:20 INFO - 'tests': ['tsvgm']}}, 04:16:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:16:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:16:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:16:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:16:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:16:20 INFO - 'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:16:20 INFO - 'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['damp', 'tps']}, 04:16:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:16:20 INFO - 'tests': ['damp', 'tps']}, 04:16:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['dromaeo_dom']}, 04:16:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:16:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['basic_compositor_video']}, 04:16:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:16:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['a11yr', 04:16:20 INFO - 'ts_paint', 04:16:20 INFO - 'tpaint', 04:16:20 INFO - 'sessionrestore', 04:16:20 INFO - 'sessionrestore_no_auto_restore', 04:16:20 INFO - 'tabpaint']}, 04:16:20 INFO - 'other-e10s': {'tests': ['a11yr', 04:16:20 INFO - 'ts_paint', 04:16:20 INFO - 'tpaint', 04:16:20 INFO - 'sessionrestore', 04:16:20 INFO - 'sessionrestore_no_auto_restore', 04:16:20 INFO - 'tabpaint']}, 04:16:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:16:20 INFO - 'ts_paint', 04:16:20 INFO - 'tpaint', 04:16:20 INFO - 'sessionrestore', 04:16:20 INFO - 'sessionrestore_no_auto_restore', 04:16:20 INFO - 'tabpaint']}, 04:16:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:16:20 INFO - 'ts_paint', 04:16:20 INFO - 'tpaint', 04:16:20 INFO - 'sessionrestore', 04:16:20 INFO - 'sessionrestore_no_auto_restore', 04:16:20 INFO - 'tabpaint']}, 04:16:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['a11yr', 04:16:20 INFO - 'ts_paint', 04:16:20 INFO - 'tpaint', 04:16:20 INFO - 'sessionrestore', 04:16:20 INFO - 'sessionrestore_no_auto_restore', 04:16:20 INFO - 'tabpaint']}, 04:16:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['a11yr', 04:16:20 INFO - 'ts_paint', 04:16:20 INFO - 'tpaint', 04:16:20 INFO - 'sessionrestore', 04:16:20 INFO - 'sessionrestore_no_auto_restore', 04:16:20 INFO - 'tabpaint']}, 04:16:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['tsvgx', 04:16:20 INFO - 'tsvgr_opacity', 04:16:20 INFO - 'tart', 04:16:20 INFO - 'tscrollx', 04:16:20 INFO - 'cart', 04:16:20 INFO - 'tsvg_static']}, 04:16:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:16:20 INFO - 'tsvgr_opacity', 04:16:20 INFO - 'tart', 04:16:20 INFO - 'tscrollx', 04:16:20 INFO - 'cart', 04:16:20 INFO - 'tsvg_static']}, 04:16:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:16:20 INFO - 'talos_options': ['--disable-e10s'], 04:16:20 INFO - 'tests': ['tp5o']}, 04:16:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:16:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:16:20 INFO - 'talos_options': ['--disable-e10s', 04:16:20 INFO - '--xperf_path', 04:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:20 INFO - 'tests': ['tp5n']}, 04:16:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:16:20 INFO - 'talos_options': ['--xperf_path', 04:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:20 INFO - 'tests': ['tp5n']}}, 04:16:20 INFO - 'talos.zip': {'path': '', 04:16:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:16:20 INFO - Running post-action listener: _resource_record_post_action 04:16:20 INFO - [mozharness: 2017-04-10 11:16:20.417358Z] Finished populate-webroot step (success) 04:16:20 INFO - [mozharness: 2017-04-10 11:16:20.417501Z] Running create-virtualenv step. 04:16:20 INFO - Running pre-action listener: _resource_record_pre_action 04:16:20 INFO - Running main action method: create_virtualenv 04:16:20 INFO - Creating virtualenv /builds/slave/test/build/venv 04:16:20 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 04:16:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:16:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:16:20 INFO - Using real prefix '/tools/python27' 04:16:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:16:21 INFO - Installing distribute.............................................................................................................................................................................................done. 04:16:24 INFO - Installing pip.................done. 04:16:24 INFO - Return code: 0 04:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:16:24 INFO - Reading from file tmpfile_stdout 04:16:24 INFO - Output received: 04:16:24 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) 04:16:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:24 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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:24 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 04:16:24 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 04:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:24 INFO - 'HOME': '/Users/cltbld', 04:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:24 INFO - 'LOGNAME': 'cltbld', 04:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:24 INFO - 'MOZ_NO_REMOTE': '1', 04:16:24 INFO - 'NO_EM_RESTART': '1', 04:16:24 INFO - 'PAGER': '/bin/cat', 04:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:24 INFO - 'PWD': '/builds/slave/test', 04:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:24 INFO - 'SHELL': '/bin/bash', 04:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:24 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:24 INFO - 'USER': 'cltbld', 04:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:24 INFO - 'XPC_FLAGS': '0x0', 04:16:24 INFO - 'XPC_SERVICE_NAME': '0', 04:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:24 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 04:16:24 INFO - Cleaning up... 04:16:24 INFO - Return code: 0 04:16:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:24 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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:24 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 04:16:24 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 04:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:24 INFO - 'HOME': '/Users/cltbld', 04:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:24 INFO - 'LOGNAME': 'cltbld', 04:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:24 INFO - 'MOZ_NO_REMOTE': '1', 04:16:24 INFO - 'NO_EM_RESTART': '1', 04:16:24 INFO - 'PAGER': '/bin/cat', 04:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:24 INFO - 'PWD': '/builds/slave/test', 04:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:24 INFO - 'SHELL': '/bin/bash', 04:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:24 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:24 INFO - 'USER': 'cltbld', 04:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:24 INFO - 'XPC_FLAGS': '0x0', 04:16:24 INFO - 'XPC_SERVICE_NAME': '0', 04:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:24 INFO - Downloading/unpacking psutil>=3.1.1 04:16: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 04:16: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 04:16: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 04:16: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 04:16: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 04:16: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 04:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:16:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:28 INFO - Installing collected packages: psutil 04:16:28 INFO - Running setup.py install for psutil 04:16:28 INFO - building 'psutil._psutil_osx' extension 04:16:28 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 04:16:28 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 04:16:29 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 04:16:29 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 04:16:29 INFO - building 'psutil._psutil_posix' extension 04:16:29 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 04:16:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:16:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:16:29 INFO - ^ 04:16:29 INFO - 1 warning generated. 04:16:29 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 04:16:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:29 INFO - Successfully installed psutil 04:16:29 INFO - Cleaning up... 04:16:29 INFO - Return code: 0 04:16:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:16:29 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')]} 04:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:29 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')]} 04:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:29 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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:29 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 04:16:29 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 04:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:29 INFO - 'HOME': '/Users/cltbld', 04:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:29 INFO - 'LOGNAME': 'cltbld', 04:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:29 INFO - 'MOZ_NO_REMOTE': '1', 04:16:29 INFO - 'NO_EM_RESTART': '1', 04:16:29 INFO - 'PAGER': '/bin/cat', 04:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:29 INFO - 'PWD': '/builds/slave/test', 04:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:29 INFO - 'SHELL': '/bin/bash', 04:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:29 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:29 INFO - 'USER': 'cltbld', 04:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:29 INFO - 'XPC_FLAGS': '0x0', 04:16:29 INFO - 'XPC_SERVICE_NAME': '0', 04:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:16: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 04:16: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 04:16: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 04:16: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 04:16: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 04:16: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 04:16:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:16:32 INFO - Installing collected packages: mozsystemmonitor 04:16:32 INFO - Running setup.py install for mozsystemmonitor 04:16:32 INFO - Successfully installed mozsystemmonitor 04:16:32 INFO - Cleaning up... 04:16:32 INFO - Return code: 0 04:16:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:16:32 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')]} 04:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:32 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')]} 04:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:32 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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:32 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 04:16:32 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 04:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:32 INFO - 'HOME': '/Users/cltbld', 04:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:32 INFO - 'LOGNAME': 'cltbld', 04:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:32 INFO - 'MOZ_NO_REMOTE': '1', 04:16:32 INFO - 'NO_EM_RESTART': '1', 04:16:32 INFO - 'PAGER': '/bin/cat', 04:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:32 INFO - 'PWD': '/builds/slave/test', 04:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:32 INFO - 'SHELL': '/bin/bash', 04:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:32 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:32 INFO - 'USER': 'cltbld', 04:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:32 INFO - 'XPC_FLAGS': '0x0', 04:16:32 INFO - 'XPC_SERVICE_NAME': '0', 04:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:32 INFO - Downloading/unpacking jsonschema==2.5.1 04:16:32 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 04:16:32 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 04:16:32 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 04:16:32 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 04:16:32 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 04:16:32 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 04:16:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:16:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:16:35 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 04:16:35 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 04:16:35 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 04:16:35 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 04:16:35 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 04:16:35 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 04:16:35 INFO - Downloading functools32-3.2.3-2.tar.gz 04:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:16:36 INFO - warning: no files found matching '*.txt' 04:16:36 INFO - no previously-included directories found matching 'build' 04:16:36 INFO - no previously-included directories found matching 'dist' 04:16:36 INFO - no previously-included directories found matching '.git*' 04:16:36 INFO - Installing collected packages: jsonschema, functools32 04:16:36 INFO - Running setup.py install for functools32 04:16:36 INFO - warning: no files found matching '*.txt' 04:16:36 INFO - no previously-included directories found matching 'build' 04:16:36 INFO - no previously-included directories found matching 'dist' 04:16:36 INFO - no previously-included directories found matching '.git*' 04:16:36 INFO - Successfully installed jsonschema functools32 04:16:36 INFO - Cleaning up... 04:16:36 INFO - Return code: 0 04:16:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:36 INFO - 'HOME': '/Users/cltbld', 04:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:36 INFO - 'LOGNAME': 'cltbld', 04:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:36 INFO - 'MOZ_NO_REMOTE': '1', 04:16:36 INFO - 'NO_EM_RESTART': '1', 04:16:36 INFO - 'PAGER': '/bin/cat', 04:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:36 INFO - 'PWD': '/builds/slave/test', 04:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:36 INFO - 'SHELL': '/bin/bash', 04:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:36 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:36 INFO - 'USER': 'cltbld', 04:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:36 INFO - 'XPC_FLAGS': '0x0', 04:16:36 INFO - 'XPC_SERVICE_NAME': '0', 04:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:16:36 INFO - Cleaning up... 04:16:36 INFO - Return code: 0 04:16:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:36 INFO - 'HOME': '/Users/cltbld', 04:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:36 INFO - 'LOGNAME': 'cltbld', 04:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:36 INFO - 'MOZ_NO_REMOTE': '1', 04:16:36 INFO - 'NO_EM_RESTART': '1', 04:16:36 INFO - 'PAGER': '/bin/cat', 04:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:36 INFO - 'PWD': '/builds/slave/test', 04:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:36 INFO - 'SHELL': '/bin/bash', 04:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:36 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:36 INFO - 'USER': 'cltbld', 04:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:36 INFO - 'XPC_FLAGS': '0x0', 04:16:36 INFO - 'XPC_SERVICE_NAME': '0', 04:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:36 INFO - Downloading/unpacking blobuploader==1.2.4 04:16:36 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 04:16:36 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 04:16:36 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 04:16:36 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 04:16:36 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 04:16:36 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 04:16:39 INFO - Downloading blobuploader-1.2.4.tar.gz 04:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:16:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:16:39 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 04:16:39 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 04:16:39 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 04:16:39 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 04:16:39 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 04:16:39 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 04:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:16:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:16:40 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 04:16:40 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 04:16:40 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 04:16:40 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 04:16:40 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 04:16:40 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 04:16:40 INFO - Downloading docopt-0.6.1.tar.gz 04:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:16:40 INFO - Installing collected packages: blobuploader, requests, docopt 04:16:40 INFO - Running setup.py install for blobuploader 04:16:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:16:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:16:40 INFO - Running setup.py install for requests 04:16:41 INFO - Running setup.py install for docopt 04:16:41 INFO - Successfully installed blobuploader requests docopt 04:16:41 INFO - Cleaning up... 04:16:41 INFO - Return code: 0 04:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:41 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')]} 04:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:41 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')]} 04:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:41 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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:41 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 04:16:41 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 04:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:41 INFO - 'HOME': '/Users/cltbld', 04:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:41 INFO - 'LOGNAME': 'cltbld', 04:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:41 INFO - 'MOZ_NO_REMOTE': '1', 04:16:41 INFO - 'NO_EM_RESTART': '1', 04:16:41 INFO - 'PAGER': '/bin/cat', 04:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:41 INFO - 'PWD': '/builds/slave/test', 04:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:41 INFO - 'SHELL': '/bin/bash', 04:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:41 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:41 INFO - 'USER': 'cltbld', 04:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:41 INFO - 'XPC_FLAGS': '0x0', 04:16:41 INFO - 'XPC_SERVICE_NAME': '0', 04:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:41 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-V6pNu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:41 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-IwNubM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-EGGIsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-VUDSGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-6h6JZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-UXmnt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-deJJ5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-c69wZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-fi00UU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-GwTl2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:42 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ZrT8zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:43 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-JFNk_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:43 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-hVVNYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:43 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-F6ZwD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:43 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-rvMG03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:43 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-_IgHpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:43 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-I37Alh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:16:43 INFO - Running setup.py install for manifestparser 04:16:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:16:43 INFO - Running setup.py install for mozcrash 04:16:43 INFO - Running setup.py install for mozdebug 04:16:44 INFO - Running setup.py install for mozdevice 04:16:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Running setup.py install for mozfile 04:16:44 INFO - Running setup.py install for mozhttpd 04:16:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Running setup.py install for mozinfo 04:16:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Running setup.py install for mozInstall 04:16:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:16:44 INFO - Running setup.py install for mozleak 04:16:44 INFO - Running setup.py install for mozlog 04:16:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:16:45 INFO - Running setup.py install for moznetwork 04:16:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:16:45 INFO - Running setup.py install for mozprocess 04:16:45 INFO - Running setup.py install for mozprofile 04:16:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:16:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:16:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:16:45 INFO - Running setup.py install for mozrunner 04:16:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:16:45 INFO - Running setup.py install for mozscreenshot 04:16:45 INFO - Running setup.py install for moztest 04:16:46 INFO - Running setup.py install for mozversion 04:16:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:16:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:16:46 INFO - Cleaning up... 04:16:46 INFO - Return code: 0 04:16:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:46 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')]} 04:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:46 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')]} 04:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:46 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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:46 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 04:16:46 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 04:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:46 INFO - 'HOME': '/Users/cltbld', 04:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:46 INFO - 'LOGNAME': 'cltbld', 04:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:46 INFO - 'MOZ_NO_REMOTE': '1', 04:16:46 INFO - 'NO_EM_RESTART': '1', 04:16:46 INFO - 'PAGER': '/bin/cat', 04:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:46 INFO - 'PWD': '/builds/slave/test', 04:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:46 INFO - 'SHELL': '/bin/bash', 04:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:46 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:46 INFO - 'USER': 'cltbld', 04:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:46 INFO - 'XPC_FLAGS': '0x0', 04:16:46 INFO - 'XPC_SERVICE_NAME': '0', 04:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-SXld00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:46 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)) 04:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ORRaqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:46 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)) 04:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-KdicSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:46 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)) 04:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-0duv83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:46 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)) 04:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-EsDofJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-eVS5WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-AzNt08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-neljuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-gX0p6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-_964pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-lKc7NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-Ou54rR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-EyIjtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:47 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)) 04:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-n3Pf59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:48 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)) 04:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:48 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-y0VR_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:48 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)) 04:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:48 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-d8mr3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 04:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:48 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-5s4q76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:48 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)) 04:16:48 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)) 04:16:48 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)) 04:16:48 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)) 04:16:48 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)) 04:16:48 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)) 04:16:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:50 INFO - Downloading blessings-1.6.tar.gz 04:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:16:51 INFO - Installing collected packages: blessings 04:16:51 INFO - Running setup.py install for blessings 04:16:51 INFO - Successfully installed blessings 04:16:51 INFO - Cleaning up... 04:16:51 INFO - Return code: 0 04:16:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:51 INFO - Reading from file tmpfile_stdout 04:16:51 INFO - Current package versions: 04:16:51 INFO - blessings == 1.6 04:16:51 INFO - blobuploader == 1.2.4 04:16:51 INFO - docopt == 0.6.1 04:16:51 INFO - functools32 == 3.2.3-2 04:16:51 INFO - jsonschema == 2.5.1 04:16:51 INFO - manifestparser == 1.1 04:16:51 INFO - mozInstall == 1.12 04:16:51 INFO - mozcrash == 1.0 04:16:51 INFO - mozdebug == 0.1 04:16:51 INFO - mozdevice == 0.49 04:16:51 INFO - mozfile == 1.2 04:16:51 INFO - mozhttpd == 0.7 04:16:51 INFO - mozinfo == 0.9 04:16:51 INFO - mozleak == 0.1 04:16:51 INFO - mozlog == 3.4 04:16:51 INFO - moznetwork == 0.27 04:16:51 INFO - mozprocess == 0.24 04:16:51 INFO - mozprofile == 0.28 04:16:51 INFO - mozrunner == 6.13 04:16:51 INFO - mozscreenshot == 0.1 04:16:51 INFO - mozsystemmonitor == 0.3 04:16:51 INFO - moztest == 0.8 04:16:51 INFO - mozversion == 1.4 04:16:51 INFO - psutil == 3.1.1 04:16:51 INFO - requests == 1.2.3 04:16:51 INFO - wsgiref == 0.1.2 04:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:52 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 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:52 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 04:16:52 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 04:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:52 INFO - 'HOME': '/Users/cltbld', 04:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:52 INFO - 'LOGNAME': 'cltbld', 04:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:52 INFO - 'MOZ_NO_REMOTE': '1', 04:16:52 INFO - 'NO_EM_RESTART': '1', 04:16:52 INFO - 'PAGER': '/bin/cat', 04:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:52 INFO - 'PWD': '/builds/slave/test', 04:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:52 INFO - 'SHELL': '/bin/bash', 04:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:52 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:52 INFO - 'USER': 'cltbld', 04:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:52 INFO - 'XPC_FLAGS': '0x0', 04:16:52 INFO - 'XPC_SERVICE_NAME': '0', 04:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 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)) 04:16:52 INFO - Cleaning up... 04:16:52 INFO - Return code: 0 04:16:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d63b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d66768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9273d71470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, '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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 04:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:52 INFO - 'HOME': '/Users/cltbld', 04:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:52 INFO - 'LOGNAME': 'cltbld', 04:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:52 INFO - 'MOZ_NO_REMOTE': '1', 04:16:52 INFO - 'NO_EM_RESTART': '1', 04:16:52 INFO - 'PAGER': '/bin/cat', 04:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:52 INFO - 'PWD': '/builds/slave/test', 04:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:52 INFO - 'SHELL': '/bin/bash', 04:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:16:52 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:52 INFO - 'USER': 'cltbld', 04:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:52 INFO - 'XPC_FLAGS': '0x0', 04:16:52 INFO - 'XPC_SERVICE_NAME': '0', 04:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:16:53 INFO - Cleaning up... 04:16:53 INFO - Return code: 0 04:16:53 INFO - Running post-action listener: _resource_record_post_action 04:16:53 INFO - Running post-action listener: _start_resource_monitoring 04:16:53 INFO - Starting resource monitoring. 04:16:53 INFO - [mozharness: 2017-04-10 11:16:53.131866Z] Finished create-virtualenv step (success) 04:16:53 INFO - [mozharness: 2017-04-10 11:16:53.132848Z] Running install step. 04:16:53 INFO - Running pre-action listener: _resource_record_pre_action 04:16:53 INFO - Running main action method: install 04:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:53 INFO - Reading from file tmpfile_stdout 04:16:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:16:53 INFO - Reading from file tmpfile_stdout 04:16:53 INFO - Output received: 04:16:53 INFO - Usage: mozinstall [options] installer 04:16:53 INFO - Options: 04:16:53 INFO - -h, --help show this help message and exit 04:16:53 INFO - -d DEST, --destination=DEST 04:16:53 INFO - Directory to install application into. [default: 04:16:53 INFO - "/builds/slave/test"] 04:16:53 INFO - --app=APP Application being installed. [default: firefox] 04:16:53 INFO - mkdir: /builds/slave/test/build/application 04:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:17:08 INFO - Reading from file tmpfile_stdout 04:17:08 INFO - Output received: 04:17:08 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:17:08 INFO - Running post-action listener: _resource_record_post_action 04:17:08 INFO - [mozharness: 2017-04-10 11:17:08.747190Z] Finished install step (success) 04:17:08 INFO - [mozharness: 2017-04-10 11:17:08.747354Z] Running run-tests step. 04:17:08 INFO - Running pre-action listener: _resource_record_pre_action 04:17:08 INFO - Running main action method: run_tests 04:17:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:17:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:17:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:17:08 INFO - 2017-04-10 04:17:08.761 screenresolution[1925:21617] starting screenresolution argv=screenresolution get 04:17:08 INFO - 2017-04-10 04:17:08.764 screenresolution[1925:21617] Display 0: 1600x1200x32@60 04:17:08 INFO - 2017-04-10 04:17:08.770 screenresolution[1926:21620] starting screenresolution argv=screenresolution list 04:17:08 INFO - Available Modes on Display 0 04:17:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:17:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:17:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:17:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:17:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:17:08 INFO - Intel Iris: 04:17:08 INFO - Chipset Model: Intel Iris 04:17:08 INFO - Type: GPU 04:17:08 INFO - Bus: Built-In 04:17:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:17:08 INFO - Vendor: Intel (0x8086) 04:17:08 INFO - Device ID: 0x0a2e 04:17:08 INFO - Revision ID: 0x0009 04:17:08 INFO - Displays: 04:17:08 INFO - Display: 04:17:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:17:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:17:08 INFO - Display Serial Number: 1600x1200 60 04:17:08 INFO - Main Display: Yes 04:17:08 INFO - Mirror: Off 04:17:09 INFO - Online: Yes 04:17:09 INFO - Rotation: Supported 04:17:09 INFO - Return code: 0 04:17:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:17:09 INFO - Python 2.7.3 04:17:09 INFO - Return code: 0 04:17:09 INFO - grabbing minidump binary from tooltool 04:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:09 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 0x101b41d30>, '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 0x101a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b36be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:17:09 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 04:17:09 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 04:17:09 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 04:17:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:17:09 INFO - Return code: 0 04:17:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:17:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:17:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:17:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:17:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:17:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 04:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0384 --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 04:17:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wbYlXSDUXJ/Render', 04:17:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:17:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:17:09 INFO - 'HOME': '/Users/cltbld', 04:17:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:17:09 INFO - 'LOGNAME': 'cltbld', 04:17:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:17:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:09 INFO - 'MOZ_NO_REMOTE': '1', 04:17:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:17:09 INFO - 'NO_EM_RESTART': '1', 04:17:09 INFO - 'PAGER': '/bin/cat', 04:17:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:09 INFO - 'PWD': '/builds/slave/test', 04:17:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:17:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:17:09 INFO - 'SHELL': '/bin/bash', 04:17:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bx8NKN4PTL/Listeners', 04:17:09 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 04:17:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:17:09 INFO - 'USER': 'cltbld', 04:17:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:17:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:09 INFO - 'XPC_FLAGS': '0x0', 04:17:09 INFO - 'XPC_SERVICE_NAME': '0', 04:17:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:17:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 04:17:09 INFO - mozversion application_buildid: 20170410010648 04:17:09 INFO - mozversion application_changeset: c4bcbbdbf8aaa07d782a5422eac873c5c7a70909 04:17:09 INFO - mozversion application_display_name: Firefox 04:17:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:17:09 INFO - mozversion application_name: Firefox 04:17:09 INFO - mozversion application_remotingname: firefox 04:17:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:17:09 INFO - mozversion application_vendor: Mozilla 04:17:09 INFO - mozversion application_version: 53.0 04:17:09 INFO - mozversion platform_buildid: 20170410010648 04:17:09 INFO - mozversion platform_changeset: c4bcbbdbf8aaa07d782a5422eac873c5c7a70909 04:17:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:17:09 INFO - mozversion platform_version: 53.0 04:17:09 INFO - using testdate: 1491823029 04:17:09 INFO - actual date: 1491823029 04:17:09 INFO - starting webserver on 'localhost:56896' 04:17:09 INFO - SUITE-START | Running 2 tests 04:17:09 INFO - TEST-START | dromaeo_css 04:17:09 INFO - operating with platform_type : mac_ 04:17:09 INFO - Initialising browser for dromaeo_css test... 04:17:09 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpBsV3t9/profile http://localhost:56896/getInfo.html) 04:17:12 INFO - PROCESS | 1936 | 2017-04-10 04:17:12.046 plugin-container[1939:21771] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 04:17:12 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:12 INFO - PROCESS | 1936 | 2017-04-10 04:17:12.047 plugin-container[1939:21771] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x901f, name = 'com.apple.CFPasteboardClient' 04:17:12 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:12 INFO - PROCESS | 1936 | 2017-04-10 04:17:12.047 plugin-container[1939:21771] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:17:12 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 04:17:12 INFO - PROCESS | 1936 | colorDepth:24 04:17:12 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 04:17:12 INFO - PROCESS | 1936 | __metrics 04:17:12 ERROR - PROCESS | 1936 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 04:17:12 INFO - TEST-INFO | 1936: exit 0 04:17:12 INFO - Browser initialized. 04:17:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:17:12 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpBsV3t9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:15 INFO - PROCESS | 1940 | 2017-04-10 04:17:15.007 plugin-container[1941:21903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.tsm.portname' 04:17:15 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:15 INFO - PROCESS | 1940 | 2017-04-10 04:17:15.007 plugin-container[1941:21903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 04:17:15 INFO - PROCESS | 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:17:15 INFO - PROCESS | 1940 | 2017-04-10 04:17:15.007 plugin-container[1941:21903] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:17:24 INFO - PROCESS | 1940 | RSS: Main: 172756992 04:17:24 INFO - PROCESS | 1940 | 04:20:27 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56896/tests/dromaeo/cssquery-ext.html) 04:20:28 INFO - PROCESS | 1940 | RSS: Main: 192806912 04:20:28 INFO - PROCESS | 1940 | 04:23:31 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-ext.html (next: http://localhost:56896/tests/dromaeo/cssquery-jquery.html) 04:23:32 INFO - PROCESS | 1940 | RSS: Main: 204349440 04:23:32 INFO - PROCESS | 1940 | 04:26:33 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56896/tests/dromaeo/cssquery-mootools.html) 04:26:34 INFO - PROCESS | 1940 | RSS: Main: 206110720 04:26:34 INFO - PROCESS | 1940 | 04:29:36 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56896/tests/dromaeo/cssquery-prototype.html) 04:29:36 INFO - PROCESS | 1940 | RSS: Main: 209625088 04:29:36 INFO - PROCESS | 1940 | 04:32:39 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56896/tests/dromaeo/cssquery-yui.html) 04:32:40 INFO - PROCESS | 1940 | RSS: Main: 210780160 04:32:40 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:56896/tests/dromaeo/cssquery-yui.html (next: http://localhost:56896/tests/dromaeo/cssquery-dojo.html) 04:35:43 INFO - PROCESS | 1940 | RSS: Main: 210956288 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | __start_tp_report 04:35:43 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 04:35:43 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 04:35:43 INFO - PROCESS | 1940 | |i|pagename|runs| 04:35:43 INFO - PROCESS | 1940 | |0;dojo.html;3028.803127796693;3524.3832329342376;3548.4322508398627;3585.318789430008;3591.7665351752134;5927.49616282616;5931.258592675916;6006.45941865232;6042.184305118808;6070.937585922468;5900.232969713942;5920.970395148018;5924;5928.6739229342365;5949.6727679977585;5216.139337009389;5274.725274725275;5279.1817268323475;5320.9467905320835;5372.677639341632;5351.464853514637;5351.866203344908;5359.410464848883;5367.490088441592;5390.973045134789;5332.760025798829;5340.412554618989;5341.786328546854;5366.597505187126;5387.892242155154;4495.842645507391;5315.574754019669;5371.5165635092835;5381.354237491487;5470.507654311131;5289.920651190235;5366.114591092465;5382.300300960889;5423.267858839045;5436.3476382834315;4640;6021.277446706424;6070.0591408331775;6072.696365181723;6097.481714054266;5659.377468478464;5886.323072846634;6142.468080853482;6204.937950620481;6220.688965551704;6429.871402571969;6448.903266451007;6457.063725759738;6489;6548.345165483413;6087.878242435127;6109.14471973924;6111.8472038198515;6114.991026480626;6126.142340072393;6359.523035772336;6428.5178611604315;6458.418742313214;6497.707603157869;6646.368594983469;6245.344238854946;6256.968715156395;6265.592736472115;6297.149884765498;6312.905306420407;5339.679619222859;6427.871442571122;6451.0645956337;6451.677416129175;6452.612843229421;6179.84550386244;6185.690715464209;6219.222597175353;6243.125962365272;6292.370762923671;5927.436893495111;6186.350433204449;6280.026595877646;6290.968545157245;6318.368163183645;5188.351456067991;5192.636515443958;5206.843794686166;5223.869403264949;5244.921326180111;4511.571400716926;4515.729056256635;4617.58441740245;4639.234526303189;4659.487456379796;6064.36324185963;6102.603330783573;6111.633302001894;6121.849889295292;6182.969085154636;5330;6353.841153971095;6384.457321127757;6385.074164246158;6411.134496842867;6081.179040829431;6091.725872335755;6098.420650038239;6120.112583675342;6120.938790612126;5651.943480565142;5673.120666296731;5684.573656975744;5694.943050569441;5696.401877802854;5498.80754173602;6613.404793568602;6614.536982411185;6625;6647;6145.617236121837;6161.199044124235;6289.874202516015;6383.265924418752;6392.328805475544;5673.971630141823;6491.5131365148745;6577.408033277028;6603.141591593062;6624.436922861611;6316.305206427198;6330.651814150266;6336.049592561153;6340.71466784005;6359.3640635936035;4364.869053928387;6124.683193113502;6126.365000174519;6433.581817181868;6455.644939528371;6153.600015998945;6155.938440615537;6179.567430279837;6270.9372906270355;6284.245890492987;5761.48146666802;6215.471684906653;6258.123862659322;6269.3417191196095;6278.811635650938;5212.765425555935;5815.127730840408;6137.162486020116;6173;6175.814725558241;5804.9709751452665;6153.600015998945;6154.599951003171;6195.287541932736;6215.285242197205;5096.77677357439;5633.352164501135;5992.580519363602;6201.844953876009;6206.162168107247 04:35:43 INFO - PROCESS | 1940 | |1;ext.html;449.29236452587173;478.26108662117025;492.4902725678923;519.5817367019549;530.978760849566;32190;35967;43504.564954350484;43536.78231608838;43547.56452435475;37696.81151594245;44412.3338149928;44508.3323750144;45296.54703452964;45466.09067818642;9411.294152938523;9681.612735490571;9684.176844968186;9715;11436.085113190924;11363.715907102305;11581.826272605918;11675.708107297301;11677.299362038262;11770.352630605314;9543.618255269781;11579.652610421701;11595.710107247301;11622.6513204604;11664.41677916105;11484.310941343547;11509.194356395055;11537.94231028848;11551.191416600841;11574.189806713533;11482.712932176679;11525.423728813526;11534.961853433231;11551;11581.247218930743;38822.41766373507;38894.611053889384;38945.221095578214;39152.41271380932;39226.41160382597;4056.3104272273786;4060.9593904060876;4090.6522945549664;4160.022394737234;4172.395002724618;28351;28479;28597.57103643447;28689;28801.42397152066;3260.85326160321;3395.7453191010773;3439;3452.1369657585606;3460.7058400036026;437902.9999999968;439306.80346598383;440029;441226;441789;59363.40636593665;59690.10464843031;60060.69969650124;60080.699596502614;60190;60744.088838667456;60928;61027;61118.69440652857;61383;16393.83606163923;16597.58506037359;16689;16836.91581542109;16918.830811691725;10713.78572428547;10907.181961352771;12681;12907.031972602092;12909.935450322877;18777.591680623733;18780.71828922567;18798.154083066296;18927.90536047311;18942.43172704821;6963.651817409109;8241.950478323059;8433.95783021081;8556.401051926305;8564.871526927102;893.8257039877161;899.6491368366343;903.9322050846213;933.4725879877848;933.9159475647089;30634.693653063183;31221.37557248842;31365.947711765188;31620.525692114636;32058.35883282368;875.2953872132866;1122.1920217443428;1140.4696815980608;1146.363768108691;1249.681331260541;13794.65513362174;15932.442364517186;16332.75500867488;16501.669966600843;17189.226484808212;624822;639067;645702;645967;647486;55358.723206384515;58609.5347616313;64194.6790266055;64279;64353;1362.5571689200851;1488.1963739580722;1491.2543728135931;1533.8236102848093;1538.630728625122;29872.25319367033;30911.690883091782;31053;31134.53298200574;31481.527777082905;52269;53094;53618.65953351197;53625.19562206492;53628.73185634047;570904;573790;575332;576418;576611;27285.317867053483;27961.580576291777;28032.719672803825;28342.85828570844;28458.57312140278;41514.37728434136;46565.76717116393;48075.75962120167;50489;51724.74137629288;33917.83041084779;34039.48940765841;34132.488012680325;34548;34814;28940.27649308784;33890.15274618056;34175.316493670485;34175.48736769;34474.65525344714;36245;37170.8141459291;37233.44149837809;37559.812200939916;38421.847344579706;21809.890950545145;30445.69554304517;31579.996312960422;32036.35927281488;32465;24231.394215144763;24870;25026.499470010134;25348.493030139663;25452.363690907874 04:35:43 INFO - PROCESS | 1940 | |2;jquery.html;338.8780039185894;400.5413801197629;408.4710300161291;418.8240938805702;435.09717336526717;15066.623334416618;20707.275245366414;21517.46206344842;22052.779472205293;22062.669059964093;22193.334199973986;22331.776682233172;22703.20538781143;23295.3011409658;23527.529449411002;6035;6218.689065546727;6235.095911092889;6262.780802671908;6264.248290205182;5983.341832398432;6112.6638034908065;6140.508759299245;6174.629522228658;6203.131561581382;6096.390360963913;6102.450779429851;6111.113888486167;6145.692715364236;6150.96924515379;4846;6109.511239100883;6227.750889964419;6243.750249989995;6346.11154438379;5018.673786203885;6110.511159107261;6207.6275423474735;6217.813465596039;6219.720112594944;14173.220472873987;14321.78517322241;22536.985835637435;22774.861256937253;25531;2647.7087520372743;2675.505031569166;2676.812623116383;2709.336212627919;2712.471068141713;10180.185585153169;10285.228607854366;10313.174946004301;10767.353958762422;10850.403227822466;1907.9523011924673;1936.1287420660758;1938.1375287996848;1965.1451618545834;1968.3996381103757;123578.76421235852;123818;131756.99999999904;132095;132746;9204.401713888576;12411.441485133191;12448.81326780099;12755.872441275655;12783.61649150527;13201.801972970414;16244.67510649804;16550.006999579822;16601.50195494113;16618.75071873923;7474.887876681854;8931.374803763803;9103.499307538017;9361.391509551857;9375.10936461035;8876.955615221883;9028.413153145024;9028.81942361149;9171.266298696088;9183.494907780136;4953.529414705597;5005.199168133081;5007.499250074963;5024.723640199824;5055.317532133115;2081.8022287882627;2143.646298360753;2146.6779983002675;2161.1031421960024;2165.945851353729;827.9071625454343;870.4864130163234;897.0939351255279;929.9814003719888;947.3321308477507;19354.806451935583;22505.662415063787;22978.885105574365;23070.884645577;23183.956721947216;779.6024027745777;909.2498688581815;926.6247169896203;947.9810403792023;959.769655282741;11288.435578221057;11657.300561966309;11798.46906889192;11836.230645008047;11894.16740828151;648456;701304;706922;707966.8406631838;711162;106738;112409.31386029217;130290.04564931533;154134;155803;1044.0916402729522;1056.915446764257;1094.7482079122005;1097.1332647208776;1097.6048622495896;20273;20441.897790510953;20992.370228893156;21040.053197605837;21178;56868;58760.36115304983;59963.70018150056;59964.70017649884;61160.69419652873;85352.5732371359;86460.13539864772;88030.11969880476;88617;88875.29893749618;8140.75577732669;8618;8732.126787321216;8824.779380515538;8835.602397891982;9500.954894961687;13299.601011969657;13552.457901684216;13718.794218086538;13811.654708631962;11397.145214108808;11524.711882203012;11646.136618141058;11720.70698232551;11987.700307492034;10089.142422894136;10232.283740138118;10574.89425105739;10721.678349649523;11015.228933974866;11704.70738231517;11725.237859539102;11774.058075354122;11779.411029448665;11875.990540804129;7398.630068496661;8521.872171917303;8791.34064945119;8919.73240802777;8941.552922353727;9161.908380916106;11778.293302401884;11788.410579470821;11971.880281197076;11997.100217483548 04:35:43 INFO - PROCESS | 1940 | |3;mootools.html;1467.4350375105594;1494.6935878144982;1499.655079331753;1507.6230942264433;1509.7433436315828;7836.412269079828;8113;8350;8787.121287871218;8823.338249631279;8253.79365515861;8522.701705440311;8534.786630334229;8547.829043419128;8669.566521673923;2413.191580820427;2424.490856920049;2437.890294936732;2443.1571107967725;2448.2655203438985;2047.3448496481133;2432.805375569959;2443.657887895696;2453.901843926241;2459.3768113045394;2418.286605451396;2438.402591365109;2440.9267721968367;2443.8900249488725;2445.645381419693;1976.6738488149438;1979.9208031678716;2381.2856143156982;2393.497365553227;2413.0589070262613;2426.9514609707885;2427.66012758214;2444.498877730061;2450.3016640257442;2506.323292710976;7522.774316770506;8488.387206430758;8562.14378562145;8720.651173953034;8742.825143497159;5268.130758424855;5384.865378365533;5421.864453388657;5429;5435.0488664483955;3604.6936010439144;3635.309291234657;3636.709063274932;3669.541307336583;3687.631236876318;1255.8492980842266;1263.6082814327588;1282.58316047285;1303.1920209470188;1305.9542915997895;9188.632454701803;11085.27945683536;11108.944455277753;11116.277441966327;11173.441327933571;4772.809087636456;4794.304825800239;4836.588889944394;4838.491958344393;4880.731559764247;9606.807863842683;9755.02449755033;9775.706728798148;9797.363171393836;9842.966488518745;5375.489328513785;5381.408045115034;5397.703126328011;5440.836774896759;5517.641353312022;4261.21167583998;4495.842645507391;4897.510248975074;4944.75276236194;4985.401751789808;3512.438009918401;3905.902352441212;4058.289799285172;4077.368007958771;4109.856155034559;1112.4215407987801;1138.4649214868998;1332.4536939854806;1334.7063645997864;1344.5697376839514;904.9728508144766;912.3651848609051;923.344425457919;923.6674797073048;948.3456415073578;5675.233843431084;5889.087191485327;5913.46778789911;5944.256967879015;6170.444659980623;939.8966113727485;980.8577756225308;997.8602995580625;1015.3653966271081;1058.4601853054844;3479.478078288277;3675.9264814703556;3881.107345310594;3888.2223555289056;3942.974826545119;9731.318807683536;10306.381617102848;10361.274710770325;10383.636572719919;10402.843957340647;10379.740506487398;10408.427536485566;10421.218408619232;10434.008769166918;10557.472126393803;8080.636371363185;8137.8372432552205;8239.258466737903;8243.958780206061;8277.006759188936;5610.495055444866;5644.209810626598;5651.4348565143155;5821;5894.793682221101;9404.811903762024;9493.288003399635;9538.90461095408;9561.617535298788;9621.807563848824;9135.954320228357;9490.715278541655;9498.335116541775;9630.518474076409;9630.711078667371;6839.658017099225;7011.824704382431;7194.568325900463;7334.779956601311;7492.9884465598625;8011.399145064027;8054.838903221786;8172.34621230312;8202.384821138558;8219.794505137419;7097.396721278749;7201.819954501179;7214.386777123793;7314.670839812327;7433.553986760811;7012.7545535906;7056.682449289897;7075.716971321295;7160.964195178991;7193.712251509881;6471.126397936218;6473.48212143018;6481.935180648133;6558.049082883051;6567.737290508326;4996.739525414198;5027.974860125676;5075.187969924795;5085.64400491962;5134.7946082156295;3881.437191607258;5048.7475626219275;5140.254663073908;5161.122609156378;5202.97398513005 04:35:43 INFO - PROCESS | 1940 | |4;prototype.html;316.5694655268834;349.3432347187288;350.99824500877486;355.5520044743622;385.6259428354495;5442.755076021579;5552.611317207797;5637.802676906309;5761.251037365142;5790.855228619286;4576.816927322911;5243.352107185866;5287.444818294071;5656.717164141787;5659.915101273484;3852.460655508217;4331.613883557263;4412.271975124108;4438.823711716398;4478.15637070461;3129.233337832223;4065.272259289805;4209.663226941837;4421.391379086338;4475.70907890986;3681.889543313705;4191.685623578229;4213.726107802999;4222.788860556975;4225;3350.7486938479587;4030.6976976726864;4201.138766552849;4221;4229.915401691949;3662.7985460799646;3675.8529658813613;3680.907977300562;3683.521142251517;3721.79530125843;4594.931076033862;5104.668196567249;5405.459454054563;5493.395726470085;5518.862028449281;4433.933490997638;4523.182810307046;4887.413510378742;4900.558949694509;4933.284673722326;4966.900661986776;5802.332731735863;5874.766299077199;5911.438413350725;5924.58527903047;3781.6974642028845;3975.4434379186937;4024.3762216856435;4042.312806822847;4057.3305404608204;5866.20806191163;6167.84580385494;6271.184745983039;6285.9057114143325;6369.426751592379;3805.543334799842;4110.917781644351;4128.080442594208;4149.443958515557;4150.1739234736615;4029.8313489088423;4136.751794892316;4150.107726838683;4153.833846646161;4198.32826747725;4359.411479450297;4810.470848206694;4897.1674815281485;4917;4932.358793356841;4997.9500204997485;5512.944870551323;5987.371326010706;5992.453207386843;6031.255778620717;1151.8214676725122;1269.156011252525;1340.2092765268537;1340.6581321762887;1343.3081962789172;880.0759202836996;886.9445360021554;900.8739076154807;910.1478491270233;913.5614904845711;4759;4809.893724443397;5113.233015047773;5276.551493123127;5295.443978382292;5821.563382746226;5822.883542329129;5831.183634291117;5841.415858414124;5857.025833649654;4086.6322031017353;4821.2045012572535;4844.467108618049;5234.92147617786;5280.122333328345;2782.6939036705944;2853.614762007144;2898.8984186009184;2926.326944802665;3095.9628524444115;5138.794448222104;5809.796657116981;5955.493783028491;6024.909626355608;6036.607620504653;5768.913466298009;5983.072623743326;6194.752209911643;6288.874222515524;6308.526860485482;5316.601254905898;6378.596708724073;6407.2631647359285;6414.390632889868;6419.775307864295;4802.015586804627;5453;5752.712364381848;6158.538109641884;6190.380961903774;5430.67415955044;5615.101583746581;6094.390560944047;6101.237345331833;6101.359357267423;5102.719350435762;5527.861803454785;6379.054388411579;6387.8722425550295;6427.489539958199;3425.3491836550975;4042.575529569353;4056.371262454324;4079.3269110597607;4087.509498860156;5207.947920520746;5256.553192978487;5537.2524709165355;5884.7940322088525;6098.664573448503;5331.067063263991;5401.783928642967;5567.66594004361;5683.772649094155;5854.648721076749;5875.706214689163;5879.764809407576;5887.646741195542;5938.554608404301;5945.570549940149;5318.840434786963;5631.169869792252;5736.913946290892;5776.971115144397;5883.882322353444;5927.95485557482;5929.547260921102;5960.105984102246;5960.569463328683;5992.940070599413;5173.379194496684;5311.831397092634;5488.777259498586;5841.1238314253205;5841.795537156179 04:35:43 INFO - PROCESS | 1940 | |5;yui.html;728.8943103250026;741.4883730226147;777.7394572818106;849.172057244187;856.7386946981172;13550.390232439551;15663.686726265469;16287.185640717946;16645.83354166458;16700.498985030437;12562.560310389139;15028.947973641849;15239.61900952474;15262.08427494351;15263.236838158075;858.6222062292602;881.5107615273537;887.5074333744769;888.320434867327;888.3470649072926;877.2192748453881;894.1907573645829;894.190757364586;895.4537731983485;897.4884315939917;919.3840127114815;939.0281059103829;947.7535840681438;951.3768481644533;953.5756588318167;918.6922381002372;921.8663293822392;929.3215952354751;931.1061381074177;946.6828612414849;688.6040526697119;695.8295217671703;698.2179958446521;702.6486756621689;703.6622421237835;14339.856601434061;15612;16749.497515074567;16968.490945271536;16999.915000425044;14556.344964476519;14603.48887788933;14645.73097269666;14664.926675366662;14740.778888316683;11914.761704765942;11937.582184623496;11999.88000120005;12238.204516706386;12591.937040314831;3729.533808273966;3832.7351973848563;3871.2451072040963;3991.720579559432;4001.339778936472;117800;122512.16231756531;123278;124515;126324.36837815844;11050.557977680963;11273.379964101889;11295.774084518263;11724.648260552198;11924.284542927453;21505;22362.888185558968;23344.64983025256;23550.7644923552;23836.52326953486;8099.838003239902;8180.590970451453;8194.344452443791;8366.623501942428;8475.618597163142;4433.889152771142;4462.955370446319;4543.068670922519;4597.632189424838;4607.009492958963;3679.2089700714473;3764.228333191689;3772.3775577030033;3928.2929072766606;4111.465509483808;745.8657441660553;746.3320328306136;754.3738696881575;760.6462994707488;773.8220209351881;675.4596322941627;691.5332150798191;711.7081996381459;712.9108861392326;734.8824188129873;10439.217058720626;10626.734331641615;10760.3005804624;10792.406417646947;11203.103751700008;694.1288682703276;711.5588335232189;714.9142102947679;737.8302990312151;746.3357611725569;5118.744062796845;5256.9737151314;5263;5263.736813159403;5267.657602255841;108348.91651083388;109772.90227097626;110531;111502;112623;92737.536312318;93356.53321733348;94970;95821;97775.53336699955;83846.16153838506;85387.5730621343;86199;87090;88250.67623985517;8737.606807693543;9971.152452041657;9991.350562213433;10164.237682173718;11077;48362.51637483721;50290;51286.23070654018;51302.486975129774;51798;68047.3195268041;68244.65877670779;69275.8221583659;70928.64535677289;71881.20296992618;10412.895871041495;12819.294938778457;13272.601821945356;13289.13620614657;14616.269186540843;14978.251087445802;15343.290103473775;15652.139132347831;16150.19249037567;16381.935174213639;14149.221792801494;14232.762792275298;14609.269536523343;14617.19605421712;15085.083398578665;13920.234387108807;14135.633162694134;14208.786868197192;14413.927930360282;14515;13710.931445343109;14295.142291462545;14376.496822611158;14391.992560520663;14518.346674399889;9517.762055948657;10515.053645171665;10524.052835244865;10603.204759643211;10787.559220568097;7101.325374089453;10212.744879510436;10836.891631083903;11011.665576814514;11550.59572914944 04:35:43 INFO - PROCESS | 1940 | __end_tp_report 04:35:43 INFO - PROCESS | 1940 | __start_cc_report 04:35:43 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,383 04:35:43 INFO - PROCESS | 1940 | __end_cc_report 04:35:43 INFO - PROCESS | 1940 | __startTimestamp1491824143312__endTimestamp 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | ------- Summary: start ------- 04:35:43 INFO - PROCESS | 1940 | Number of tests: 6 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5852.78 Median:6111.63 stddev:665.83 (10.9%) stddev-sans-first:629.94 04:35:43 INFO - PROCESS | 1940 | Values: 3028.8 3524.4 3548.4 3585.3 3591.8 5927.5 5931.3 6006.5 6042.2 6070.9 5900.2 5921.0 5924.0 5928.7 5949.7 5216.1 5274.7 5279.2 5320.9 5372.7 5351.5 5351.9 5359.4 5367.5 5391.0 5332.8 5340.4 5341.8 5366.6 5387.9 4495.8 5315.6 5371.5 5381.4 5470.5 5289.9 5366.1 5382.3 5423.3 5436.3 4640.0 6021.3 6070.1 6072.7 6097.5 5659.4 5886.3 6142.5 6204.9 6220.7 6429.9 6448.9 6457.1 6489.0 6548.3 6087.9 6109.1 6111.8 6115.0 6126.1 6359.5 6428.5 6458.4 6497.7 6646.4 6245.3 6257.0 6265.6 6297.1 6312.9 5339.7 6427.9 6451.1 6451.7 6452.6 6179.8 6185.7 6219.2 6243.1 6292.4 5927.4 6186.4 6280.0 6291.0 6318.4 5188.4 5192.6 5206.8 5223.9 5244.9 4511.6 4515.7 4617.6 4639.2 4659.5 6064.4 6102.6 6111.6 6121.8 6183.0 5330.0 6353.8 6384.5 6385.1 6411.1 6081.2 6091.7 6098.4 6120.1 6120.9 5651.9 5673.1 5684.6 5694.9 5696.4 5498.8 6613.4 6614.5 6625.0 6647.0 6145.6 6161.2 6289.9 6383.3 6392.3 5674.0 6491.5 6577.4 6603.1 6624.4 6316.3 6330.7 6336.0 6340.7 6359.4 4364.9 6124.7 6126.4 6433.6 6455.6 6153.6 6155.9 6179.6 6270.9 6284.2 5761.5 6215.5 6258.1 6269.3 6278.8 5212.8 5815.1 6137.2 6173.0 6175.8 5805.0 6153.6 6154.6 6195.3 6215.3 5096.8 5633.4 5992.6 6201.8 6206.2 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:68833.47 Median:18963.40 stddev:149060.35 (786.0%) stddev-sans-first:149390.08 04:35:43 INFO - PROCESS | 1940 | Values: 449.3 478.3 492.5 519.6 531.0 32190.0 35967.0 43504.6 43536.8 43547.6 37696.8 44412.3 44508.3 45296.5 45466.1 9411.3 9681.6 9684.2 9715.0 11436.1 11363.7 11581.8 11675.7 11677.3 11770.4 9543.6 11579.7 11595.7 11622.7 11664.4 11484.3 11509.2 11537.9 11551.2 11574.2 11482.7 11525.4 11535.0 11551.0 11581.2 38822.4 38894.6 38945.2 39152.4 39226.4 4056.3 4061.0 4090.7 4160.0 4172.4 28351.0 28479.0 28597.6 28689.0 28801.4 3260.9 3395.7 3439.0 3452.1 3460.7 437903.0 439306.8 440029.0 441226.0 441789.0 59363.4 59690.1 60060.7 60080.7 60190.0 60744.1 60928.0 61027.0 61118.7 61383.0 16393.8 16597.6 16689.0 16836.9 16918.8 10713.8 10907.2 12681.0 12907.0 12909.9 18777.6 18780.7 18798.2 18927.9 18942.4 6963.7 8242.0 8434.0 8556.4 8564.9 893.8 899.6 903.9 933.5 933.9 30634.7 31221.4 31365.9 31620.5 32058.4 875.3 1122.2 1140.5 1146.4 1249.7 13794.7 15932.4 16332.8 16501.7 17189.2 624822.0 639067.0 645702.0 645967.0 647486.0 55358.7 58609.5 64194.7 64279.0 64353.0 1362.6 1488.2 1491.3 1533.8 1538.6 29872.3 30911.7 31053.0 31134.5 31481.5 52269.0 53094.0 53618.7 53625.2 53628.7 570904.0 573790.0 575332.0 576418.0 576611.0 27285.3 27961.6 28032.7 28342.9 28458.6 41514.4 46565.8 48075.8 50489.0 51724.7 33917.8 34039.5 34132.5 34548.0 34814.0 28940.3 33890.2 34175.3 34175.5 34474.7 36245.0 37170.8 37233.4 37559.8 38421.8 21809.9 30445.7 31580.0 32036.4 32465.0 24231.4 24870.0 25026.5 25348.5 25452.4 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:38807.81 Median:24529.26 stddev:115710.50 (471.7%) stddev-sans-first:115999.04 04:35:43 INFO - PROCESS | 1940 | Values: 338.9 400.5 408.5 418.8 435.1 15066.6 20707.3 21517.5 22052.8 22062.7 22193.3 22331.8 22703.2 23295.3 23527.5 6035.0 6218.7 6235.1 6262.8 6264.2 5983.3 6112.7 6140.5 6174.6 6203.1 6096.4 6102.5 6111.1 6145.7 6151.0 4846.0 6109.5 6227.8 6243.8 6346.1 5018.7 6110.5 6207.6 6217.8 6219.7 14173.2 14321.8 22537.0 22774.9 25531.0 2647.7 2675.5 2676.8 2709.3 2712.5 10180.2 10285.2 10313.2 10767.4 10850.4 1908.0 1936.1 1938.1 1965.1 1968.4 123578.8 123818.0 131757.0 132095.0 132746.0 9204.4 12411.4 12448.8 12755.9 12783.6 13201.8 16244.7 16550.0 16601.5 16618.8 7474.9 8931.4 9103.5 9361.4 9375.1 8877.0 9028.4 9028.8 9171.3 9183.5 4953.5 5005.2 5007.5 5024.7 5055.3 2081.8 2143.6 2146.7 2161.1 2165.9 827.9 870.5 897.1 930.0 947.3 19354.8 22505.7 22978.9 23070.9 23184.0 779.6 909.2 926.6 948.0 959.8 11288.4 11657.3 11798.5 11836.2 11894.2 648456.0 701304.0 706922.0 707966.8 711162.0 106738.0 112409.3 130290.0 154134.0 155803.0 1044.1 1056.9 1094.7 1097.1 1097.6 20273.0 20441.9 20992.4 21040.1 21178.0 56868.0 58760.4 59963.7 59964.7 61160.7 85352.6 86460.1 88030.1 88617.0 88875.3 8140.8 8618.0 8732.1 8824.8 8835.6 9501.0 13299.6 13552.5 13718.8 13811.7 11397.1 11524.7 11646.1 11720.7 11987.7 10089.1 10232.3 10574.9 10721.7 11015.2 11704.7 11725.2 11774.1 11779.4 11876.0 7398.6 8521.9 8791.3 8919.7 8941.6 9161.9 11778.3 11788.4 11971.9 11997.1 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5583.28 Median:5321.81 stddev:3025.49 (56.9%) stddev-sans-first:3018.17 04:35:43 INFO - PROCESS | 1940 | Values: 1467.4 1494.7 1499.7 1507.6 1509.7 7836.4 8113.0 8350.0 8787.1 8823.3 8253.8 8522.7 8534.8 8547.8 8669.6 2413.2 2424.5 2437.9 2443.2 2448.3 2047.3 2432.8 2443.7 2453.9 2459.4 2418.3 2438.4 2440.9 2443.9 2445.6 1976.7 1979.9 2381.3 2393.5 2413.1 2427.0 2427.7 2444.5 2450.3 2506.3 7522.8 8488.4 8562.1 8720.7 8742.8 5268.1 5384.9 5421.9 5429.0 5435.0 3604.7 3635.3 3636.7 3669.5 3687.6 1255.8 1263.6 1282.6 1303.2 1306.0 9188.6 11085.3 11108.9 11116.3 11173.4 4772.8 4794.3 4836.6 4838.5 4880.7 9606.8 9755.0 9775.7 9797.4 9843.0 5375.5 5381.4 5397.7 5440.8 5517.6 4261.2 4495.8 4897.5 4944.8 4985.4 3512.4 3905.9 4058.3 4077.4 4109.9 1112.4 1138.5 1332.5 1334.7 1344.6 905.0 912.4 923.3 923.7 948.3 5675.2 5889.1 5913.5 5944.3 6170.4 939.9 980.9 997.9 1015.4 1058.5 3479.5 3675.9 3881.1 3888.2 3943.0 9731.3 10306.4 10361.3 10383.6 10402.8 10379.7 10408.4 10421.2 10434.0 10557.5 8080.6 8137.8 8239.3 8244.0 8277.0 5610.5 5644.2 5651.4 5821.0 5894.8 9404.8 9493.3 9538.9 9561.6 9621.8 9136.0 9490.7 9498.3 9630.5 9630.7 6839.7 7011.8 7194.6 7334.8 7493.0 8011.4 8054.8 8172.3 8202.4 8219.8 7097.4 7201.8 7214.4 7314.7 7433.6 7012.8 7056.7 7075.7 7161.0 7193.7 6471.1 6473.5 6481.9 6558.0 6567.7 4996.7 5028.0 5075.2 5085.6 5134.8 3881.4 5048.7 5140.3 5161.1 5203.0 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4737.35 Median:5283.78 stddev:1474.65 (27.9%) stddev-sans-first:1440.97 04:35:43 INFO - PROCESS | 1940 | Values: 316.6 349.3 351.0 355.6 385.6 5442.8 5552.6 5637.8 5761.3 5790.9 4576.8 5243.4 5287.4 5656.7 5659.9 3852.5 4331.6 4412.3 4438.8 4478.2 3129.2 4065.3 4209.7 4421.4 4475.7 3681.9 4191.7 4213.7 4222.8 4225.0 3350.7 4030.7 4201.1 4221.0 4229.9 3662.8 3675.9 3680.9 3683.5 3721.8 4594.9 5104.7 5405.5 5493.4 5518.9 4433.9 4523.2 4887.4 4900.6 4933.3 4966.9 5802.3 5874.8 5911.4 5924.6 3781.7 3975.4 4024.4 4042.3 4057.3 5866.2 6167.8 6271.2 6285.9 6369.4 3805.5 4110.9 4128.1 4149.4 4150.2 4029.8 4136.8 4150.1 4153.8 4198.3 4359.4 4810.5 4897.2 4917.0 4932.4 4998.0 5512.9 5987.4 5992.5 6031.3 1151.8 1269.2 1340.2 1340.7 1343.3 880.1 886.9 900.9 910.1 913.6 4759.0 4809.9 5113.2 5276.6 5295.4 5821.6 5822.9 5831.2 5841.4 5857.0 4086.6 4821.2 4844.5 5234.9 5280.1 2782.7 2853.6 2898.9 2926.3 3096.0 5138.8 5809.8 5955.5 6024.9 6036.6 5768.9 5983.1 6194.8 6288.9 6308.5 5316.6 6378.6 6407.3 6414.4 6419.8 4802.0 5453.0 5752.7 6158.5 6190.4 5430.7 5615.1 6094.4 6101.2 6101.4 5102.7 5527.9 6379.1 6387.9 6427.5 3425.3 4042.6 4056.4 4079.3 4087.5 5207.9 5256.6 5537.3 5884.8 6098.7 5331.1 5401.8 5567.7 5683.8 5854.6 5875.7 5879.8 5887.6 5938.6 5945.6 5318.8 5631.2 5736.9 5777.0 5883.9 5928.0 5929.5 5960.1 5960.6 5992.9 5173.4 5311.8 5488.8 5841.1 5841.8 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:21903.36 Median:3899.77 stddev:32271.30 (827.5%) stddev-sans-first:32322.66 04:35:43 INFO - PROCESS | 1940 | Values: 728.9 741.5 777.7 849.2 856.7 13550.4 15663.7 16287.2 16645.8 16700.5 12562.6 15028.9 15239.6 15262.1 15263.2 858.6 881.5 887.5 888.3 888.3 877.2 894.2 894.2 895.5 897.5 919.4 939.0 947.8 951.4 953.6 918.7 921.9 929.3 931.1 946.7 688.6 695.8 698.2 702.6 703.7 14339.9 15612.0 16749.5 16968.5 16999.9 14556.3 14603.5 14645.7 14664.9 14740.8 11914.8 11937.6 11999.9 12238.2 12591.9 3729.5 3832.7 3871.2 3991.7 4001.3 117800.0 122512.2 123278.0 124515.0 126324.4 11050.6 11273.4 11295.8 11724.6 11924.3 21505.0 22362.9 23344.6 23550.8 23836.5 8099.8 8180.6 8194.3 8366.6 8475.6 4433.9 4463.0 4543.1 4597.6 4607.0 3679.2 3764.2 3772.4 3928.3 4111.5 745.9 746.3 754.4 760.6 773.8 675.5 691.5 711.7 712.9 734.9 10439.2 10626.7 10760.3 10792.4 11203.1 694.1 711.6 714.9 737.8 746.3 5118.7 5257.0 5263.0 5263.7 5267.7 108348.9 109772.9 110531.0 111502.0 112623.0 92737.5 93356.5 94970.0 95821.0 97775.5 83846.2 85387.6 86199.0 87090.0 88250.7 8737.6 9971.2 9991.4 10164.2 11077.0 48362.5 50290.0 51286.2 51302.5 51798.0 68047.3 68244.7 69275.8 70928.6 71881.2 10412.9 12819.3 13272.6 13289.1 14616.3 14978.3 15343.3 15652.1 16150.2 16381.9 14149.2 14232.8 14609.3 14617.2 15085.1 13920.2 14135.6 14208.8 14413.9 14515.0 13710.9 14295.1 14376.5 14392.0 14518.3 9517.8 10515.1 10524.1 10603.2 10787.6 7101.3 10212.7 10836.9 11011.7 11550.6 04:35:43 INFO - PROCESS | 1940 | -------- Summary: end -------- 04:35:43 INFO - PROCESS | 1940 | 04:35:43 INFO - TEST-INFO | 1940: exit 0 04:35:43 INFO - TEST-OK | dromaeo_css | took 1114538ms 04:35:43 INFO - TEST-START | kraken 04:35:43 INFO - operating with platform_type : mac_ 04:35:43 INFO - Initialising browser for kraken test... 04:35:43 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpZEii0k/profile http://localhost:56896/getInfo.html) 04:35:46 INFO - PROCESS | 1947 | 2017-04-10 04:35:46.331 plugin-container[1949:23983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 04:35:46 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:46 INFO - PROCESS | 1947 | 2017-04-10 04:35:46.331 plugin-container[1949:23983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 04:35:46 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:46 INFO - PROCESS | 1947 | 2017-04-10 04:35:46.332 plugin-container[1949:23983] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:35:46 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 04:35:46 INFO - PROCESS | 1947 | colorDepth:24 04:35:46 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 04:35:46 INFO - PROCESS | 1947 | __metrics 04:35:46 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 04:35:47 INFO - TEST-INFO | 1947: exit 0 04:35:47 INFO - Browser initialized. 04:35:47 INFO - Running cycle 1/1 for kraken test... 04:35:47 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpZEii0k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:49 INFO - PROCESS | 1950 | 2017-04-10 04:35:49.475 plugin-container[1951:24114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 04:35:49 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:49 INFO - PROCESS | 1950 | 2017-04-10 04:35:49.476 plugin-container[1951:24114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 04:35:49 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:49 INFO - PROCESS | 1950 | 2017-04-10 04:35:49.476 plugin-container[1951:24114] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:35:59 INFO - PROCESS | 1950 | RSS: Main: 173297664 04:35:59 INFO - PROCESS | 1950 | 04:36:02 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-beat-detection) 04:36:03 INFO - PROCESS | 1950 | RSS: Main: 186253312 04:36:03 INFO - PROCESS | 1950 | 04:36:05 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-dft) 04:36:06 INFO - PROCESS | 1950 | RSS: Main: 195018752 04:36:06 INFO - PROCESS | 1950 | 04:36:09 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-fft) 04:36:10 INFO - PROCESS | 1950 | RSS: Main: 198041600 04:36:10 INFO - PROCESS | 1950 | 04:36:12 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56896/tests/kraken/driver.html?testName=audio-oscillator) 04:36:12 INFO - PROCESS | 1950 | RSS: Main: 199331840 04:36:12 INFO - PROCESS | 1950 | 04:36:14 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56896/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:36:15 INFO - PROCESS | 1950 | RSS: Main: 199401472 04:36:15 INFO - PROCESS | 1950 | 04:36:18 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56896/tests/kraken/driver.html?testName=imaging-darkroom) 04:36:19 INFO - PROCESS | 1950 | RSS: Main: 203403264 04:36:19 INFO - PROCESS | 1950 | 04:36:22 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56896/tests/kraken/driver.html?testName=imaging-desaturate) 04:36:22 INFO - PROCESS | 1950 | RSS: Main: 202833920 04:36:22 INFO - PROCESS | 1950 | 04:36:25 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56896/tests/kraken/driver.html?testName=json-parse-financial) 04:36:26 INFO - PROCESS | 1950 | RSS: Main: 202883072 04:36:26 INFO - PROCESS | 1950 | 04:36:28 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56896/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:36:28 INFO - PROCESS | 1950 | RSS: Main: 201936896 04:36:28 INFO - PROCESS | 1950 | 04:36:30 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:36:31 INFO - PROCESS | 1950 | RSS: Main: 201904128 04:36:31 INFO - PROCESS | 1950 | 04:36:33 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:36:34 INFO - PROCESS | 1950 | RSS: Main: 201928704 04:36:34 INFO - PROCESS | 1950 | 04:36:36 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:36:37 INFO - PROCESS | 1950 | RSS: Main: 202313728 04:36:37 INFO - PROCESS | 1950 | 04:36:40 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:36:41 INFO - PROCESS | 1950 | RSS: Main: 202362880 04:36:41 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:56896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56896/tests/kraken/driver.html?testName=ai-astar) 04:36:43 INFO - PROCESS | 1950 | RSS: Main: 204111872 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | __start_tp_report 04:36:43 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 04:36:43 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 04:36:43 INFO - PROCESS | 1950 | |i|pagename|runs| 04:36:43 INFO - PROCESS | 1950 | |0;ai-astar;106;119;119;119;118;116;120;119;118;121 04:36:43 INFO - PROCESS | 1950 | |1;audio-beat-detection;141;110;110;143;108;111;111;119;158;118 04:36:43 INFO - PROCESS | 1950 | |2;audio-dft;195;202;196;198;196;193;285;249;198;194 04:36:43 INFO - PROCESS | 1950 | |3;audio-fft;72;60;64;111;85;86;61;60;71;60 04:36:43 INFO - PROCESS | 1950 | |4;audio-oscillator;89;68;94;63;91;101;82;62;97;113 04:36:43 INFO - PROCESS | 1950 | |5;imaging-gaussian-blur;93;87;110;89;91;89;119;96;89;88 04:36:43 INFO - PROCESS | 1950 | |6;imaging-darkroom;97;99;151;93;153;131;97;98;98;98 04:36:43 INFO - PROCESS | 1950 | |7;imaging-desaturate;90;102;119;96;148;85;144;151;119;91 04:36:43 INFO - PROCESS | 1950 | |8;json-parse-financial;59;59;59;59;63;63;63;62;62;62 04:36:43 INFO - PROCESS | 1950 | |9;json-stringify-tinderbox;70;66;75;105;108;102;88;70;70;69 04:36:43 INFO - PROCESS | 1950 | |10;stanford-crypto-aes;80;83;84;83;80;87;84;82;86;81 04:36:43 INFO - PROCESS | 1950 | |11;stanford-crypto-ccm;150;150;149;133;135;132;133;139;175;154 04:36:43 INFO - PROCESS | 1950 | |12;stanford-crypto-pbkdf2;164;170;228;210;168;166;166;187;229;150 04:36:43 INFO - PROCESS | 1950 | |13;stanford-crypto-sha256-iterative;59;86;61;74;90;86;87;85;88;86 04:36:43 INFO - PROCESS | 1950 | __end_tp_report 04:36:43 INFO - PROCESS | 1950 | __start_cc_report 04:36:43 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,944 04:36:43 INFO - PROCESS | 1950 | __end_cc_report 04:36:43 INFO - PROCESS | 1950 | __startTimestamp1491824203439__endTimestamp 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | ------- Summary: start ------- 04:36:43 INFO - PROCESS | 1950 | Number of tests: 14 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#0] ai-astar Cycles:10 Average:117.50 Median:119.00 stddev:4.25 (3.6%) stddev-sans-first:1.39 04:36:43 INFO - PROCESS | 1950 | Values: 106.0 119.0 119.0 119.0 118.0 116.0 120.0 119.0 118.0 121.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:118.50 stddev:17.77 (15.0%) stddev-sans-first:17.60 04:36:43 INFO - PROCESS | 1950 | Values: 141.0 110.0 110.0 143.0 108.0 111.0 111.0 119.0 158.0 118.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#2] audio-dft Cycles:10 Average:210.60 Median:198.00 stddev:31.01 (15.7%) stddev-sans-first:32.38 04:36:43 INFO - PROCESS | 1950 | Values: 195.0 202.0 196.0 198.0 196.0 193.0 285.0 249.0 198.0 194.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#3] audio-fft Cycles:10 Average:73.00 Median:67.50 stddev:16.65 (24.7%) stddev-sans-first:17.65 04:36:43 INFO - PROCESS | 1950 | Values: 72.0 60.0 64.0 111.0 85.0 86.0 61.0 60.0 71.0 60.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:85.50 stddev:17.06 (19.9%) stddev-sans-first:18.06 04:36:43 INFO - PROCESS | 1950 | Values: 89.0 68.0 94.0 63.0 91.0 101.0 82.0 62.0 97.0 113.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:89.00 stddev:10.76 (12.1%) stddev-sans-first:11.39 04:36:43 INFO - PROCESS | 1950 | Values: 93.0 87.0 110.0 89.0 91.0 89.0 119.0 96.0 89.0 88.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#6] imaging-darkroom Cycles:10 Average:111.50 Median:97.50 stddev:23.87 (24.5%) stddev-sans-first:24.74 04:36:43 INFO - PROCESS | 1950 | Values: 97.0 99.0 151.0 93.0 153.0 131.0 97.0 98.0 98.0 98.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#7] imaging-desaturate Cycles:10 Average:114.50 Median:118.00 stddev:25.57 (21.7%) stddev-sans-first:25.54 04:36:43 INFO - PROCESS | 1950 | Values: 90.0 102.0 119.0 96.0 148.0 85.0 144.0 151.0 119.0 91.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:62.00 stddev:1.85 (3.0%) stddev-sans-first:1.80 04:36:43 INFO - PROCESS | 1950 | Values: 59.0 59.0 59.0 59.0 63.0 63.0 63.0 62.0 62.0 62.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#9] json-stringify-tinderbox Cycles:10 Average:82.30 Median:70.00 stddev:16.82 (24.0%) stddev-sans-first:17.24 04:36:43 INFO - PROCESS | 1950 | Values: 70.0 66.0 75.0 105.0 108.0 102.0 88.0 70.0 70.0 69.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:2.36 (2.8%) stddev-sans-first:2.24 04:36:43 INFO - PROCESS | 1950 | Values: 80.0 83.0 84.0 83.0 80.0 87.0 84.0 82.0 86.0 81.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:149.50 stddev:13.50 (9.0%) stddev-sans-first:14.20 04:36:43 INFO - PROCESS | 1950 | Values: 150.0 150.0 149.0 133.0 135.0 132.0 133.0 139.0 175.0 154.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:178.50 stddev:28.48 (16.0%) stddev-sans-first:29.30 04:36:43 INFO - PROCESS | 1950 | Values: 164.0 170.0 228.0 210.0 168.0 166.0 166.0 187.0 229.0 150.0 04:36:43 INFO - PROCESS | 1950 | 04:36:43 INFO - PROCESS | 1950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.20 Median:86.00 stddev:11.47 (13.3%) stddev-sans-first:9.25 04:36:43 INFO - PROCESS | 1950 | Values: 59.0 86.0 61.0 74.0 90.0 86.0 87.0 85.0 88.0 86.0 04:36:43 INFO - PROCESS | 1950 | -------- Summary: end -------- 04:36:43 INFO - PROCESS | 1950 | 04:36:44 INFO - TEST-INFO | 1950: exit 0 04:36:44 INFO - TEST-OK | kraken | took 60373ms 04:36:44 INFO - SUITE-END | took 1174s 04:36:44 INFO - Completed test suite (00:19:35) 04:36:44 INFO - javascript benchmark 04:36:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7533.369512885118, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3028.803127796693, 3524.3832329342376, 3548.4322508398627, 3585.318789430008, 3591.7665351752134, 5927.49616282616, 5931.258592675916, 6006.45941865232, 6042.184305118808, 6070.937585922468, 5900.232969713942, 5920.970395148018, 5924.0, 5928.6739229342365, 5949.6727679977585, 5216.139337009389, 5274.725274725275, 5279.1817268323475, 5320.9467905320835, 5372.677639341632, 5351.464853514637, 5351.866203344908, 5359.410464848883, 5367.490088441592, 5390.973045134789, 5332.760025798829, 5340.412554618989, 5341.786328546854, 5366.597505187126, 5387.892242155154, 4495.842645507391, 5315.574754019669, 5371.5165635092835, 5381.354237491487, 5470.507654311131, 5289.920651190235, 5366.114591092465, 5382.300300960889, 5423.267858839045, 5436.3476382834315, 4640.0, 6021.277446706424, 6070.0591408331775, 6072.696365181723, 6097.481714054266, 5659.377468478464, 5886.323072846634, 6142.468080853482, 6204.937950620481, 6220.688965551704, 6429.871402571969, 6448.903266451007, 6457.063725759738, 6489.0, 6548.345165483413, 6087.878242435127, 6109.14471973924, 6111.8472038198515, 6114.991026480626, 6126.142340072393, 6359.523035772336, 6428.5178611604315, 6458.418742313214, 6497.707603157869, 6646.368594983469, 6245.344238854946, 6256.968715156395, 6265.592736472115, 6297.149884765498, 6312.905306420407, 5339.679619222859, 6427.871442571122, 6451.0645956337, 6451.677416129175, 6452.612843229421, 6179.84550386244, 6185.690715464209, 6219.222597175353, 6243.125962365272, 6292.370762923671, 5927.436893495111, 6186.350433204449, 6280.026595877646, 6290.968545157245, 6318.368163183645, 5188.351456067991, 5192.636515443958, 5206.843794686166, 5223.869403264949, 5244.921326180111, 4511.571400716926, 4515.729056256635, 4617.58441740245, 4639.234526303189, 4659.487456379796, 6064.36324185963, 6102.603330783573, 6111.633302001894, 6121.849889295292, 6182.969085154636, 5330.0, 6353.841153971095, 6384.457321127757, 6385.074164246158, 6411.134496842867, 6081.179040829431, 6091.725872335755, 6098.420650038239, 6120.112583675342, 6120.938790612126, 5651.943480565142, 5673.120666296731, 5684.573656975744, 5694.943050569441, 5696.401877802854, 5498.80754173602, 6613.404793568602, 6614.536982411185, 6625.0, 6647.0, 6145.617236121837, 6161.199044124235, 6289.874202516015, 6383.265924418752, 6392.328805475544, 5673.971630141823, 6491.5131365148745, 6577.408033277028, 6603.141591593062, 6624.436922861611, 6316.305206427198, 6330.651814150266, 6336.049592561153, 6340.71466784005, 6359.3640635936035, 4364.869053928387, 6124.683193113502, 6126.365000174519, 6433.581817181868, 6455.644939528371, 6153.600015998945, 6155.938440615537, 6179.567430279837, 6270.9372906270355, 6284.245890492987, 5761.48146666802, 6215.471684906653, 6258.123862659322, 6269.3417191196095, 6278.811635650938, 5212.765425555935, 5815.127730840408, 6137.162486020116, 6173.0, 6175.814725558241, 5804.9709751452665, 6153.600015998945, 6154.599951003171, 6195.287541932736, 6215.285242197205, 5096.77677357439, 5633.352164501135, 5992.580519363602, 6201.844953876009, 6206.162168107247], "value": 5814.642900402697, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.29236452587173, 478.26108662117025, 492.4902725678923, 519.5817367019549, 530.978760849566, 32190.0, 35967.0, 43504.564954350484, 43536.78231608838, 43547.56452435475, 37696.81151594245, 44412.3338149928, 44508.3323750144, 45296.54703452964, 45466.09067818642, 9411.294152938523, 9681.612735490571, 9684.176844968186, 9715.0, 11436.085113190924, 11363.715907102305, 11581.826272605918, 11675.708107297301, 11677.299362038262, 11770.352630605314, 9543.618255269781, 11579.652610421701, 11595.710107247301, 11622.6513204604, 11664.41677916105, 11484.310941343547, 11509.194356395055, 11537.94231028848, 11551.191416600841, 11574.189806713533, 11482.712932176679, 11525.423728813526, 11534.961853433231, 11551.0, 11581.247218930743, 38822.41766373507, 38894.611053889384, 38945.221095578214, 39152.41271380932, 39226.41160382597, 4056.3104272273786, 4060.9593904060876, 4090.6522945549664, 4160.022394737234, 4172.395002724618, 28351.0, 28479.0, 28597.57103643447, 28689.0, 28801.42397152066, 3260.85326160321, 3395.7453191010773, 3439.0, 3452.1369657585606, 3460.7058400036026, 437902.9999999968, 439306.80346598383, 440029.0, 441226.0, 441789.0, 59363.40636593665, 59690.10464843031, 60060.69969650124, 60080.699596502614, 60190.0, 60744.088838667456, 60928.0, 61027.0, 61118.69440652857, 61383.0, 16393.83606163923, 16597.58506037359, 16689.0, 16836.91581542109, 16918.830811691725, 10713.78572428547, 10907.181961352771, 12681.0, 12907.031972602092, 12909.935450322877, 18777.591680623733, 18780.71828922567, 18798.154083066296, 18927.90536047311, 18942.43172704821, 6963.651817409109, 8241.950478323059, 8433.95783021081, 8556.401051926305, 8564.871526927102, 893.8257039877161, 899.6491368366343, 903.9322050846213, 933.4725879877848, 933.9159475647089, 30634.693653063183, 31221.37557248842, 31365.947711765188, 31620.525692114636, 32058.35883282368, 875.2953872132866, 1122.1920217443428, 1140.4696815980608, 1146.363768108691, 1249.681331260541, 13794.65513362174, 15932.442364517186, 16332.75500867488, 16501.669966600843, 17189.226484808212, 624822.0, 639067.0, 645702.0, 645967.0, 647486.0, 55358.723206384515, 58609.5347616313, 64194.6790266055, 64279.0, 64353.0, 1362.5571689200851, 1488.1963739580722, 1491.2543728135931, 1533.8236102848093, 1538.630728625122, 29872.25319367033, 30911.690883091782, 31053.0, 31134.53298200574, 31481.527777082905, 52269.0, 53094.0, 53618.65953351197, 53625.19562206492, 53628.73185634047, 570904.0, 573790.0, 575332.0, 576418.0, 576611.0, 27285.317867053483, 27961.580576291777, 28032.719672803825, 28342.85828570844, 28458.57312140278, 41514.37728434136, 46565.76717116393, 48075.75962120167, 50489.0, 51724.74137629288, 33917.83041084779, 34039.48940765841, 34132.488012680325, 34548.0, 34814.0, 28940.27649308784, 33890.15274618056, 34175.316493670485, 34175.48736769, 34474.65525344714, 36245.0, 37170.8141459291, 37233.44149837809, 37559.812200939916, 38421.847344579706, 21809.890950545145, 30445.69554304517, 31579.996312960422, 32036.35927281488, 32465.0, 24231.394215144763, 24870.0, 25026.499470010134, 25348.493030139663, 25452.363690907874], "value": 20374.306029346328, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.8780039185894, 400.5413801197629, 408.4710300161291, 418.8240938805702, 435.09717336526717, 15066.623334416618, 20707.275245366414, 21517.46206344842, 22052.779472205293, 22062.669059964093, 22193.334199973986, 22331.776682233172, 22703.20538781143, 23295.3011409658, 23527.529449411002, 6035.0, 6218.689065546727, 6235.095911092889, 6262.780802671908, 6264.248290205182, 5983.341832398432, 6112.6638034908065, 6140.508759299245, 6174.629522228658, 6203.131561581382, 6096.390360963913, 6102.450779429851, 6111.113888486167, 6145.692715364236, 6150.96924515379, 4846.0, 6109.511239100883, 6227.750889964419, 6243.750249989995, 6346.11154438379, 5018.673786203885, 6110.511159107261, 6207.6275423474735, 6217.813465596039, 6219.720112594944, 14173.220472873987, 14321.78517322241, 22536.985835637435, 22774.861256937253, 25531.0, 2647.7087520372743, 2675.505031569166, 2676.812623116383, 2709.336212627919, 2712.471068141713, 10180.185585153169, 10285.228607854366, 10313.174946004301, 10767.353958762422, 10850.403227822466, 1907.9523011924673, 1936.1287420660758, 1938.1375287996848, 1965.1451618545834, 1968.3996381103757, 123578.76421235852, 123818.0, 131756.99999999904, 132095.0, 132746.0, 9204.401713888576, 12411.441485133191, 12448.81326780099, 12755.872441275655, 12783.61649150527, 13201.801972970414, 16244.67510649804, 16550.006999579822, 16601.50195494113, 16618.75071873923, 7474.887876681854, 8931.374803763803, 9103.499307538017, 9361.391509551857, 9375.10936461035, 8876.955615221883, 9028.413153145024, 9028.81942361149, 9171.266298696088, 9183.494907780136, 4953.529414705597, 5005.199168133081, 5007.499250074963, 5024.723640199824, 5055.317532133115, 2081.8022287882627, 2143.646298360753, 2146.6779983002675, 2161.1031421960024, 2165.945851353729, 827.9071625454343, 870.4864130163234, 897.0939351255279, 929.9814003719888, 947.3321308477507, 19354.806451935583, 22505.662415063787, 22978.885105574365, 23070.884645577, 23183.956721947216, 779.6024027745777, 909.2498688581815, 926.6247169896203, 947.9810403792023, 959.769655282741, 11288.435578221057, 11657.300561966309, 11798.46906889192, 11836.230645008047, 11894.16740828151, 648456.0, 701304.0, 706922.0, 707966.8406631838, 711162.0, 106738.0, 112409.31386029217, 130290.04564931533, 154134.0, 155803.0, 1044.0916402729522, 1056.915446764257, 1094.7482079122005, 1097.1332647208776, 1097.6048622495896, 20273.0, 20441.897790510953, 20992.370228893156, 21040.053197605837, 21178.0, 56868.0, 58760.36115304983, 59963.70018150056, 59964.70017649884, 61160.69419652873, 85352.5732371359, 86460.13539864772, 88030.11969880476, 88617.0, 88875.29893749618, 8140.75577732669, 8618.0, 8732.126787321216, 8824.779380515538, 8835.602397891982, 9500.954894961687, 13299.601011969657, 13552.457901684216, 13718.794218086538, 13811.654708631962, 11397.145214108808, 11524.711882203012, 11646.136618141058, 11720.70698232551, 11987.700307492034, 10089.142422894136, 10232.283740138118, 10574.89425105739, 10721.678349649523, 11015.228933974866, 11704.70738231517, 11725.237859539102, 11774.058075354122, 11779.411029448665, 11875.990540804129, 7398.630068496661, 8521.872171917303, 8791.34064945119, 8919.73240802777, 8941.552922353727, 9161.908380916106, 11778.293302401884, 11788.410579470821, 11971.880281197076, 11997.100217483548], "value": 10051.244017287105, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.4350375105594, 1494.6935878144982, 1499.655079331753, 1507.6230942264433, 1509.7433436315828, 7836.412269079828, 8113.0, 8350.0, 8787.121287871218, 8823.338249631279, 8253.79365515861, 8522.701705440311, 8534.786630334229, 8547.829043419128, 8669.566521673923, 2413.191580820427, 2424.490856920049, 2437.890294936732, 2443.1571107967725, 2448.2655203438985, 2047.3448496481133, 2432.805375569959, 2443.657887895696, 2453.901843926241, 2459.3768113045394, 2418.286605451396, 2438.402591365109, 2440.9267721968367, 2443.8900249488725, 2445.645381419693, 1976.6738488149438, 1979.9208031678716, 2381.2856143156982, 2393.497365553227, 2413.0589070262613, 2426.9514609707885, 2427.66012758214, 2444.498877730061, 2450.3016640257442, 2506.323292710976, 7522.774316770506, 8488.387206430758, 8562.14378562145, 8720.651173953034, 8742.825143497159, 5268.130758424855, 5384.865378365533, 5421.864453388657, 5429.0, 5435.0488664483955, 3604.6936010439144, 3635.309291234657, 3636.709063274932, 3669.541307336583, 3687.631236876318, 1255.8492980842266, 1263.6082814327588, 1282.58316047285, 1303.1920209470188, 1305.9542915997895, 9188.632454701803, 11085.27945683536, 11108.944455277753, 11116.277441966327, 11173.441327933571, 4772.809087636456, 4794.304825800239, 4836.588889944394, 4838.491958344393, 4880.731559764247, 9606.807863842683, 9755.02449755033, 9775.706728798148, 9797.363171393836, 9842.966488518745, 5375.489328513785, 5381.408045115034, 5397.703126328011, 5440.836774896759, 5517.641353312022, 4261.21167583998, 4495.842645507391, 4897.510248975074, 4944.75276236194, 4985.401751789808, 3512.438009918401, 3905.902352441212, 4058.289799285172, 4077.368007958771, 4109.856155034559, 1112.4215407987801, 1138.4649214868998, 1332.4536939854806, 1334.7063645997864, 1344.5697376839514, 904.9728508144766, 912.3651848609051, 923.344425457919, 923.6674797073048, 948.3456415073578, 5675.233843431084, 5889.087191485327, 5913.46778789911, 5944.256967879015, 6170.444659980623, 939.8966113727485, 980.8577756225308, 997.8602995580625, 1015.3653966271081, 1058.4601853054844, 3479.478078288277, 3675.9264814703556, 3881.107345310594, 3888.2223555289056, 3942.974826545119, 9731.318807683536, 10306.381617102848, 10361.274710770325, 10383.636572719919, 10402.843957340647, 10379.740506487398, 10408.427536485566, 10421.218408619232, 10434.008769166918, 10557.472126393803, 8080.636371363185, 8137.8372432552205, 8239.258466737903, 8243.958780206061, 8277.006759188936, 5610.495055444866, 5644.209810626598, 5651.4348565143155, 5821.0, 5894.793682221101, 9404.811903762024, 9493.288003399635, 9538.90461095408, 9561.617535298788, 9621.807563848824, 9135.954320228357, 9490.715278541655, 9498.335116541775, 9630.518474076409, 9630.711078667371, 6839.658017099225, 7011.824704382431, 7194.568325900463, 7334.779956601311, 7492.9884465598625, 8011.399145064027, 8054.838903221786, 8172.34621230312, 8202.384821138558, 8219.794505137419, 7097.396721278749, 7201.819954501179, 7214.386777123793, 7314.670839812327, 7433.553986760811, 7012.7545535906, 7056.682449289897, 7075.716971321295, 7160.964195178991, 7193.712251509881, 6471.126397936218, 6473.48212143018, 6481.935180648133, 6558.049082883051, 6567.737290508326, 4996.739525414198, 5027.974860125676, 5075.187969924795, 5085.64400491962, 5134.7946082156295, 3881.437191607258, 5048.7475626219275, 5140.254663073908, 5161.122609156378, 5202.97398513005], "value": 4552.6880634259605, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.5694655268834, 349.3432347187288, 350.99824500877486, 355.5520044743622, 385.6259428354495, 5442.755076021579, 5552.611317207797, 5637.802676906309, 5761.251037365142, 5790.855228619286, 4576.816927322911, 5243.352107185866, 5287.444818294071, 5656.717164141787, 5659.915101273484, 3852.460655508217, 4331.613883557263, 4412.271975124108, 4438.823711716398, 4478.15637070461, 3129.233337832223, 4065.272259289805, 4209.663226941837, 4421.391379086338, 4475.70907890986, 3681.889543313705, 4191.685623578229, 4213.726107802999, 4222.788860556975, 4225.0, 3350.7486938479587, 4030.6976976726864, 4201.138766552849, 4221.0, 4229.915401691949, 3662.7985460799646, 3675.8529658813613, 3680.907977300562, 3683.521142251517, 3721.79530125843, 4594.931076033862, 5104.668196567249, 5405.459454054563, 5493.395726470085, 5518.862028449281, 4433.933490997638, 4523.182810307046, 4887.413510378742, 4900.558949694509, 4933.284673722326, 4966.900661986776, 5802.332731735863, 5874.766299077199, 5911.438413350725, 5924.58527903047, 3781.6974642028845, 3975.4434379186937, 4024.3762216856435, 4042.312806822847, 4057.3305404608204, 5866.20806191163, 6167.84580385494, 6271.184745983039, 6285.9057114143325, 6369.426751592379, 3805.543334799842, 4110.917781644351, 4128.080442594208, 4149.443958515557, 4150.1739234736615, 4029.8313489088423, 4136.751794892316, 4150.107726838683, 4153.833846646161, 4198.32826747725, 4359.411479450297, 4810.470848206694, 4897.1674815281485, 4917.0, 4932.358793356841, 4997.9500204997485, 5512.944870551323, 5987.371326010706, 5992.453207386843, 6031.255778620717, 1151.8214676725122, 1269.156011252525, 1340.2092765268537, 1340.6581321762887, 1343.3081962789172, 880.0759202836996, 886.9445360021554, 900.8739076154807, 910.1478491270233, 913.5614904845711, 4759.0, 4809.893724443397, 5113.233015047773, 5276.551493123127, 5295.443978382292, 5821.563382746226, 5822.883542329129, 5831.183634291117, 5841.415858414124, 5857.025833649654, 4086.6322031017353, 4821.2045012572535, 4844.467108618049, 5234.92147617786, 5280.122333328345, 2782.6939036705944, 2853.614762007144, 2898.8984186009184, 2926.326944802665, 3095.9628524444115, 5138.794448222104, 5809.796657116981, 5955.493783028491, 6024.909626355608, 6036.607620504653, 5768.913466298009, 5983.072623743326, 6194.752209911643, 6288.874222515524, 6308.526860485482, 5316.601254905898, 6378.596708724073, 6407.2631647359285, 6414.390632889868, 6419.775307864295, 4802.015586804627, 5453.0, 5752.712364381848, 6158.538109641884, 6190.380961903774, 5430.67415955044, 5615.101583746581, 6094.390560944047, 6101.237345331833, 6101.359357267423, 5102.719350435762, 5527.861803454785, 6379.054388411579, 6387.8722425550295, 6427.489539958199, 3425.3491836550975, 4042.575529569353, 4056.371262454324, 4079.3269110597607, 4087.509498860156, 5207.947920520746, 5256.553192978487, 5537.2524709165355, 5884.7940322088525, 6098.664573448503, 5331.067063263991, 5401.783928642967, 5567.66594004361, 5683.772649094155, 5854.648721076749, 5875.706214689163, 5879.764809407576, 5887.646741195542, 5938.554608404301, 5945.570549940149, 5318.840434786963, 5631.169869792252, 5736.913946290892, 5776.971115144397, 5883.882322353444, 5927.95485557482, 5929.547260921102, 5960.105984102246, 5960.569463328683, 5992.940070599413, 5173.379194496684, 5311.831397092634, 5488.777259498586, 5841.1238314253205, 5841.795537156179], "value": 4271.619088959045, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.8943103250026, 741.4883730226147, 777.7394572818106, 849.172057244187, 856.7386946981172, 13550.390232439551, 15663.686726265469, 16287.185640717946, 16645.83354166458, 16700.498985030437, 12562.560310389139, 15028.947973641849, 15239.61900952474, 15262.08427494351, 15263.236838158075, 858.6222062292602, 881.5107615273537, 887.5074333744769, 888.320434867327, 888.3470649072926, 877.2192748453881, 894.1907573645829, 894.190757364586, 895.4537731983485, 897.4884315939917, 919.3840127114815, 939.0281059103829, 947.7535840681438, 951.3768481644533, 953.5756588318167, 918.6922381002372, 921.8663293822392, 929.3215952354751, 931.1061381074177, 946.6828612414849, 688.6040526697119, 695.8295217671703, 698.2179958446521, 702.6486756621689, 703.6622421237835, 14339.856601434061, 15612.0, 16749.497515074567, 16968.490945271536, 16999.915000425044, 14556.344964476519, 14603.48887788933, 14645.73097269666, 14664.926675366662, 14740.778888316683, 11914.761704765942, 11937.582184623496, 11999.88000120005, 12238.204516706386, 12591.937040314831, 3729.533808273966, 3832.7351973848563, 3871.2451072040963, 3991.720579559432, 4001.339778936472, 117800.0, 122512.16231756531, 123278.0, 124515.0, 126324.36837815844, 11050.557977680963, 11273.379964101889, 11295.774084518263, 11724.648260552198, 11924.284542927453, 21505.0, 22362.888185558968, 23344.64983025256, 23550.7644923552, 23836.52326953486, 8099.838003239902, 8180.590970451453, 8194.344452443791, 8366.623501942428, 8475.618597163142, 4433.889152771142, 4462.955370446319, 4543.068670922519, 4597.632189424838, 4607.009492958963, 3679.2089700714473, 3764.228333191689, 3772.3775577030033, 3928.2929072766606, 4111.465509483808, 745.8657441660553, 746.3320328306136, 754.3738696881575, 760.6462994707488, 773.8220209351881, 675.4596322941627, 691.5332150798191, 711.7081996381459, 712.9108861392326, 734.8824188129873, 10439.217058720626, 10626.734331641615, 10760.3005804624, 10792.406417646947, 11203.103751700008, 694.1288682703276, 711.5588335232189, 714.9142102947679, 737.8302990312151, 746.3357611725569, 5118.744062796845, 5256.9737151314, 5263.0, 5263.736813159403, 5267.657602255841, 108348.91651083388, 109772.90227097626, 110531.0, 111502.0, 112623.0, 92737.536312318, 93356.53321733348, 94970.0, 95821.0, 97775.53336699955, 83846.16153838506, 85387.5730621343, 86199.0, 87090.0, 88250.67623985517, 8737.606807693543, 9971.152452041657, 9991.350562213433, 10164.237682173718, 11077.0, 48362.51637483721, 50290.0, 51286.23070654018, 51302.486975129774, 51798.0, 68047.3195268041, 68244.65877670779, 69275.8221583659, 70928.64535677289, 71881.20296992618, 10412.895871041495, 12819.294938778457, 13272.601821945356, 13289.13620614657, 14616.269186540843, 14978.251087445802, 15343.290103473775, 15652.139132347831, 16150.19249037567, 16381.935174213639, 14149.221792801494, 14232.762792275298, 14609.269536523343, 14617.19605421712, 15085.083398578665, 13920.234387108807, 14135.633162694134, 14208.786868197192, 14413.927930360282, 14515.0, 13710.931445343109, 14295.142291462545, 14376.496822611158, 14391.992560520663, 14518.346674399889, 9517.762055948657, 10515.053645171665, 10524.052835244865, 10603.204759643211, 10787.559220568097, 7101.325374089453, 10212.744879510436, 10836.891631083903, 11011.665576814514, 11550.59572914944], "value": 7892.309761749314, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 119.0, 119.0, 118.0, 116.0, 120.0, 119.0, 118.0, 121.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 110.0, 143.0, 108.0, 111.0, 111.0, 119.0, 158.0, 118.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 202.0, 196.0, 198.0, 196.0, 193.0, 285.0, 249.0, 198.0, 194.0], "value": 210.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 60.0, 64.0, 111.0, 85.0, 86.0, 61.0, 60.0, 71.0, 60.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 94.0, 63.0, 91.0, 101.0, 82.0, 62.0, 97.0, 113.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 110.0, 89.0, 91.0, 89.0, 119.0, 96.0, 89.0, 88.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 151.0, 93.0, 153.0, 131.0, 97.0, 98.0, 98.0, 98.0], "value": 111.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 119.0, 96.0, 148.0, 85.0, 144.0, 151.0, 119.0, 91.0], "value": 114.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 63.0, 63.0, 63.0, 62.0, 62.0, 62.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 75.0, 105.0, 108.0, 102.0, 88.0, 70.0, 70.0, 69.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 84.0, 83.0, 80.0, 87.0, 84.0, 82.0, 86.0, 81.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 133.0, 135.0, 132.0, 133.0, 139.0, 175.0, 154.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 170.0, 228.0, 210.0, 168.0, 166.0, 166.0, 187.0, 229.0, 150.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 86.0, 61.0, 74.0, 90.0, 86.0, 87.0, 85.0, 88.0, 86.0], "value": 80.2, "unit": "score"}]}]} 04:36:44 INFO - Return code: 0 04:36:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:36:44 ERROR - # TBPL SUCCESS # 04:36:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:36:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:36:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:36:44 INFO - 2017-04-10 04:36:44.754 screenresolution[1955:24493] starting screenresolution argv=screenresolution get 04:36:44 INFO - 2017-04-10 04:36:44.757 screenresolution[1955:24493] Display 0: 1600x1200x32@60 04:36:44 INFO - 2017-04-10 04:36:44.763 screenresolution[1956:24496] starting screenresolution argv=screenresolution list 04:36:44 INFO - Available Modes on Display 0 04:36:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:36:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:36:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:36:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:36:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:36:44 INFO - Intel Iris: 04:36:44 INFO - Chipset Model: Intel Iris 04:36:44 INFO - Type: GPU 04:36:44 INFO - Bus: Built-In 04:36:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:36:44 INFO - Vendor: Intel (0x8086) 04:36:44 INFO - Device ID: 0x0a2e 04:36:44 INFO - Revision ID: 0x0009 04:36:44 INFO - Displays: 04:36:44 INFO - Display: 04:36:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:36:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:36:44 INFO - Display Serial Number: 1600x1200 60 04:36:44 INFO - Main Display: Yes 04:36:44 INFO - Mirror: Off 04:36:44 INFO - Online: Yes 04:36:44 INFO - Rotation: Supported 04:36:44 INFO - Return code: 0 04:36:44 INFO - Running post-action listener: _resource_record_post_action 04:36:44 INFO - [mozharness: 2017-04-10 11:36:44.996616Z] Finished run-tests step (success) 04:36:44 INFO - Running post-run listener: _resource_record_post_run 04:36:45 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 1601536; Write bytes: 426802176; Read time: 16; Write time: 1466 04:36:45 INFO - TinderboxPrint: CPU usage
54.3% 04:36:45 INFO - TinderboxPrint: I/O read bytes / time
1,601,536 / 16 04:36:45 INFO - TinderboxPrint: I/O write bytes / time
426,802,176 / 1,466 04:36:45 INFO - TinderboxPrint: CPU idle
2,179.9 (45.7%) 04:36:45 INFO - TinderboxPrint: CPU system
962.3 (20.2%) 04:36:45 INFO - TinderboxPrint: CPU user
1,623.4 (34.1%) 04:36:45 INFO - TinderboxPrint: Swap in / out
123,748,352 / 0 04:36:45 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 114948608; Write bytes: 112828416; Read time: 10693; Write time: 257 04:36:45 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1601536; Write bytes: 303217664; Read time: 16; Write time: 1171 04:36:45 INFO - Running post-run listener: _upload_blobber_files 04:36:45 INFO - Blob upload gear active. 04:36:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:36:45 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 04:36:45 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:36:45 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:36:45 INFO - (blobuploader) - INFO - Open directory for files ... 04:36:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:36:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:36:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:46 INFO - (blobuploader) - INFO - Done attempting. 04:36:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:36:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:36:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:48 INFO - (blobuploader) - INFO - Done attempting. 04:36:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:36:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:36:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:49 INFO - (blobuploader) - INFO - Done attempting. 04:36:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:36:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:51 INFO - (blobuploader) - INFO - Done attempting. 04:36:51 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:51 INFO - Return code: 0 04:36:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/53f31605f28efb074adb5fa0f3685450028d928dbeb09bb2377986158a8a16cfd90520fed8316cf3578eff01deefaa77890a5ddbe76fc2d0a8e1866e70e9b3bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2aa87efa0a47ba00a23f4a069d6577bae364b9f5c66e326f4dee3245917a94f329c97a37cb3e35458e6f4aad390d29697cf200e05027fecf69fe667f78f180", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f22ff3fcb5935b107c38100af0de2b231c34dcf21fe4332ae4ee944e6be4cd8f6af5803c63910688628828326ffedbc87b81a49753f0f6293b9c03e11a3cb3a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1569e4ca7ddab3d5d203dbf46fb8bd1bc75516bf8bf9037577197388c45af0356da438afdff5f6791d3d0f26564ef68c747323ea07e618012ba1374cb1fe80b8"} 04:36:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:51 INFO - Contents: 04:36:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/53f31605f28efb074adb5fa0f3685450028d928dbeb09bb2377986158a8a16cfd90520fed8316cf3578eff01deefaa77890a5ddbe76fc2d0a8e1866e70e9b3bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2aa87efa0a47ba00a23f4a069d6577bae364b9f5c66e326f4dee3245917a94f329c97a37cb3e35458e6f4aad390d29697cf200e05027fecf69fe667f78f180", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f22ff3fcb5935b107c38100af0de2b231c34dcf21fe4332ae4ee944e6be4cd8f6af5803c63910688628828326ffedbc87b81a49753f0f6293b9c03e11a3cb3a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1569e4ca7ddab3d5d203dbf46fb8bd1bc75516bf8bf9037577197388c45af0356da438afdff5f6791d3d0f26564ef68c747323ea07e618012ba1374cb1fe80b8"} 04:36:51 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:51 INFO - Copying logs to upload dir... 04:36:51 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.190534 ========= master_lag: -0.33 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-10 04:36:50.781162) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 04:36:50.785959) ========= 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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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/Mozilla-Beta/sha512/53f31605f28efb074adb5fa0f3685450028d928dbeb09bb2377986158a8a16cfd90520fed8316cf3578eff01deefaa77890a5ddbe76fc2d0a8e1866e70e9b3bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2aa87efa0a47ba00a23f4a069d6577bae364b9f5c66e326f4dee3245917a94f329c97a37cb3e35458e6f4aad390d29697cf200e05027fecf69fe667f78f180", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f22ff3fcb5935b107c38100af0de2b231c34dcf21fe4332ae4ee944e6be4cd8f6af5803c63910688628828326ffedbc87b81a49753f0f6293b9c03e11a3cb3a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1569e4ca7ddab3d5d203dbf46fb8bd1bc75516bf8bf9037577197388c45af0356da438afdff5f6791d3d0f26564ef68c747323ea07e618012ba1374cb1fe80b8"} build_url:https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010628 build_url: 'https://queue.taskcluster.net/v1/task/I9laiLiGRN6D27L0UxPECA/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/53f31605f28efb074adb5fa0f3685450028d928dbeb09bb2377986158a8a16cfd90520fed8316cf3578eff01deefaa77890a5ddbe76fc2d0a8e1866e70e9b3bb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2aa87efa0a47ba00a23f4a069d6577bae364b9f5c66e326f4dee3245917a94f329c97a37cb3e35458e6f4aad390d29697cf200e05027fecf69fe667f78f180", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f22ff3fcb5935b107c38100af0de2b231c34dcf21fe4332ae4ee944e6be4cd8f6af5803c63910688628828326ffedbc87b81a49753f0f6293b9c03e11a3cb3a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1569e4ca7ddab3d5d203dbf46fb8bd1bc75516bf8bf9037577197388c45af0356da438afdff5f6791d3d0f26564ef68c747323ea07e618012ba1374cb1fe80b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 04:36:50.819725) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 04:36:50.820089) ========= 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.wbYlXSDUXJ/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.bx8NKN4PTL/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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.005247 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 04:36:50.869279) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 04:36:50.869593) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 04:36:50.869899) ========= ========= Total master_lag: -0.18 =========